May 13 23:57:35.936469 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue May 13 22:08:35 -00 2025 May 13 23:57:35.936497 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=digitalocean verity.usrhash=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:57:35.936516 kernel: BIOS-provided physical RAM map: May 13 23:57:35.936526 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 13 23:57:35.936536 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 13 23:57:35.936547 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 13 23:57:35.936560 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdafff] usable May 13 23:57:35.936568 kernel: BIOS-e820: [mem 0x000000007ffdb000-0x000000007fffffff] reserved May 13 23:57:35.936594 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 13 23:57:35.936601 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 13 23:57:35.936612 kernel: NX (Execute Disable) protection: active May 13 23:57:35.936623 kernel: APIC: Static calls initialized May 13 23:57:35.936641 kernel: SMBIOS 2.8 present. May 13 23:57:35.936653 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 May 13 23:57:35.936667 kernel: Hypervisor detected: KVM May 13 23:57:35.936678 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 13 23:57:35.936699 kernel: kvm-clock: using sched offset of 3181085207 cycles May 13 23:57:35.936712 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 13 23:57:35.937023 kernel: tsc: Detected 1995.309 MHz processor May 13 23:57:35.937032 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 13 23:57:35.937040 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 13 23:57:35.937048 kernel: last_pfn = 0x7ffdb max_arch_pfn = 0x400000000 May 13 23:57:35.937056 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 13 23:57:35.937064 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 13 23:57:35.937077 kernel: ACPI: Early table checksum verification disabled May 13 23:57:35.937094 kernel: ACPI: RSDP 0x00000000000F5950 000014 (v00 BOCHS ) May 13 23:57:35.937106 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937114 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937121 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937129 kernel: ACPI: FACS 0x000000007FFE0000 000040 May 13 23:57:35.937136 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937144 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937151 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937165 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:57:35.937177 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] May 13 23:57:35.937189 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] May 13 23:57:35.937202 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] May 13 23:57:35.937214 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] May 13 23:57:35.937221 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] May 13 23:57:35.937229 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] May 13 23:57:35.937241 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] May 13 23:57:35.937257 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 May 13 23:57:35.937270 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 May 13 23:57:35.937284 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] May 13 23:57:35.937295 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] May 13 23:57:35.937684 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffdafff] -> [mem 0x00000000-0x7ffdafff] May 13 23:57:35.937706 kernel: NODE_DATA(0) allocated [mem 0x7ffd5000-0x7ffdafff] May 13 23:57:35.937776 kernel: Zone ranges: May 13 23:57:35.937795 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 13 23:57:35.937806 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdafff] May 13 23:57:35.937818 kernel: Normal empty May 13 23:57:35.937830 kernel: Movable zone start for each node May 13 23:57:35.937843 kernel: Early memory node ranges May 13 23:57:35.937856 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 13 23:57:35.937868 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdafff] May 13 23:57:35.937882 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdafff] May 13 23:57:35.937895 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 13 23:57:35.937912 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 13 23:57:35.937931 kernel: On node 0, zone DMA32: 37 pages in unavailable ranges May 13 23:57:35.937945 kernel: ACPI: PM-Timer IO Port: 0x608 May 13 23:57:35.937959 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 13 23:57:35.937972 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 13 23:57:35.937986 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 13 23:57:35.937999 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 13 23:57:35.938013 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 13 23:57:35.938026 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 13 23:57:35.938036 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 13 23:57:35.938044 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 13 23:57:35.938052 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 13 23:57:35.938060 kernel: TSC deadline timer available May 13 23:57:35.938068 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs May 13 23:57:35.938076 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 13 23:57:35.938084 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices May 13 23:57:35.938096 kernel: Booting paravirtualized kernel on KVM May 13 23:57:35.938104 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 13 23:57:35.938114 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 May 13 23:57:35.938122 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 May 13 23:57:35.938130 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 May 13 23:57:35.938138 kernel: pcpu-alloc: [0] 0 1 May 13 23:57:35.938145 kernel: kvm-guest: PV spinlocks disabled, no host support May 13 23:57:35.938155 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=digitalocean verity.usrhash=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:57:35.938164 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 23:57:35.938172 kernel: random: crng init done May 13 23:57:35.938182 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 23:57:35.938200 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 13 23:57:35.938209 kernel: Fallback order for Node 0: 0 May 13 23:57:35.938218 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515803 May 13 23:57:35.938231 kernel: Policy zone: DMA32 May 13 23:57:35.938244 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 23:57:35.938257 kernel: Memory: 1967104K/2096612K available (14336K kernel code, 2296K rwdata, 25068K rodata, 43604K init, 1468K bss, 129248K reserved, 0K cma-reserved) May 13 23:57:35.938265 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 13 23:57:35.938273 kernel: Kernel/User page tables isolation: enabled May 13 23:57:35.938283 kernel: ftrace: allocating 37993 entries in 149 pages May 13 23:57:35.938291 kernel: ftrace: allocated 149 pages with 4 groups May 13 23:57:35.938298 kernel: Dynamic Preempt: voluntary May 13 23:57:35.938306 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 23:57:35.938321 kernel: rcu: RCU event tracing is enabled. May 13 23:57:35.938334 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 13 23:57:35.938349 kernel: Trampoline variant of Tasks RCU enabled. May 13 23:57:35.938356 kernel: Rude variant of Tasks RCU enabled. May 13 23:57:35.938365 kernel: Tracing variant of Tasks RCU enabled. May 13 23:57:35.938375 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 23:57:35.938383 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 13 23:57:35.938391 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 May 13 23:57:35.938405 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 13 23:57:35.938423 kernel: Console: colour VGA+ 80x25 May 13 23:57:35.938437 kernel: printk: console [tty0] enabled May 13 23:57:35.938446 kernel: printk: console [ttyS0] enabled May 13 23:57:35.938454 kernel: ACPI: Core revision 20230628 May 13 23:57:35.938462 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 13 23:57:35.938470 kernel: APIC: Switch to symmetric I/O mode setup May 13 23:57:35.938485 kernel: x2apic enabled May 13 23:57:35.938499 kernel: APIC: Switched APIC routing to: physical x2apic May 13 23:57:35.938512 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 13 23:57:35.938526 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3985bd6d44e, max_idle_ns: 881590467931 ns May 13 23:57:35.938540 kernel: Calibrating delay loop (skipped) preset value.. 3990.61 BogoMIPS (lpj=1995309) May 13 23:57:35.938549 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 May 13 23:57:35.938560 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 May 13 23:57:35.938586 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 13 23:57:35.938600 kernel: Spectre V2 : Mitigation: Retpolines May 13 23:57:35.938614 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 13 23:57:35.938629 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls May 13 23:57:35.938645 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 13 23:57:35.938659 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 13 23:57:35.938673 kernel: MDS: Mitigation: Clear CPU buffers May 13 23:57:35.938687 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode May 13 23:57:35.938707 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 13 23:57:35.940758 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 13 23:57:35.940778 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 13 23:57:35.940793 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 13 23:57:35.940807 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. May 13 23:57:35.940822 kernel: Freeing SMP alternatives memory: 32K May 13 23:57:35.940836 kernel: pid_max: default: 32768 minimum: 301 May 13 23:57:35.940850 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 13 23:57:35.940865 kernel: landlock: Up and running. May 13 23:57:35.940879 kernel: SELinux: Initializing. May 13 23:57:35.940898 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) May 13 23:57:35.940913 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) May 13 23:57:35.940927 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) May 13 23:57:35.940942 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 13 23:57:35.940956 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 13 23:57:35.940971 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 13 23:57:35.940986 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. May 13 23:57:35.941000 kernel: signal: max sigframe size: 1776 May 13 23:57:35.941017 kernel: rcu: Hierarchical SRCU implementation. May 13 23:57:35.941032 kernel: rcu: Max phase no-delay instances is 400. May 13 23:57:35.941046 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 13 23:57:35.941061 kernel: smp: Bringing up secondary CPUs ... May 13 23:57:35.941074 kernel: smpboot: x86: Booting SMP configuration: May 13 23:57:35.941087 kernel: .... node #0, CPUs: #1 May 13 23:57:35.941100 kernel: smp: Brought up 1 node, 2 CPUs May 13 23:57:35.941112 kernel: smpboot: Max logical packages: 1 May 13 23:57:35.941132 kernel: smpboot: Total of 2 processors activated (7981.23 BogoMIPS) May 13 23:57:35.941145 kernel: devtmpfs: initialized May 13 23:57:35.941161 kernel: x86/mm: Memory block size: 128MB May 13 23:57:35.941174 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 23:57:35.941187 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 13 23:57:35.941200 kernel: pinctrl core: initialized pinctrl subsystem May 13 23:57:35.941213 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 23:57:35.941227 kernel: audit: initializing netlink subsys (disabled) May 13 23:57:35.941244 kernel: audit: type=2000 audit(1747180655.188:1): state=initialized audit_enabled=0 res=1 May 13 23:57:35.941257 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 23:57:35.941270 kernel: thermal_sys: Registered thermal governor 'user_space' May 13 23:57:35.941286 kernel: cpuidle: using governor menu May 13 23:57:35.941298 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 23:57:35.941311 kernel: dca service started, version 1.12.1 May 13 23:57:35.941324 kernel: PCI: Using configuration type 1 for base access May 13 23:57:35.941337 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 13 23:57:35.941351 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 13 23:57:35.941364 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 13 23:57:35.941378 kernel: ACPI: Added _OSI(Module Device) May 13 23:57:35.941390 kernel: ACPI: Added _OSI(Processor Device) May 13 23:57:35.941406 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 23:57:35.941418 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 23:57:35.941431 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 23:57:35.941443 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 13 23:57:35.941455 kernel: ACPI: Interpreter enabled May 13 23:57:35.941468 kernel: ACPI: PM: (supports S0 S5) May 13 23:57:35.941481 kernel: ACPI: Using IOAPIC for interrupt routing May 13 23:57:35.941494 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 13 23:57:35.941507 kernel: PCI: Using E820 reservations for host bridge windows May 13 23:57:35.941524 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F May 13 23:57:35.941536 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 13 23:57:35.941795 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] May 13 23:57:35.941957 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] May 13 23:57:35.942098 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge May 13 23:57:35.942117 kernel: acpiphp: Slot [3] registered May 13 23:57:35.942132 kernel: acpiphp: Slot [4] registered May 13 23:57:35.942150 kernel: acpiphp: Slot [5] registered May 13 23:57:35.942164 kernel: acpiphp: Slot [6] registered May 13 23:57:35.942178 kernel: acpiphp: Slot [7] registered May 13 23:57:35.942202 kernel: acpiphp: Slot [8] registered May 13 23:57:35.942215 kernel: acpiphp: Slot [9] registered May 13 23:57:35.942230 kernel: acpiphp: Slot [10] registered May 13 23:57:35.942244 kernel: acpiphp: Slot [11] registered May 13 23:57:35.942257 kernel: acpiphp: Slot [12] registered May 13 23:57:35.942272 kernel: acpiphp: Slot [13] registered May 13 23:57:35.942289 kernel: acpiphp: Slot [14] registered May 13 23:57:35.942304 kernel: acpiphp: Slot [15] registered May 13 23:57:35.942319 kernel: acpiphp: Slot [16] registered May 13 23:57:35.942332 kernel: acpiphp: Slot [17] registered May 13 23:57:35.942346 kernel: acpiphp: Slot [18] registered May 13 23:57:35.942360 kernel: acpiphp: Slot [19] registered May 13 23:57:35.942374 kernel: acpiphp: Slot [20] registered May 13 23:57:35.942388 kernel: acpiphp: Slot [21] registered May 13 23:57:35.942402 kernel: acpiphp: Slot [22] registered May 13 23:57:35.942416 kernel: acpiphp: Slot [23] registered May 13 23:57:35.942433 kernel: acpiphp: Slot [24] registered May 13 23:57:35.942447 kernel: acpiphp: Slot [25] registered May 13 23:57:35.942461 kernel: acpiphp: Slot [26] registered May 13 23:57:35.942476 kernel: acpiphp: Slot [27] registered May 13 23:57:35.942489 kernel: acpiphp: Slot [28] registered May 13 23:57:35.942503 kernel: acpiphp: Slot [29] registered May 13 23:57:35.942517 kernel: acpiphp: Slot [30] registered May 13 23:57:35.942531 kernel: acpiphp: Slot [31] registered May 13 23:57:35.942546 kernel: PCI host bridge to bus 0000:00 May 13 23:57:35.942715 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 13 23:57:35.942876 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 13 23:57:35.943007 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 13 23:57:35.943144 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] May 13 23:57:35.943236 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] May 13 23:57:35.943323 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 13 23:57:35.943470 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 May 13 23:57:35.943585 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 May 13 23:57:35.948751 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 May 13 23:57:35.948939 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] May 13 23:57:35.949083 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] May 13 23:57:35.949225 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] May 13 23:57:35.949365 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] May 13 23:57:35.949542 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] May 13 23:57:35.949703 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 May 13 23:57:35.949826 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] May 13 23:57:35.949960 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 May 13 23:57:35.950060 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI May 13 23:57:35.950153 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB May 13 23:57:35.950277 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 May 13 23:57:35.950387 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] May 13 23:57:35.950513 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] May 13 23:57:35.950645 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] May 13 23:57:35.953839 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] May 13 23:57:35.953956 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 13 23:57:35.954104 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 May 13 23:57:35.954249 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] May 13 23:57:35.954362 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] May 13 23:57:35.954483 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] May 13 23:57:35.954631 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 13 23:57:35.954802 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] May 13 23:57:35.954943 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] May 13 23:57:35.955082 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] May 13 23:57:35.955246 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 May 13 23:57:35.955392 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] May 13 23:57:35.955519 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] May 13 23:57:35.955614 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] May 13 23:57:35.957771 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 May 13 23:57:35.957932 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] May 13 23:57:35.958034 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] May 13 23:57:35.958158 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] May 13 23:57:35.958356 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 May 13 23:57:35.958460 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] May 13 23:57:35.958555 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] May 13 23:57:35.958648 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] May 13 23:57:35.958770 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 May 13 23:57:35.958869 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] May 13 23:57:35.958969 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] May 13 23:57:35.958980 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 13 23:57:35.958989 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 13 23:57:35.958998 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 13 23:57:35.959007 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 13 23:57:35.959015 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 May 13 23:57:35.959024 kernel: iommu: Default domain type: Translated May 13 23:57:35.959035 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 13 23:57:35.959044 kernel: PCI: Using ACPI for IRQ routing May 13 23:57:35.959052 kernel: PCI: pci_cache_line_size set to 64 bytes May 13 23:57:35.959061 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 13 23:57:35.959070 kernel: e820: reserve RAM buffer [mem 0x7ffdb000-0x7fffffff] May 13 23:57:35.959164 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device May 13 23:57:35.959274 kernel: pci 0000:00:02.0: vgaarb: bridge control possible May 13 23:57:35.959378 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 13 23:57:35.959396 kernel: vgaarb: loaded May 13 23:57:35.959415 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 13 23:57:35.959429 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 13 23:57:35.959443 kernel: clocksource: Switched to clocksource kvm-clock May 13 23:57:35.959458 kernel: VFS: Disk quotas dquot_6.6.0 May 13 23:57:35.959469 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 23:57:35.959478 kernel: pnp: PnP ACPI init May 13 23:57:35.959487 kernel: pnp: PnP ACPI: found 4 devices May 13 23:57:35.959495 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 13 23:57:35.959504 kernel: NET: Registered PF_INET protocol family May 13 23:57:35.959515 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) May 13 23:57:35.959524 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) May 13 23:57:35.959533 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 23:57:35.959542 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) May 13 23:57:35.959551 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) May 13 23:57:35.959560 kernel: TCP: Hash tables configured (established 16384 bind 16384) May 13 23:57:35.959568 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) May 13 23:57:35.959577 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) May 13 23:57:35.959586 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 23:57:35.959596 kernel: NET: Registered PF_XDP protocol family May 13 23:57:35.959695 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 13 23:57:35.963625 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 13 23:57:35.963761 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 13 23:57:35.963864 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] May 13 23:57:35.963992 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] May 13 23:57:35.964144 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release May 13 23:57:35.964297 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers May 13 23:57:35.964319 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 May 13 23:57:35.964457 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 31196 usecs May 13 23:57:35.964471 kernel: PCI: CLS 0 bytes, default 64 May 13 23:57:35.964480 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer May 13 23:57:35.964489 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3985bd6d44e, max_idle_ns: 881590467931 ns May 13 23:57:35.964498 kernel: Initialise system trusted keyrings May 13 23:57:35.964508 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 May 13 23:57:35.964516 kernel: Key type asymmetric registered May 13 23:57:35.964529 kernel: Asymmetric key parser 'x509' registered May 13 23:57:35.964538 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 13 23:57:35.964546 kernel: io scheduler mq-deadline registered May 13 23:57:35.964555 kernel: io scheduler kyber registered May 13 23:57:35.964563 kernel: io scheduler bfq registered May 13 23:57:35.964572 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 13 23:57:35.964581 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 May 13 23:57:35.964590 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 May 13 23:57:35.964599 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 May 13 23:57:35.964607 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 23:57:35.964618 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 13 23:57:35.964627 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 13 23:57:35.964636 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 13 23:57:35.964645 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 13 23:57:35.964801 kernel: rtc_cmos 00:03: RTC can wake from S4 May 13 23:57:35.964815 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 13 23:57:35.964902 kernel: rtc_cmos 00:03: registered as rtc0 May 13 23:57:35.964999 kernel: rtc_cmos 00:03: setting system clock to 2025-05-13T23:57:35 UTC (1747180655) May 13 23:57:35.965112 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram May 13 23:57:35.965130 kernel: intel_pstate: CPU model not supported May 13 23:57:35.965144 kernel: NET: Registered PF_INET6 protocol family May 13 23:57:35.965159 kernel: Segment Routing with IPv6 May 13 23:57:35.965169 kernel: In-situ OAM (IOAM) with IPv6 May 13 23:57:35.965178 kernel: NET: Registered PF_PACKET protocol family May 13 23:57:35.965187 kernel: Key type dns_resolver registered May 13 23:57:35.965196 kernel: IPI shorthand broadcast: enabled May 13 23:57:35.965210 kernel: sched_clock: Marking stable (899004538, 130169017)->(1124532129, -95358574) May 13 23:57:35.965224 kernel: registered taskstats version 1 May 13 23:57:35.965238 kernel: Loading compiled-in X.509 certificates May 13 23:57:35.965253 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 166efda032ca4d6e9037c569aca9b53585ee6f94' May 13 23:57:35.965264 kernel: Key type .fscrypt registered May 13 23:57:35.965273 kernel: Key type fscrypt-provisioning registered May 13 23:57:35.965281 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 23:57:35.965290 kernel: ima: Allocated hash algorithm: sha1 May 13 23:57:35.965303 kernel: ima: No architecture policies found May 13 23:57:35.965320 kernel: clk: Disabling unused clocks May 13 23:57:35.965333 kernel: Freeing unused kernel image (initmem) memory: 43604K May 13 23:57:35.965346 kernel: Write protecting the kernel read-only data: 40960k May 13 23:57:35.965360 kernel: Freeing unused kernel image (rodata/data gap) memory: 1556K May 13 23:57:35.965395 kernel: Run /init as init process May 13 23:57:35.965413 kernel: with arguments: May 13 23:57:35.965426 kernel: /init May 13 23:57:35.965440 kernel: with environment: May 13 23:57:35.965454 kernel: HOME=/ May 13 23:57:35.965471 kernel: TERM=linux May 13 23:57:35.965485 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 23:57:35.965501 systemd[1]: Successfully made /usr/ read-only. May 13 23:57:35.965519 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 13 23:57:35.965535 systemd[1]: Detected virtualization kvm. May 13 23:57:35.965549 systemd[1]: Detected architecture x86-64. May 13 23:57:35.965563 systemd[1]: Running in initrd. May 13 23:57:35.965577 systemd[1]: No hostname configured, using default hostname. May 13 23:57:35.965596 systemd[1]: Hostname set to . May 13 23:57:35.965611 systemd[1]: Initializing machine ID from VM UUID. May 13 23:57:35.965626 systemd[1]: Queued start job for default target initrd.target. May 13 23:57:35.965642 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:57:35.965657 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:57:35.965674 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 13 23:57:35.965690 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:57:35.965710 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 13 23:57:35.968531 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 13 23:57:35.968548 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 13 23:57:35.968560 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 13 23:57:35.968569 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:57:35.968579 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:57:35.968588 systemd[1]: Reached target paths.target - Path Units. May 13 23:57:35.968603 systemd[1]: Reached target slices.target - Slice Units. May 13 23:57:35.968613 systemd[1]: Reached target swap.target - Swaps. May 13 23:57:35.968625 systemd[1]: Reached target timers.target - Timer Units. May 13 23:57:35.968634 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:57:35.968644 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:57:35.968654 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 13 23:57:35.968665 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 13 23:57:35.968675 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:57:35.968684 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:57:35.968693 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:57:35.968703 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:57:35.968712 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 13 23:57:35.968732 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:57:35.968742 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 13 23:57:35.968754 systemd[1]: Starting systemd-fsck-usr.service... May 13 23:57:35.968763 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:57:35.968773 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:57:35.968782 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:35.968832 systemd-journald[184]: Collecting audit messages is disabled. May 13 23:57:35.968860 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 13 23:57:35.968870 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:57:35.968880 systemd[1]: Finished systemd-fsck-usr.service. May 13 23:57:35.968890 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:57:35.968903 systemd-journald[184]: Journal started May 13 23:57:35.968925 systemd-journald[184]: Runtime Journal (/run/log/journal/21f6e900c99646abb2a73efeedbdedd5) is 4.9M, max 39.3M, 34.3M free. May 13 23:57:35.971306 systemd-modules-load[185]: Inserted module 'overlay' May 13 23:57:35.993002 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:57:35.997188 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:36.003121 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 13 23:57:36.003512 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:57:36.010132 kernel: Bridge firewalling registered May 13 23:57:36.006753 systemd-modules-load[185]: Inserted module 'br_netfilter' May 13 23:57:36.013219 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:57:36.013997 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:57:36.014683 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:57:36.017895 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:57:36.027150 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:57:36.033686 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:57:36.042771 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:57:36.045957 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:57:36.047950 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:57:36.051167 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:57:36.058869 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 13 23:57:36.074289 dracut-cmdline[218]: dracut-dracut-053 May 13 23:57:36.080522 dracut-cmdline[218]: 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=digitalocean verity.usrhash=8b3c5774a4242053287d41edc0d029958b7c22c131f7dd36b16a68182354e130 May 13 23:57:36.092920 systemd-resolved[215]: Positive Trust Anchors: May 13 23:57:36.093559 systemd-resolved[215]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:57:36.093595 systemd-resolved[215]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 13 23:57:36.099356 systemd-resolved[215]: Defaulting to hostname 'linux'. May 13 23:57:36.100540 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:57:36.101834 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:57:36.164747 kernel: SCSI subsystem initialized May 13 23:57:36.175760 kernel: Loading iSCSI transport class v2.0-870. May 13 23:57:36.187744 kernel: iscsi: registered transport (tcp) May 13 23:57:36.210978 kernel: iscsi: registered transport (qla4xxx) May 13 23:57:36.211020 kernel: QLogic iSCSI HBA Driver May 13 23:57:36.249646 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 13 23:57:36.251620 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 13 23:57:36.289137 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 13 23:57:36.289186 kernel: device-mapper: uevent: version 1.0.3 May 13 23:57:36.290743 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 13 23:57:36.334785 kernel: raid6: avx2x4 gen() 31541 MB/s May 13 23:57:36.349748 kernel: raid6: avx2x2 gen() 32033 MB/s May 13 23:57:36.366843 kernel: raid6: avx2x1 gen() 23775 MB/s May 13 23:57:36.366880 kernel: raid6: using algorithm avx2x2 gen() 32033 MB/s May 13 23:57:36.384956 kernel: raid6: .... xor() 18485 MB/s, rmw enabled May 13 23:57:36.384983 kernel: raid6: using avx2x2 recovery algorithm May 13 23:57:36.409746 kernel: xor: automatically using best checksumming function avx May 13 23:57:36.558760 kernel: Btrfs loaded, zoned=no, fsverity=no May 13 23:57:36.570803 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 13 23:57:36.573939 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:57:36.595750 systemd-udevd[401]: Using default interface naming scheme 'v255'. May 13 23:57:36.600695 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:57:36.604404 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 13 23:57:36.627062 dracut-pre-trigger[406]: rd.md=0: removing MD RAID activation May 13 23:57:36.659297 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:57:36.661192 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:57:36.719759 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:57:36.724944 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 13 23:57:36.753703 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 13 23:57:36.755455 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:57:36.757451 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:57:36.758640 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:57:36.761174 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 13 23:57:36.782999 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 13 23:57:36.810769 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues May 13 23:57:36.817748 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) May 13 23:57:36.817942 kernel: cryptd: max_cpu_qlen set to 1000 May 13 23:57:36.829060 kernel: scsi host0: Virtio SCSI HBA May 13 23:57:36.836768 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 13 23:57:36.836822 kernel: GPT:9289727 != 125829119 May 13 23:57:36.836835 kernel: GPT:Alternate GPT header not at the end of the disk. May 13 23:57:36.836847 kernel: GPT:9289727 != 125829119 May 13 23:57:36.836857 kernel: GPT: Use GNU Parted to correct GPT errors. May 13 23:57:36.836876 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:57:36.851779 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues May 13 23:57:36.852409 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:57:36.853238 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:57:36.854739 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:57:36.856067 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:57:36.856883 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:36.858220 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:36.861987 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:36.872314 kernel: libata version 3.00 loaded. May 13 23:57:36.872352 kernel: AVX2 version of gcm_enc/dec engaged. May 13 23:57:36.874627 kernel: virtio_blk virtio5: [vdb] 932 512-byte logical blocks (477 kB/466 KiB) May 13 23:57:36.881779 kernel: AES CTR mode by8 optimization enabled May 13 23:57:36.897763 kernel: ACPI: bus type USB registered May 13 23:57:36.897806 kernel: usbcore: registered new interface driver usbfs May 13 23:57:36.906151 kernel: ata_piix 0000:00:01.1: version 2.13 May 13 23:57:36.906420 kernel: usbcore: registered new interface driver hub May 13 23:57:36.906438 kernel: usbcore: registered new device driver usb May 13 23:57:36.926764 kernel: scsi host1: ata_piix May 13 23:57:36.933932 kernel: scsi host2: ata_piix May 13 23:57:36.934478 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 May 13 23:57:36.934527 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 May 13 23:57:36.949771 kernel: BTRFS: device fsid d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (455) May 13 23:57:36.956743 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (458) May 13 23:57:36.966936 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 13 23:57:36.995159 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:37.014046 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 13 23:57:37.020698 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 13 23:57:37.021335 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 13 23:57:37.030360 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:57:37.031842 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 13 23:57:37.034839 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:57:37.053589 disk-uuid[546]: Primary Header is updated. May 13 23:57:37.053589 disk-uuid[546]: Secondary Entries is updated. May 13 23:57:37.053589 disk-uuid[546]: Secondary Header is updated. May 13 23:57:37.060108 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:57:37.062894 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:57:37.070759 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:57:37.134652 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller May 13 23:57:37.134919 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 May 13 23:57:37.137409 kernel: uhci_hcd 0000:00:01.2: detected 2 ports May 13 23:57:37.137574 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 May 13 23:57:37.143300 kernel: hub 1-0:1.0: USB hub found May 13 23:57:37.143487 kernel: hub 1-0:1.0: 2 ports detected May 13 23:57:38.073767 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:57:38.074274 disk-uuid[549]: The operation has completed successfully. May 13 23:57:38.121609 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 23:57:38.121734 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 13 23:57:38.144889 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 13 23:57:38.160812 sh[564]: Success May 13 23:57:38.174760 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" May 13 23:57:38.225171 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 13 23:57:38.229812 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 13 23:57:38.238438 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 13 23:57:38.254741 kernel: BTRFS info (device dm-0): first mount of filesystem d2fbd39e-42cb-4ccb-87ec-99f56cfe77f8 May 13 23:57:38.254795 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 13 23:57:38.254807 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 13 23:57:38.254819 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 13 23:57:38.254830 kernel: BTRFS info (device dm-0): using free space tree May 13 23:57:38.261372 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 13 23:57:38.262323 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 13 23:57:38.264847 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 13 23:57:38.267843 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 13 23:57:38.293548 kernel: BTRFS info (device vda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:57:38.293591 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:57:38.293604 kernel: BTRFS info (device vda6): using free space tree May 13 23:57:38.298744 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:57:38.304817 kernel: BTRFS info (device vda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:57:38.306569 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 13 23:57:38.309850 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 13 23:57:38.390113 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:57:38.393905 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:57:38.435588 systemd-networkd[745]: lo: Link UP May 13 23:57:38.435601 systemd-networkd[745]: lo: Gained carrier May 13 23:57:38.437902 systemd-networkd[745]: Enumeration completed May 13 23:57:38.438015 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:57:38.438687 systemd-networkd[745]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. May 13 23:57:38.438691 systemd-networkd[745]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. May 13 23:57:38.439114 systemd[1]: Reached target network.target - Network. May 13 23:57:38.439704 systemd-networkd[745]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:57:38.439707 systemd-networkd[745]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:57:38.441111 systemd-networkd[745]: eth0: Link UP May 13 23:57:38.441116 systemd-networkd[745]: eth0: Gained carrier May 13 23:57:38.441124 systemd-networkd[745]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. May 13 23:57:38.443942 systemd-networkd[745]: eth1: Link UP May 13 23:57:38.443945 systemd-networkd[745]: eth1: Gained carrier May 13 23:57:38.443954 systemd-networkd[745]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:57:38.455891 systemd-networkd[745]: eth0: DHCPv4 address 64.23.143.183/20, gateway 64.23.128.1 acquired from 169.254.169.253 May 13 23:57:38.458001 ignition[661]: Ignition 2.20.0 May 13 23:57:38.458014 ignition[661]: Stage: fetch-offline May 13 23:57:38.458047 ignition[661]: no configs at "/usr/lib/ignition/base.d" May 13 23:57:38.458056 ignition[661]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:38.458166 ignition[661]: parsed url from cmdline: "" May 13 23:57:38.461059 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:57:38.458170 ignition[661]: no config URL provided May 13 23:57:38.462783 systemd-networkd[745]: eth1: DHCPv4 address 10.124.0.34/20 acquired from 169.254.169.253 May 13 23:57:38.458176 ignition[661]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:57:38.458184 ignition[661]: no config at "/usr/lib/ignition/user.ign" May 13 23:57:38.458189 ignition[661]: failed to fetch config: resource requires networking May 13 23:57:38.458382 ignition[661]: Ignition finished successfully May 13 23:57:38.466819 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 13 23:57:38.499144 ignition[753]: Ignition 2.20.0 May 13 23:57:38.499155 ignition[753]: Stage: fetch May 13 23:57:38.499353 ignition[753]: no configs at "/usr/lib/ignition/base.d" May 13 23:57:38.499364 ignition[753]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:38.500172 ignition[753]: parsed url from cmdline: "" May 13 23:57:38.500178 ignition[753]: no config URL provided May 13 23:57:38.500188 ignition[753]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:57:38.500203 ignition[753]: no config at "/usr/lib/ignition/user.ign" May 13 23:57:38.500236 ignition[753]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 May 13 23:57:38.517597 ignition[753]: GET result: OK May 13 23:57:38.518256 ignition[753]: parsing config with SHA512: a0f1ddcaa1a2aafcc9e3856182c908c66497473de660342dd3a0f409486c27056b7c36edf8f34700ae4d6b3c2fd957159f7c6da12902a20de6e8b75b0303d25f May 13 23:57:38.523336 unknown[753]: fetched base config from "system" May 13 23:57:38.523923 unknown[753]: fetched base config from "system" May 13 23:57:38.524139 ignition[753]: fetch: fetch complete May 13 23:57:38.523929 unknown[753]: fetched user config from "digitalocean" May 13 23:57:38.524144 ignition[753]: fetch: fetch passed May 13 23:57:38.524189 ignition[753]: Ignition finished successfully May 13 23:57:38.526988 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 13 23:57:38.529532 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 13 23:57:38.560511 ignition[761]: Ignition 2.20.0 May 13 23:57:38.561292 ignition[761]: Stage: kargs May 13 23:57:38.561904 ignition[761]: no configs at "/usr/lib/ignition/base.d" May 13 23:57:38.562465 ignition[761]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:38.563831 ignition[761]: kargs: kargs passed May 13 23:57:38.564344 ignition[761]: Ignition finished successfully May 13 23:57:38.565813 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 13 23:57:38.567456 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 13 23:57:38.591528 ignition[767]: Ignition 2.20.0 May 13 23:57:38.591541 ignition[767]: Stage: disks May 13 23:57:38.591696 ignition[767]: no configs at "/usr/lib/ignition/base.d" May 13 23:57:38.591706 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:38.593229 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 13 23:57:38.592358 ignition[767]: disks: disks passed May 13 23:57:38.594300 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 13 23:57:38.592399 ignition[767]: Ignition finished successfully May 13 23:57:38.599367 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 13 23:57:38.600445 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:57:38.601460 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:57:38.602608 systemd[1]: Reached target basic.target - Basic System. May 13 23:57:38.605822 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 13 23:57:38.623993 systemd-fsck[775]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 13 23:57:38.626926 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 13 23:57:38.629133 systemd[1]: Mounting sysroot.mount - /sysroot... May 13 23:57:38.742758 kernel: EXT4-fs (vda9): mounted filesystem c413e98b-da35-46b1-9852-45706e1b1f52 r/w with ordered data mode. Quota mode: none. May 13 23:57:38.743145 systemd[1]: Mounted sysroot.mount - /sysroot. May 13 23:57:38.744134 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 13 23:57:38.746913 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:57:38.749808 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 13 23:57:38.755904 systemd[1]: Starting flatcar-afterburn-network.service - Flatcar Afterburn network service... May 13 23:57:38.759926 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 13 23:57:38.762797 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 13 23:57:38.767194 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (783) May 13 23:57:38.767218 kernel: BTRFS info (device vda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:57:38.763802 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:57:38.770015 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:57:38.770045 kernel: BTRFS info (device vda6): using free space tree May 13 23:57:38.773035 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 13 23:57:38.776411 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 13 23:57:38.786745 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:57:38.787859 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:57:38.838228 coreos-metadata[785]: May 13 23:57:38.837 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 May 13 23:57:38.844974 initrd-setup-root[813]: cut: /sysroot/etc/passwd: No such file or directory May 13 23:57:38.846350 coreos-metadata[786]: May 13 23:57:38.846 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 May 13 23:57:38.849212 coreos-metadata[785]: May 13 23:57:38.849 INFO Fetch successful May 13 23:57:38.852040 initrd-setup-root[820]: cut: /sysroot/etc/group: No such file or directory May 13 23:57:38.856342 systemd[1]: flatcar-afterburn-network.service: Deactivated successfully. May 13 23:57:38.858111 systemd[1]: Finished flatcar-afterburn-network.service - Flatcar Afterburn network service. May 13 23:57:38.859387 coreos-metadata[786]: May 13 23:57:38.856 INFO Fetch successful May 13 23:57:38.861501 initrd-setup-root[827]: cut: /sysroot/etc/shadow: No such file or directory May 13 23:57:38.864836 coreos-metadata[786]: May 13 23:57:38.864 INFO wrote hostname ci-4284.0.0-n-b1a48b09a9 to /sysroot/etc/hostname May 13 23:57:38.866114 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:57:38.868666 initrd-setup-root[835]: cut: /sysroot/etc/gshadow: No such file or directory May 13 23:57:38.951369 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 13 23:57:38.953840 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 13 23:57:38.956502 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 13 23:57:38.971739 kernel: BTRFS info (device vda6): last unmount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:57:38.989134 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 13 23:57:39.006772 ignition[905]: INFO : Ignition 2.20.0 May 13 23:57:39.006772 ignition[905]: INFO : Stage: mount May 13 23:57:39.006772 ignition[905]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:57:39.006772 ignition[905]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:39.009891 ignition[905]: INFO : mount: mount passed May 13 23:57:39.009891 ignition[905]: INFO : Ignition finished successfully May 13 23:57:39.009248 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 13 23:57:39.012821 systemd[1]: Starting ignition-files.service - Ignition (files)... May 13 23:57:39.249807 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 13 23:57:39.252275 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:57:39.271750 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (916) May 13 23:57:39.271792 kernel: BTRFS info (device vda6): first mount of filesystem c0e200fb-7321-4d2d-86ff-b28bdae5fafc May 13 23:57:39.274150 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 13 23:57:39.274185 kernel: BTRFS info (device vda6): using free space tree May 13 23:57:39.278749 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:57:39.279947 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:57:39.304440 ignition[933]: INFO : Ignition 2.20.0 May 13 23:57:39.304440 ignition[933]: INFO : Stage: files May 13 23:57:39.305602 ignition[933]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:57:39.305602 ignition[933]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:39.305602 ignition[933]: DEBUG : files: compiled without relabeling support, skipping May 13 23:57:39.307681 ignition[933]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 13 23:57:39.307681 ignition[933]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 13 23:57:39.309128 ignition[933]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 13 23:57:39.309128 ignition[933]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 13 23:57:39.310545 ignition[933]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 13 23:57:39.309257 unknown[933]: wrote ssh authorized keys file for user: core May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 13 23:57:39.312005 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 May 13 23:57:39.768048 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 13 23:57:39.807849 systemd-networkd[745]: eth0: Gained IPv6LL May 13 23:57:40.023110 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 13 23:57:40.024192 ignition[933]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 23:57:40.024192 ignition[933]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 23:57:40.024192 ignition[933]: INFO : files: files passed May 13 23:57:40.026771 ignition[933]: INFO : Ignition finished successfully May 13 23:57:40.025347 systemd[1]: Finished ignition-files.service - Ignition (files). May 13 23:57:40.028872 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 13 23:57:40.032126 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 13 23:57:40.043094 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 23:57:40.043204 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 13 23:57:40.049619 initrd-setup-root-after-ignition[964]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:57:40.049619 initrd-setup-root-after-ignition[964]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 13 23:57:40.051369 initrd-setup-root-after-ignition[968]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:57:40.050903 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:57:40.052346 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 13 23:57:40.054754 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 13 23:57:40.064860 systemd-networkd[745]: eth1: Gained IPv6LL May 13 23:57:40.090922 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 23:57:40.091023 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 13 23:57:40.092267 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 13 23:57:40.093196 systemd[1]: Reached target initrd.target - Initrd Default Target. May 13 23:57:40.094228 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 13 23:57:40.095568 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 13 23:57:40.118582 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:57:40.121168 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 13 23:57:40.140225 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 13 23:57:40.141593 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:57:40.142239 systemd[1]: Stopped target timers.target - Timer Units. May 13 23:57:40.142760 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 23:57:40.142869 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:57:40.143577 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 13 23:57:40.144210 systemd[1]: Stopped target basic.target - Basic System. May 13 23:57:40.145484 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 13 23:57:40.146444 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:57:40.147392 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 13 23:57:40.148513 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 13 23:57:40.149462 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:57:40.150494 systemd[1]: Stopped target sysinit.target - System Initialization. May 13 23:57:40.151481 systemd[1]: Stopped target local-fs.target - Local File Systems. May 13 23:57:40.152504 systemd[1]: Stopped target swap.target - Swaps. May 13 23:57:40.153463 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 23:57:40.153569 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 13 23:57:40.154979 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 13 23:57:40.155593 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:57:40.156660 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 13 23:57:40.156786 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:57:40.157698 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 23:57:40.157846 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 13 23:57:40.158980 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 23:57:40.159098 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:57:40.160284 systemd[1]: ignition-files.service: Deactivated successfully. May 13 23:57:40.160401 systemd[1]: Stopped ignition-files.service - Ignition (files). May 13 23:57:40.161271 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 13 23:57:40.161380 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 13 23:57:40.164955 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 13 23:57:40.165778 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 23:57:40.165927 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:57:40.168903 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 13 23:57:40.169795 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 23:57:40.170639 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:57:40.171343 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 23:57:40.171910 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:57:40.183244 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 23:57:40.183783 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 13 23:57:40.198172 ignition[988]: INFO : Ignition 2.20.0 May 13 23:57:40.219008 ignition[988]: INFO : Stage: umount May 13 23:57:40.219008 ignition[988]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:57:40.219008 ignition[988]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" May 13 23:57:40.219008 ignition[988]: INFO : umount: umount passed May 13 23:57:40.219008 ignition[988]: INFO : Ignition finished successfully May 13 23:57:40.201876 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 23:57:40.203878 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 23:57:40.203982 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 13 23:57:40.220051 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 23:57:40.220182 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 13 23:57:40.221178 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 23:57:40.221225 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 13 23:57:40.222751 systemd[1]: ignition-fetch.service: Deactivated successfully. May 13 23:57:40.222798 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 13 23:57:40.223695 systemd[1]: Stopped target network.target - Network. May 13 23:57:40.224555 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 23:57:40.224606 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:57:40.239821 systemd[1]: Stopped target paths.target - Path Units. May 13 23:57:40.240254 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 23:57:40.242397 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:57:40.243050 systemd[1]: Stopped target slices.target - Slice Units. May 13 23:57:40.243970 systemd[1]: Stopped target sockets.target - Socket Units. May 13 23:57:40.245016 systemd[1]: iscsid.socket: Deactivated successfully. May 13 23:57:40.245067 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:57:40.246034 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 23:57:40.246088 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:57:40.248004 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 23:57:40.248058 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 13 23:57:40.248988 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 13 23:57:40.249026 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 13 23:57:40.250277 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 13 23:57:40.251230 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 13 23:57:40.252676 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 23:57:40.252789 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 13 23:57:40.254164 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 23:57:40.254283 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 13 23:57:40.256600 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 23:57:40.256699 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 13 23:57:40.264647 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 13 23:57:40.264955 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 23:57:40.265056 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 13 23:57:40.267240 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 13 23:57:40.268267 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 23:57:40.268337 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 13 23:57:40.271807 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 13 23:57:40.272424 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 23:57:40.272475 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:57:40.273548 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 23:57:40.273593 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 13 23:57:40.276061 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 23:57:40.276104 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 13 23:57:40.277137 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 13 23:57:40.277186 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:57:40.278839 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:57:40.281471 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 13 23:57:40.281535 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 13 23:57:40.290402 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 23:57:40.290553 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:57:40.291841 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 23:57:40.291882 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 13 23:57:40.293326 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 23:57:40.293360 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:57:40.294325 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 23:57:40.294373 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 13 23:57:40.295848 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 23:57:40.295887 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 13 23:57:40.297088 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:57:40.297131 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:57:40.299840 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 13 23:57:40.301962 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 13 23:57:40.302018 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:57:40.303201 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:57:40.303246 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:40.306060 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 13 23:57:40.306120 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:57:40.306440 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 23:57:40.307214 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 13 23:57:40.319930 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 23:57:40.320044 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 13 23:57:40.321340 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 13 23:57:40.323853 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 13 23:57:40.341026 systemd[1]: Switching root. May 13 23:57:40.393259 systemd-journald[184]: Journal stopped May 13 23:57:41.585652 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). May 13 23:57:41.585826 kernel: SELinux: policy capability network_peer_controls=1 May 13 23:57:41.585852 kernel: SELinux: policy capability open_perms=1 May 13 23:57:41.585873 kernel: SELinux: policy capability extended_socket_class=1 May 13 23:57:41.585892 kernel: SELinux: policy capability always_check_network=0 May 13 23:57:41.585911 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 23:57:41.585932 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 23:57:41.585951 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 23:57:41.585971 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 23:57:41.585995 kernel: audit: type=1403 audit(1747180660.494:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 13 23:57:41.586017 systemd[1]: Successfully loaded SELinux policy in 39.528ms. May 13 23:57:41.586055 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.160ms. May 13 23:57:41.586077 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 13 23:57:41.586098 systemd[1]: Detected virtualization kvm. May 13 23:57:41.586119 systemd[1]: Detected architecture x86-64. May 13 23:57:41.586140 systemd[1]: Detected first boot. May 13 23:57:41.586166 systemd[1]: Hostname set to . May 13 23:57:41.586190 systemd[1]: Initializing machine ID from VM UUID. May 13 23:57:41.586209 kernel: Guest personality initialized and is inactive May 13 23:57:41.586242 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 13 23:57:41.586267 kernel: Initialized host personality May 13 23:57:41.586287 zram_generator::config[1032]: No configuration found. May 13 23:57:41.586309 kernel: NET: Registered PF_VSOCK protocol family May 13 23:57:41.586356 systemd[1]: Populated /etc with preset unit settings. May 13 23:57:41.586379 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 13 23:57:41.586401 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 13 23:57:41.586427 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 13 23:57:41.586448 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 13 23:57:41.586470 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 13 23:57:41.586491 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 13 23:57:41.586512 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 13 23:57:41.586533 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 13 23:57:41.586554 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 13 23:57:41.586575 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 13 23:57:41.586599 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 13 23:57:41.586619 systemd[1]: Created slice user.slice - User and Session Slice. May 13 23:57:41.586640 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:57:41.586662 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:57:41.586683 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 13 23:57:41.586748 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 13 23:57:41.586772 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 13 23:57:41.586797 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:57:41.586818 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 13 23:57:41.586839 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:57:41.586860 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 13 23:57:41.586882 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 13 23:57:41.586903 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 13 23:57:41.586924 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 13 23:57:41.586944 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:57:41.586968 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:57:41.586987 systemd[1]: Reached target slices.target - Slice Units. May 13 23:57:41.587008 systemd[1]: Reached target swap.target - Swaps. May 13 23:57:41.587028 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 13 23:57:41.587048 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 13 23:57:41.587067 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 13 23:57:41.587088 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:57:41.587108 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:57:41.587129 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:57:41.587151 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 13 23:57:41.587176 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 13 23:57:41.587207 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 13 23:57:41.587228 systemd[1]: Mounting media.mount - External Media Directory... May 13 23:57:41.587249 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:41.587270 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 13 23:57:41.587291 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 13 23:57:41.587311 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 13 23:57:41.587332 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 13 23:57:41.587357 systemd[1]: Reached target machines.target - Containers. May 13 23:57:41.587377 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 13 23:57:41.587397 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:57:41.587418 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:57:41.587439 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 13 23:57:41.587459 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:57:41.587480 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:57:41.587501 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:57:41.587520 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 13 23:57:41.587545 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:57:41.587566 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 13 23:57:41.587587 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 13 23:57:41.587608 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 13 23:57:41.587628 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 13 23:57:41.587657 systemd[1]: Stopped systemd-fsck-usr.service. May 13 23:57:41.587679 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:57:41.587700 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:57:41.591796 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:57:41.597292 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 13 23:57:41.597310 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 13 23:57:41.597323 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 13 23:57:41.597335 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:57:41.597348 systemd[1]: verity-setup.service: Deactivated successfully. May 13 23:57:41.597364 systemd[1]: Stopped verity-setup.service. May 13 23:57:41.597386 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:41.597401 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 13 23:57:41.597413 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 13 23:57:41.597428 systemd[1]: Mounted media.mount - External Media Directory. May 13 23:57:41.597439 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 13 23:57:41.597451 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 13 23:57:41.597463 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 13 23:57:41.597474 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:57:41.597487 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:57:41.597498 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:57:41.597510 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:57:41.597522 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:57:41.597536 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:57:41.597548 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:57:41.597559 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 13 23:57:41.597571 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 23:57:41.597584 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:57:41.597595 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 13 23:57:41.597607 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 13 23:57:41.597620 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 13 23:57:41.597631 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:57:41.597645 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 13 23:57:41.597658 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:57:41.597669 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 13 23:57:41.597681 kernel: loop: module loaded May 13 23:57:41.597694 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 13 23:57:41.597705 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 23:57:41.600356 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 13 23:57:41.600653 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:57:41.600678 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:57:41.600711 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 13 23:57:41.602055 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 13 23:57:41.602073 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:57:41.602086 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 13 23:57:41.602098 systemd[1]: Reached target network-pre.target - Preparation for Network. May 13 23:57:41.602111 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 13 23:57:41.602123 kernel: ACPI: bus type drm_connector registered May 13 23:57:41.602136 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:57:41.602348 kernel: loop0: detected capacity change from 0 to 205544 May 13 23:57:41.602365 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 13 23:57:41.602377 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:57:41.602388 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:57:41.602411 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 13 23:57:41.602423 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 13 23:57:41.602434 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 23:57:41.602445 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 13 23:57:41.602457 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 13 23:57:41.605296 systemd-journald[1109]: Collecting audit messages is disabled. May 13 23:57:41.605333 kernel: fuse: init (API version 7.39) May 13 23:57:41.605346 kernel: loop1: detected capacity change from 0 to 8 May 13 23:57:41.605359 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 13 23:57:41.606847 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 23:57:41.606874 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 13 23:57:41.606887 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 13 23:57:41.606904 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 13 23:57:41.606917 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 13 23:57:41.606929 kernel: loop2: detected capacity change from 0 to 151640 May 13 23:57:41.606960 systemd-journald[1109]: Journal started May 13 23:57:41.606986 systemd-journald[1109]: Runtime Journal (/run/log/journal/21f6e900c99646abb2a73efeedbdedd5) is 4.9M, max 39.3M, 34.3M free. May 13 23:57:41.098317 systemd[1]: Queued start job for default target multi-user.target. May 13 23:57:41.109162 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 13 23:57:41.615626 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:57:41.109573 systemd[1]: systemd-journald.service: Deactivated successfully. May 13 23:57:41.611421 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:57:41.618994 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 13 23:57:41.622829 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 13 23:57:41.656142 systemd-journald[1109]: Time spent on flushing to /var/log/journal/21f6e900c99646abb2a73efeedbdedd5 is 31.204ms for 993 entries. May 13 23:57:41.656142 systemd-journald[1109]: System Journal (/var/log/journal/21f6e900c99646abb2a73efeedbdedd5) is 8M, max 195.6M, 187.6M free. May 13 23:57:41.700110 systemd-journald[1109]: Received client request to flush runtime journal. May 13 23:57:41.700607 kernel: loop3: detected capacity change from 0 to 109808 May 13 23:57:41.663292 udevadm[1141]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 13 23:57:41.703368 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 13 23:57:41.711741 kernel: loop4: detected capacity change from 0 to 205544 May 13 23:57:41.712230 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 13 23:57:41.724771 kernel: loop5: detected capacity change from 0 to 8 May 13 23:57:41.728749 kernel: loop6: detected capacity change from 0 to 151640 May 13 23:57:41.742390 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:57:41.746801 kernel: loop7: detected capacity change from 0 to 109808 May 13 23:57:41.759215 (sd-merge)[1180]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. May 13 23:57:41.759893 (sd-merge)[1180]: Merged extensions into '/usr'. May 13 23:57:41.772708 systemd[1]: Reload requested from client PID 1130 ('systemd-sysext') (unit systemd-sysext.service)... May 13 23:57:41.772742 systemd[1]: Reloading... May 13 23:57:41.798001 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. May 13 23:57:41.798025 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. May 13 23:57:41.945761 zram_generator::config[1210]: No configuration found. May 13 23:57:42.055891 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 23:57:42.134094 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 23:57:42.134171 systemd[1]: Reloading finished in 361 ms. May 13 23:57:42.150524 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:57:42.155177 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 13 23:57:42.165977 systemd[1]: Starting ensure-sysext.service... May 13 23:57:42.167912 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:57:42.193052 ldconfig[1124]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 23:57:42.196598 systemd[1]: Reload requested from client PID 1259 ('systemctl') (unit ensure-sysext.service)... May 13 23:57:42.196668 systemd[1]: Reloading... May 13 23:57:42.235784 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 23:57:42.235998 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 13 23:57:42.236710 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 23:57:42.236949 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. May 13 23:57:42.237009 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. May 13 23:57:42.244337 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:57:42.244351 systemd-tmpfiles[1260]: Skipping /boot May 13 23:57:42.270577 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:57:42.270590 systemd-tmpfiles[1260]: Skipping /boot May 13 23:57:42.313745 zram_generator::config[1290]: No configuration found. May 13 23:57:42.447777 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 23:57:42.512181 systemd[1]: Reloading finished in 315 ms. May 13 23:57:42.528552 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 13 23:57:42.529453 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 13 23:57:42.535850 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:57:42.543883 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:57:42.547398 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 13 23:57:42.552872 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 13 23:57:42.560921 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:57:42.565229 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:57:42.569013 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 13 23:57:42.576360 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.576528 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:57:42.578054 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:57:42.589052 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:57:42.594068 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:57:42.594692 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:57:42.594813 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:57:42.594913 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.598932 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.599094 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:57:42.599239 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:57:42.599320 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:57:42.617024 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 13 23:57:42.618027 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.619613 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 13 23:57:42.621158 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:57:42.621931 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:57:42.638246 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.638538 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:57:42.642987 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:57:42.646589 systemd-udevd[1340]: Using default interface naming scheme 'v255'. May 13 23:57:42.650804 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:57:42.651460 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:57:42.651590 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:57:42.656029 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 13 23:57:42.656571 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.661144 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 13 23:57:42.662684 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 13 23:57:42.663964 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:57:42.664884 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:57:42.665897 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:57:42.666085 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:57:42.670037 systemd[1]: Finished ensure-sysext.service. May 13 23:57:42.670849 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:57:42.671016 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:57:42.674108 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:57:42.674371 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:57:42.684290 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:57:42.684381 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:57:42.689953 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 13 23:57:42.690528 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 23:57:42.698628 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 13 23:57:42.704041 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:57:42.709421 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:57:42.719506 augenrules[1385]: No rules May 13 23:57:42.720811 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:57:42.721064 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:57:42.736705 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 13 23:57:42.849854 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 13 23:57:42.856937 systemd[1]: Condition check resulted in dev-disk-by\x2dlabel-config\x2d2.device - /dev/disk/by-label/config-2 being skipped. May 13 23:57:42.858921 systemd-resolved[1339]: Positive Trust Anchors: May 13 23:57:42.859175 systemd-resolved[1339]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:57:42.859291 systemd-resolved[1339]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 13 23:57:42.865896 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... May 13 23:57:42.867120 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.867246 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:57:42.868381 systemd-resolved[1339]: Using system hostname 'ci-4284.0.0-n-b1a48b09a9'. May 13 23:57:42.868961 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:57:42.874678 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:57:42.881937 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:57:42.882733 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:57:42.882770 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 13 23:57:42.882798 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 13 23:57:42.882812 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 13 23:57:42.882990 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:57:42.883948 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:57:42.895596 systemd-networkd[1383]: lo: Link UP May 13 23:57:42.895608 systemd-networkd[1383]: lo: Gained carrier May 13 23:57:42.896630 systemd-networkd[1383]: Enumeration completed May 13 23:57:42.896783 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:57:42.897350 systemd[1]: Reached target network.target - Network. May 13 23:57:42.899339 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 13 23:57:42.901586 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 13 23:57:42.912998 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 13 23:57:42.913591 systemd[1]: Reached target time-set.target - System Time Set. May 13 23:57:42.934370 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:57:42.934564 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:57:42.939910 systemd-networkd[1383]: eth0: Configuring with /run/systemd/network/10-92:77:64:2d:c5:8a.network. May 13 23:57:42.940019 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:57:42.940208 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:57:42.942614 systemd-networkd[1383]: eth1: Configuring with /run/systemd/network/10-ae:a6:56:1f:8d:6f.network. May 13 23:57:42.943472 systemd-networkd[1383]: eth0: Link UP May 13 23:57:42.943479 systemd-networkd[1383]: eth0: Gained carrier May 13 23:57:42.945396 systemd-networkd[1383]: eth1: Link UP May 13 23:57:42.945398 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:57:42.945508 systemd-networkd[1383]: eth1: Gained carrier May 13 23:57:42.945773 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:57:42.949126 systemd-timesyncd[1375]: Network configuration changed, trying to establish connection. May 13 23:57:42.951096 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:57:42.951143 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:57:42.957847 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 13 23:57:42.962788 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1398) May 13 23:57:42.969057 kernel: ISO 9660 Extensions: RRIP_1991A May 13 23:57:42.971460 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. May 13 23:57:42.990810 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 May 13 23:57:43.010873 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 May 13 23:57:43.015869 kernel: ACPI: button: Power Button [PWRF] May 13 23:57:43.034744 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 May 13 23:57:43.041045 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:57:43.042913 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 13 23:57:43.072160 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 13 23:57:43.101936 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:43.137753 kernel: mousedev: PS/2 mouse device common for all mice May 13 23:57:43.160736 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 May 13 23:57:43.160810 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console May 13 23:57:43.175314 kernel: Console: switching to colour dummy device 80x25 May 13 23:57:43.175398 kernel: [drm] features: -virgl +edid -resource_blob -host_visible May 13 23:57:43.175414 kernel: [drm] features: -context_init May 13 23:57:43.175426 kernel: [drm] number of scanouts: 1 May 13 23:57:43.175439 kernel: [drm] number of cap sets: 0 May 13 23:57:43.178901 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 May 13 23:57:43.864395 systemd-timesyncd[1375]: Contacted time server 102.129.185.135:123 (0.flatcar.pool.ntp.org). May 13 23:57:43.865960 systemd-timesyncd[1375]: Initial clock synchronization to Tue 2025-05-13 23:57:43.864182 UTC. May 13 23:57:43.890981 systemd-resolved[1339]: Clock change detected. Flushing caches. May 13 23:57:43.901097 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:57:43.901407 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:43.903225 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:57:43.907624 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device May 13 23:57:43.907674 kernel: Console: switching to colour frame buffer device 128x48 May 13 23:57:43.908200 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:43.911533 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device May 13 23:57:43.933075 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:57:43.933322 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:43.939454 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:57:43.948789 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:57:43.960912 kernel: EDAC MC: Ver: 3.0.0 May 13 23:57:43.983242 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 13 23:57:43.984643 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 13 23:57:44.013051 lvm[1453]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:57:44.024599 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:57:44.049874 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 13 23:57:44.050760 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:57:44.050914 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:57:44.051078 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 13 23:57:44.051274 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 13 23:57:44.052872 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 13 23:57:44.053529 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 13 23:57:44.053609 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 13 23:57:44.053681 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 13 23:57:44.053713 systemd[1]: Reached target paths.target - Path Units. May 13 23:57:44.053761 systemd[1]: Reached target timers.target - Timer Units. May 13 23:57:44.055096 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 13 23:57:44.056542 systemd[1]: Starting docker.socket - Docker Socket for the API... May 13 23:57:44.059762 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 13 23:57:44.061141 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 13 23:57:44.064364 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 13 23:57:44.071529 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 13 23:57:44.072408 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 13 23:57:44.074389 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 13 23:57:44.077521 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 13 23:57:44.078347 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:57:44.079332 systemd[1]: Reached target basic.target - Basic System. May 13 23:57:44.079779 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 13 23:57:44.079803 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 13 23:57:44.091993 systemd[1]: Starting containerd.service - containerd container runtime... May 13 23:57:44.094293 lvm[1460]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:57:44.095156 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 13 23:57:44.100023 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 13 23:57:44.107593 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 13 23:57:44.113847 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 13 23:57:44.115422 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 13 23:57:44.120508 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 13 23:57:44.128072 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 13 23:57:44.131328 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 13 23:57:44.144126 systemd[1]: Starting systemd-logind.service - User Login Management... May 13 23:57:44.145437 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 23:57:44.150945 jq[1464]: false May 13 23:57:44.153272 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 13 23:57:44.156339 systemd[1]: Starting update-engine.service - Update Engine... May 13 23:57:44.166018 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 13 23:57:44.168954 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 13 23:57:44.172115 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 23:57:44.172320 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 13 23:57:44.172597 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 23:57:44.172812 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 13 23:57:44.179366 coreos-metadata[1462]: May 13 23:57:44.178 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 May 13 23:57:44.184826 dbus-daemon[1463]: [system] SELinux support is enabled May 13 23:57:44.185259 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 13 23:57:44.189570 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 13 23:57:44.189602 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 13 23:57:44.192569 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 13 23:57:44.192638 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). May 13 23:57:44.192653 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 13 23:57:44.197012 coreos-metadata[1462]: May 13 23:57:44.193 INFO Fetch successful May 13 23:57:44.204843 extend-filesystems[1467]: Found loop4 May 13 23:57:44.204843 extend-filesystems[1467]: Found loop5 May 13 23:57:44.204843 extend-filesystems[1467]: Found loop6 May 13 23:57:44.204843 extend-filesystems[1467]: Found loop7 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda May 13 23:57:44.204843 extend-filesystems[1467]: Found vda1 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda2 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda3 May 13 23:57:44.204843 extend-filesystems[1467]: Found usr May 13 23:57:44.204843 extend-filesystems[1467]: Found vda4 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda6 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda7 May 13 23:57:44.204843 extend-filesystems[1467]: Found vda9 May 13 23:57:44.204843 extend-filesystems[1467]: Checking size of /dev/vda9 May 13 23:57:44.263462 jq[1474]: true May 13 23:57:44.263575 update_engine[1473]: I20250513 23:57:44.254630 1473 main.cc:92] Flatcar Update Engine starting May 13 23:57:44.230186 systemd[1]: motdgen.service: Deactivated successfully. May 13 23:57:44.270339 update_engine[1473]: I20250513 23:57:44.268298 1473 update_check_scheduler.cc:74] Next update check in 6m45s May 13 23:57:44.230417 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 13 23:57:44.230829 (ntainerd)[1488]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 13 23:57:44.265006 systemd[1]: Started update-engine.service - Update Engine. May 13 23:57:44.281419 extend-filesystems[1467]: Resized partition /dev/vda9 May 13 23:57:44.282627 jq[1492]: true May 13 23:57:44.285206 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 13 23:57:44.293874 extend-filesystems[1501]: resize2fs 1.47.2 (1-Jan-2025) May 13 23:57:44.309137 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks May 13 23:57:44.321346 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 13 23:57:44.322544 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 13 23:57:44.392613 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1384) May 13 23:57:44.404468 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 13 23:57:44.452969 kernel: EXT4-fs (vda9): resized filesystem to 15121403 May 13 23:57:44.461655 systemd-logind[1471]: New seat seat0. May 13 23:57:44.493158 sshd_keygen[1493]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 23:57:44.495375 systemd-logind[1471]: Watching system buttons on /dev/input/event1 (Power Button) May 13 23:57:44.495416 systemd-logind[1471]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 13 23:57:44.495645 systemd[1]: Started systemd-logind.service - User Login Management. May 13 23:57:44.498806 extend-filesystems[1501]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 13 23:57:44.498806 extend-filesystems[1501]: old_desc_blocks = 1, new_desc_blocks = 8 May 13 23:57:44.498806 extend-filesystems[1501]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. May 13 23:57:44.508546 extend-filesystems[1467]: Resized filesystem in /dev/vda9 May 13 23:57:44.508546 extend-filesystems[1467]: Found vdb May 13 23:57:44.499791 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 23:57:44.500021 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 13 23:57:44.516738 locksmithd[1499]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 23:57:44.518346 bash[1525]: Updated "/home/core/.ssh/authorized_keys" May 13 23:57:44.521684 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 13 23:57:44.529984 systemd[1]: Starting sshkeys.service... May 13 23:57:44.540712 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 13 23:57:44.545585 systemd[1]: Starting issuegen.service - Generate /run/issue... May 13 23:57:44.551168 systemd[1]: Started sshd@0-64.23.143.183:22-147.75.109.163:57734.service - OpenSSH per-connection server daemon (147.75.109.163:57734). May 13 23:57:44.571638 systemd[1]: issuegen.service: Deactivated successfully. May 13 23:57:44.571850 systemd[1]: Finished issuegen.service - Generate /run/issue. May 13 23:57:44.581407 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 13 23:57:44.585195 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 13 23:57:44.589135 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 13 23:57:44.618710 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 13 23:57:44.627418 systemd[1]: Started getty@tty1.service - Getty on tty1. May 13 23:57:44.631291 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 13 23:57:44.632390 systemd[1]: Reached target getty.target - Login Prompts. May 13 23:57:44.648319 coreos-metadata[1550]: May 13 23:57:44.648 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 May 13 23:57:44.674079 coreos-metadata[1550]: May 13 23:57:44.673 INFO Fetch successful May 13 23:57:44.678647 unknown[1550]: wrote ssh authorized keys file for user: core May 13 23:57:44.719245 update-ssh-keys[1559]: Updated "/home/core/.ssh/authorized_keys" May 13 23:57:44.720648 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 13 23:57:44.722135 sshd[1545]: Accepted publickey for core from 147.75.109.163 port 57734 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:44.723725 sshd-session[1545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:44.725186 systemd[1]: Finished sshkeys.service. May 13 23:57:44.734512 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 13 23:57:44.738216 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 13 23:57:44.747268 systemd-logind[1471]: New session 1 of user core. May 13 23:57:44.752112 containerd[1488]: time="2025-05-13T23:57:44Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 13 23:57:44.754907 containerd[1488]: time="2025-05-13T23:57:44.753389800Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 May 13 23:57:44.763856 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 13 23:57:44.767542 containerd[1488]: time="2025-05-13T23:57:44.767505516Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.387µs" May 13 23:57:44.767623 containerd[1488]: time="2025-05-13T23:57:44.767608756Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 13 23:57:44.767671 containerd[1488]: time="2025-05-13T23:57:44.767660889Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 13 23:57:44.767886 containerd[1488]: time="2025-05-13T23:57:44.767870061Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 13 23:57:44.767986 containerd[1488]: time="2025-05-13T23:57:44.767970630Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 13 23:57:44.768048 containerd[1488]: time="2025-05-13T23:57:44.768038065Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:57:44.768177 containerd[1488]: time="2025-05-13T23:57:44.768135601Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:57:44.768224 containerd[1488]: time="2025-05-13T23:57:44.768214624Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:57:44.768521 containerd[1488]: time="2025-05-13T23:57:44.768500476Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:57:44.768609 containerd[1488]: time="2025-05-13T23:57:44.768592977Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:57:44.768656 containerd[1488]: time="2025-05-13T23:57:44.768646137Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:57:44.768693 containerd[1488]: time="2025-05-13T23:57:44.768685018Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 13 23:57:44.768840 containerd[1488]: time="2025-05-13T23:57:44.768823890Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 13 23:57:44.769116 containerd[1488]: time="2025-05-13T23:57:44.769097195Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:57:44.769294 containerd[1488]: time="2025-05-13T23:57:44.769275598Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:57:44.769347 containerd[1488]: time="2025-05-13T23:57:44.769337894Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 13 23:57:44.769414 containerd[1488]: time="2025-05-13T23:57:44.769402891Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 13 23:57:44.769750 containerd[1488]: time="2025-05-13T23:57:44.769733726Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 13 23:57:44.769872 containerd[1488]: time="2025-05-13T23:57:44.769859770Z" level=info msg="metadata content store policy set" policy=shared May 13 23:57:44.770164 systemd[1]: Starting user@500.service - User Manager for UID 500... May 13 23:57:44.774965 containerd[1488]: time="2025-05-13T23:57:44.774937013Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 13 23:57:44.775064 containerd[1488]: time="2025-05-13T23:57:44.775051053Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 13 23:57:44.775193 containerd[1488]: time="2025-05-13T23:57:44.775176804Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 13 23:57:44.775282 containerd[1488]: time="2025-05-13T23:57:44.775266110Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 13 23:57:44.775332 containerd[1488]: time="2025-05-13T23:57:44.775322581Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 13 23:57:44.775374 containerd[1488]: time="2025-05-13T23:57:44.775364921Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 13 23:57:44.775431 containerd[1488]: time="2025-05-13T23:57:44.775420154Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 13 23:57:44.775474 containerd[1488]: time="2025-05-13T23:57:44.775464674Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 13 23:57:44.775523 containerd[1488]: time="2025-05-13T23:57:44.775512443Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 13 23:57:44.775594 containerd[1488]: time="2025-05-13T23:57:44.775582712Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 13 23:57:44.775645 containerd[1488]: time="2025-05-13T23:57:44.775634959Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 13 23:57:44.775689 containerd[1488]: time="2025-05-13T23:57:44.775680026Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 13 23:57:44.775840 containerd[1488]: time="2025-05-13T23:57:44.775824633Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 13 23:57:44.775919 containerd[1488]: time="2025-05-13T23:57:44.775907999Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.775969386Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.775986461Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.775998254Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776008734Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776020650Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776056338Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776072518Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776084302Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776094821Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776156827Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 13 23:57:44.776210 containerd[1488]: time="2025-05-13T23:57:44.776182221Z" level=info msg="Start snapshots syncer" May 13 23:57:44.777760 containerd[1488]: time="2025-05-13T23:57:44.777720570Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 13 23:57:44.778239 containerd[1488]: time="2025-05-13T23:57:44.778125784Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 13 23:57:44.778239 containerd[1488]: time="2025-05-13T23:57:44.778189577Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778482287Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778624729Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778663445Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778692428Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778706714Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778727058Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778738706Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778750212Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778774917Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778787480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 13 23:57:44.779245 containerd[1488]: time="2025-05-13T23:57:44.778797457Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 13 23:57:44.780053 containerd[1488]: time="2025-05-13T23:57:44.779939816Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:57:44.780092 containerd[1488]: time="2025-05-13T23:57:44.780057175Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:57:44.780092 containerd[1488]: time="2025-05-13T23:57:44.780072213Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:57:44.780092 containerd[1488]: time="2025-05-13T23:57:44.780083159Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780092506Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780106028Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780118329Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780136225Z" level=info msg="runtime interface created" May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780142374Z" level=info msg="created NRI interface" May 13 23:57:44.780161 containerd[1488]: time="2025-05-13T23:57:44.780151459Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 13 23:57:44.780316 containerd[1488]: time="2025-05-13T23:57:44.780165697Z" level=info msg="Connect containerd service" May 13 23:57:44.780316 containerd[1488]: time="2025-05-13T23:57:44.780197413Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 13 23:57:44.781244 containerd[1488]: time="2025-05-13T23:57:44.781143554Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 13 23:57:44.790808 (systemd)[1567]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 23:57:44.793704 systemd-logind[1471]: New session c1 of user core. May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929319468Z" level=info msg="Start subscribing containerd event" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929376099Z" level=info msg="Start recovering state" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929478911Z" level=info msg="Start event monitor" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929493814Z" level=info msg="Start cni network conf syncer for default" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929508406Z" level=info msg="Start streaming server" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929517706Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929525811Z" level=info msg="runtime interface starting up..." May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929534154Z" level=info msg="starting plugins..." May 13 23:57:44.929988 containerd[1488]: time="2025-05-13T23:57:44.929547556Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 13 23:57:44.930277 containerd[1488]: time="2025-05-13T23:57:44.930033376Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 23:57:44.930277 containerd[1488]: time="2025-05-13T23:57:44.930078517Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 23:57:44.930233 systemd[1]: Started containerd.service - containerd container runtime. May 13 23:57:44.931602 containerd[1488]: time="2025-05-13T23:57:44.931566503Z" level=info msg="containerd successfully booted in 0.180165s" May 13 23:57:44.942818 systemd[1567]: Queued start job for default target default.target. May 13 23:57:44.950036 systemd[1567]: Created slice app.slice - User Application Slice. May 13 23:57:44.950184 systemd[1567]: Reached target paths.target - Paths. May 13 23:57:44.950281 systemd[1567]: Reached target timers.target - Timers. May 13 23:57:44.951662 systemd[1567]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:57:44.963309 systemd[1567]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:57:44.963429 systemd[1567]: Reached target sockets.target - Sockets. May 13 23:57:44.963481 systemd[1567]: Reached target basic.target - Basic System. May 13 23:57:44.963529 systemd[1567]: Reached target default.target - Main User Target. May 13 23:57:44.963557 systemd[1567]: Startup finished in 161ms. May 13 23:57:44.963756 systemd[1]: Started user@500.service - User Manager for UID 500. May 13 23:57:44.976048 systemd[1]: Started session-1.scope - Session 1 of User core. May 13 23:57:45.039264 systemd[1]: Started sshd@1-64.23.143.183:22-147.75.109.163:57748.service - OpenSSH per-connection server daemon (147.75.109.163:57748). May 13 23:57:45.092819 sshd[1589]: Accepted publickey for core from 147.75.109.163 port 57748 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:45.094267 sshd-session[1589]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:45.099607 systemd-logind[1471]: New session 2 of user core. May 13 23:57:45.101042 systemd-networkd[1383]: eth0: Gained IPv6LL May 13 23:57:45.101624 systemd-networkd[1383]: eth1: Gained IPv6LL May 13 23:57:45.105054 systemd[1]: Started session-2.scope - Session 2 of User core. May 13 23:57:45.106625 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 13 23:57:45.109708 systemd[1]: Reached target network-online.target - Network is Online. May 13 23:57:45.113159 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:57:45.118121 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 13 23:57:45.144983 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 13 23:57:45.179378 sshd[1594]: Connection closed by 147.75.109.163 port 57748 May 13 23:57:45.179996 sshd-session[1589]: pam_unix(sshd:session): session closed for user core May 13 23:57:45.190576 systemd[1]: sshd@1-64.23.143.183:22-147.75.109.163:57748.service: Deactivated successfully. May 13 23:57:45.192705 systemd[1]: session-2.scope: Deactivated successfully. May 13 23:57:45.195285 systemd-logind[1471]: Session 2 logged out. Waiting for processes to exit. May 13 23:57:45.198247 systemd[1]: Started sshd@2-64.23.143.183:22-147.75.109.163:57760.service - OpenSSH per-connection server daemon (147.75.109.163:57760). May 13 23:57:45.203949 systemd-logind[1471]: Removed session 2. May 13 23:57:45.250027 sshd[1608]: Accepted publickey for core from 147.75.109.163 port 57760 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:45.251269 sshd-session[1608]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:45.256025 systemd-logind[1471]: New session 3 of user core. May 13 23:57:45.262033 systemd[1]: Started session-3.scope - Session 3 of User core. May 13 23:57:45.326663 sshd[1611]: Connection closed by 147.75.109.163 port 57760 May 13 23:57:45.327083 sshd-session[1608]: pam_unix(sshd:session): session closed for user core May 13 23:57:45.330169 systemd[1]: sshd@2-64.23.143.183:22-147.75.109.163:57760.service: Deactivated successfully. May 13 23:57:45.332741 systemd[1]: session-3.scope: Deactivated successfully. May 13 23:57:45.334627 systemd-logind[1471]: Session 3 logged out. Waiting for processes to exit. May 13 23:57:45.335777 systemd-logind[1471]: Removed session 3. May 13 23:57:45.993259 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:57:45.994160 systemd[1]: Reached target multi-user.target - Multi-User System. May 13 23:57:45.995696 systemd[1]: Startup finished in 1.062s (kernel) + 4.764s (initrd) + 4.853s (userspace) = 10.680s. May 13 23:57:46.003310 (kubelet)[1621]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 13 23:57:46.573143 kubelet[1621]: E0513 23:57:46.573073 1621 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 13 23:57:46.575650 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 13 23:57:46.575807 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 13 23:57:46.576162 systemd[1]: kubelet.service: Consumed 1.204s CPU time, 236.2M memory peak. May 13 23:57:55.340363 systemd[1]: Started sshd@3-64.23.143.183:22-147.75.109.163:50470.service - OpenSSH per-connection server daemon (147.75.109.163:50470). May 13 23:57:55.394619 sshd[1634]: Accepted publickey for core from 147.75.109.163 port 50470 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:55.396243 sshd-session[1634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:55.400972 systemd-logind[1471]: New session 4 of user core. May 13 23:57:55.408038 systemd[1]: Started session-4.scope - Session 4 of User core. May 13 23:57:55.467509 sshd[1636]: Connection closed by 147.75.109.163 port 50470 May 13 23:57:55.468066 sshd-session[1634]: pam_unix(sshd:session): session closed for user core May 13 23:57:55.481085 systemd[1]: sshd@3-64.23.143.183:22-147.75.109.163:50470.service: Deactivated successfully. May 13 23:57:55.482910 systemd[1]: session-4.scope: Deactivated successfully. May 13 23:57:55.485057 systemd-logind[1471]: Session 4 logged out. Waiting for processes to exit. May 13 23:57:55.486140 systemd[1]: Started sshd@4-64.23.143.183:22-147.75.109.163:50482.service - OpenSSH per-connection server daemon (147.75.109.163:50482). May 13 23:57:55.487265 systemd-logind[1471]: Removed session 4. May 13 23:57:55.538172 sshd[1641]: Accepted publickey for core from 147.75.109.163 port 50482 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:55.539398 sshd-session[1641]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:55.544067 systemd-logind[1471]: New session 5 of user core. May 13 23:57:55.551021 systemd[1]: Started session-5.scope - Session 5 of User core. May 13 23:57:55.606699 sshd[1644]: Connection closed by 147.75.109.163 port 50482 May 13 23:57:55.607270 sshd-session[1641]: pam_unix(sshd:session): session closed for user core May 13 23:57:55.621324 systemd[1]: sshd@4-64.23.143.183:22-147.75.109.163:50482.service: Deactivated successfully. May 13 23:57:55.623864 systemd[1]: session-5.scope: Deactivated successfully. May 13 23:57:55.625370 systemd-logind[1471]: Session 5 logged out. Waiting for processes to exit. May 13 23:57:55.627302 systemd[1]: Started sshd@5-64.23.143.183:22-147.75.109.163:50494.service - OpenSSH per-connection server daemon (147.75.109.163:50494). May 13 23:57:55.629271 systemd-logind[1471]: Removed session 5. May 13 23:57:55.673288 sshd[1649]: Accepted publickey for core from 147.75.109.163 port 50494 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:55.674589 sshd-session[1649]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:55.679185 systemd-logind[1471]: New session 6 of user core. May 13 23:57:55.690059 systemd[1]: Started session-6.scope - Session 6 of User core. May 13 23:57:55.747556 sshd[1652]: Connection closed by 147.75.109.163 port 50494 May 13 23:57:55.748115 sshd-session[1649]: pam_unix(sshd:session): session closed for user core May 13 23:57:55.759406 systemd[1]: sshd@5-64.23.143.183:22-147.75.109.163:50494.service: Deactivated successfully. May 13 23:57:55.761093 systemd[1]: session-6.scope: Deactivated successfully. May 13 23:57:55.763069 systemd-logind[1471]: Session 6 logged out. Waiting for processes to exit. May 13 23:57:55.764146 systemd[1]: Started sshd@6-64.23.143.183:22-147.75.109.163:50504.service - OpenSSH per-connection server daemon (147.75.109.163:50504). May 13 23:57:55.765239 systemd-logind[1471]: Removed session 6. May 13 23:57:55.818506 sshd[1657]: Accepted publickey for core from 147.75.109.163 port 50504 ssh2: RSA SHA256:bC78CM2YHyER82uuK7NAX7heS0tcdIEHhEXL2ubzJPc May 13 23:57:55.820187 sshd-session[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:57:55.826051 systemd-logind[1471]: New session 7 of user core. May 13 23:57:55.833111 systemd[1]: Started session-7.scope - Session 7 of User core. May 13 23:57:55.898041 sudo[1661]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 13 23:57:55.898321 sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:57:56.449219 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:57:56.449473 systemd[1]: kubelet.service: Consumed 1.204s CPU time, 236.2M memory peak. May 13 23:57:56.451522 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:57:56.488062 systemd[1]: Reload requested from client PID 1693 ('systemctl') (unit session-7.scope)... May 13 23:57:56.488201 systemd[1]: Reloading... May 13 23:57:56.620926 zram_generator::config[1739]: No configuration found. May 13 23:57:56.728944 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 13 23:57:56.825476 systemd[1]: Reloading finished in 336 ms. May 13 23:57:56.889823 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:57:56.893414 systemd[1]: kubelet.service: Deactivated successfully. May 13 23:57:56.893622 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:57:56.893675 systemd[1]: kubelet.service: Consumed 99ms CPU time, 83.6M memory peak. May 13 23:57:56.895155 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:57:57.022556 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:57:57.031073 (kubelet)[1792]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 13 23:57:57.075735 kubelet[1792]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 13 23:57:57.075735 kubelet[1792]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 13 23:57:57.075735 kubelet[1792]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 13 23:57:57.076879 kubelet[1792]: I0513 23:57:57.076829 1792 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 13 23:57:57.769485 kubelet[1792]: I0513 23:57:57.769444 1792 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 13 23:57:57.769713 kubelet[1792]: I0513 23:57:57.769630 1792 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 13 23:57:57.769960 kubelet[1792]: I0513 23:57:57.769942 1792 server.go:929] "Client rotation is on, will bootstrap in background" May 13 23:57:57.792258 kubelet[1792]: I0513 23:57:57.791838 1792 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 13 23:57:57.804818 kubelet[1792]: I0513 23:57:57.804793 1792 server.go:1426] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 13 23:57:57.813166 kubelet[1792]: I0513 23:57:57.813139 1792 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 13 23:57:57.814304 kubelet[1792]: I0513 23:57:57.814282 1792 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 13 23:57:57.814908 kubelet[1792]: I0513 23:57:57.814858 1792 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 13 23:57:57.815145 kubelet[1792]: I0513 23:57:57.814988 1792 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"64.23.143.183","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":"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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 13 23:57:57.815602 kubelet[1792]: I0513 23:57:57.815308 1792 topology_manager.go:138] "Creating topology manager with none policy" May 13 23:57:57.815602 kubelet[1792]: I0513 23:57:57.815329 1792 container_manager_linux.go:300] "Creating device plugin manager" May 13 23:57:57.815602 kubelet[1792]: I0513 23:57:57.815445 1792 state_mem.go:36] "Initialized new in-memory state store" May 13 23:57:57.816717 kubelet[1792]: I0513 23:57:57.816549 1792 kubelet.go:408] "Attempting to sync node with API server" May 13 23:57:57.816717 kubelet[1792]: I0513 23:57:57.816568 1792 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 13 23:57:57.816717 kubelet[1792]: I0513 23:57:57.816599 1792 kubelet.go:314] "Adding apiserver pod source" May 13 23:57:57.816717 kubelet[1792]: I0513 23:57:57.816615 1792 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 13 23:57:57.818084 kubelet[1792]: E0513 23:57:57.817022 1792 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:57:57.818084 kubelet[1792]: E0513 23:57:57.817062 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:57:57.820962 kubelet[1792]: I0513 23:57:57.820913 1792 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" May 13 23:57:57.823249 kubelet[1792]: I0513 23:57:57.823231 1792 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 13 23:57:57.823809 kubelet[1792]: W0513 23:57:57.823779 1792 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 13 23:57:57.824656 kubelet[1792]: I0513 23:57:57.824378 1792 server.go:1269] "Started kubelet" May 13 23:57:57.828693 kubelet[1792]: I0513 23:57:57.828661 1792 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 13 23:57:57.829783 kubelet[1792]: I0513 23:57:57.829620 1792 server.go:460] "Adding debug handlers to kubelet server" May 13 23:57:57.830914 kubelet[1792]: I0513 23:57:57.830748 1792 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 13 23:57:57.831082 kubelet[1792]: I0513 23:57:57.831070 1792 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 13 23:57:57.835109 kubelet[1792]: I0513 23:57:57.833355 1792 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 13 23:57:57.835109 kubelet[1792]: I0513 23:57:57.833480 1792 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 13 23:57:57.835747 kubelet[1792]: E0513 23:57:57.835730 1792 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 13 23:57:57.836599 kubelet[1792]: I0513 23:57:57.836586 1792 volume_manager.go:289] "Starting Kubelet Volume Manager" May 13 23:57:57.836743 kubelet[1792]: I0513 23:57:57.836735 1792 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 13 23:57:57.836825 kubelet[1792]: I0513 23:57:57.836818 1792 reconciler.go:26] "Reconciler: start to sync state" May 13 23:57:57.838858 kubelet[1792]: I0513 23:57:57.838839 1792 factory.go:221] Registration of the containerd container factory successfully May 13 23:57:57.838968 kubelet[1792]: I0513 23:57:57.838960 1792 factory.go:221] Registration of the systemd container factory successfully May 13 23:57:57.839101 kubelet[1792]: I0513 23:57:57.839080 1792 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory May 13 23:57:57.847599 kubelet[1792]: E0513 23:57:57.847552 1792 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.143.183\" not found" May 13 23:57:57.853076 kubelet[1792]: E0513 23:57:57.853027 1792 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"64.23.143.183\" not found" node="64.23.143.183" May 13 23:57:57.855503 kubelet[1792]: I0513 23:57:57.855489 1792 cpu_manager.go:214] "Starting CPU manager" policy="none" May 13 23:57:57.855813 kubelet[1792]: I0513 23:57:57.855801 1792 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 13 23:57:57.855979 kubelet[1792]: I0513 23:57:57.855968 1792 state_mem.go:36] "Initialized new in-memory state store" May 13 23:57:57.859183 kubelet[1792]: I0513 23:57:57.859168 1792 policy_none.go:49] "None policy: Start" May 13 23:57:57.860194 kubelet[1792]: I0513 23:57:57.860181 1792 memory_manager.go:170] "Starting memorymanager" policy="None" May 13 23:57:57.860390 kubelet[1792]: I0513 23:57:57.860380 1792 state_mem.go:35] "Initializing new in-memory state store" May 13 23:57:57.872424 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 13 23:57:57.885038 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 13 23:57:57.888645 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 13 23:57:57.893635 kubelet[1792]: I0513 23:57:57.893520 1792 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 13 23:57:57.893696 kubelet[1792]: I0513 23:57:57.893675 1792 eviction_manager.go:189] "Eviction manager: starting control loop" May 13 23:57:57.893735 kubelet[1792]: I0513 23:57:57.893686 1792 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 13 23:57:57.895439 kubelet[1792]: I0513 23:57:57.894344 1792 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 13 23:57:57.897591 kubelet[1792]: E0513 23:57:57.897444 1792 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"64.23.143.183\" not found" May 13 23:57:57.913952 kubelet[1792]: I0513 23:57:57.913920 1792 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 13 23:57:57.917107 kubelet[1792]: I0513 23:57:57.917087 1792 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 13 23:57:57.917208 kubelet[1792]: I0513 23:57:57.917200 1792 status_manager.go:217] "Starting to sync pod status with apiserver" May 13 23:57:57.917336 kubelet[1792]: I0513 23:57:57.917268 1792 kubelet.go:2321] "Starting kubelet main sync loop" May 13 23:57:57.917336 kubelet[1792]: E0513 23:57:57.917315 1792 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 13 23:57:57.995167 kubelet[1792]: I0513 23:57:57.995142 1792 kubelet_node_status.go:72] "Attempting to register node" node="64.23.143.183" May 13 23:57:58.002656 kubelet[1792]: I0513 23:57:58.002468 1792 kubelet_node_status.go:75] "Successfully registered node" node="64.23.143.183" May 13 23:57:58.026016 kubelet[1792]: I0513 23:57:58.024806 1792 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 13 23:57:58.026144 containerd[1488]: time="2025-05-13T23:57:58.026080554Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 13 23:57:58.026442 kubelet[1792]: I0513 23:57:58.026266 1792 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 13 23:57:58.052997 sudo[1661]: pam_unix(sudo:session): session closed for user root May 13 23:57:58.055935 sshd[1660]: Connection closed by 147.75.109.163 port 50504 May 13 23:57:58.056391 sshd-session[1657]: pam_unix(sshd:session): session closed for user core May 13 23:57:58.059792 systemd[1]: sshd@6-64.23.143.183:22-147.75.109.163:50504.service: Deactivated successfully. May 13 23:57:58.062004 systemd[1]: session-7.scope: Deactivated successfully. May 13 23:57:58.062332 systemd[1]: session-7.scope: Consumed 511ms CPU time, 73.4M memory peak. May 13 23:57:58.064478 systemd-logind[1471]: Session 7 logged out. Waiting for processes to exit. May 13 23:57:58.066071 systemd-logind[1471]: Removed session 7. May 13 23:57:58.772416 kubelet[1792]: I0513 23:57:58.772357 1792 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 13 23:57:58.773249 kubelet[1792]: W0513 23:57:58.772560 1792 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 23:57:58.773249 kubelet[1792]: W0513 23:57:58.772592 1792 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 23:57:58.773249 kubelet[1792]: W0513 23:57:58.772613 1792 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 23:57:58.817335 kubelet[1792]: E0513 23:57:58.817274 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:57:58.817335 kubelet[1792]: I0513 23:57:58.817295 1792 apiserver.go:52] "Watching apiserver" May 13 23:57:58.827214 systemd[1]: Created slice kubepods-besteffort-podbd4ed178_2fd8_4371_b530_38291411e0a2.slice - libcontainer container kubepods-besteffort-podbd4ed178_2fd8_4371_b530_38291411e0a2.slice. May 13 23:57:58.835943 systemd[1]: Created slice kubepods-burstable-podcddd0f90_9fae_4f2f_9306_a987564be70c.slice - libcontainer container kubepods-burstable-podcddd0f90_9fae_4f2f_9306_a987564be70c.slice. May 13 23:57:58.837093 kubelet[1792]: I0513 23:57:58.837071 1792 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 13 23:57:58.843786 kubelet[1792]: I0513 23:57:58.843470 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/cddd0f90-9fae-4f2f-9306-a987564be70c-cni-plugin\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843786 kubelet[1792]: I0513 23:57:58.843510 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/cddd0f90-9fae-4f2f-9306-a987564be70c-cni\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843786 kubelet[1792]: I0513 23:57:58.843556 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hgs2k\" (UniqueName: \"kubernetes.io/projected/cddd0f90-9fae-4f2f-9306-a987564be70c-kube-api-access-hgs2k\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843786 kubelet[1792]: I0513 23:57:58.843574 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/bd4ed178-2fd8-4371-b530-38291411e0a2-xtables-lock\") pod \"kube-proxy-mk5bb\" (UID: \"bd4ed178-2fd8-4371-b530-38291411e0a2\") " pod="kube-system/kube-proxy-mk5bb" May 13 23:57:58.843786 kubelet[1792]: I0513 23:57:58.843590 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/bd4ed178-2fd8-4371-b530-38291411e0a2-lib-modules\") pod \"kube-proxy-mk5bb\" (UID: \"bd4ed178-2fd8-4371-b530-38291411e0a2\") " pod="kube-system/kube-proxy-mk5bb" May 13 23:57:58.843977 kubelet[1792]: I0513 23:57:58.843606 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/cddd0f90-9fae-4f2f-9306-a987564be70c-run\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843977 kubelet[1792]: I0513 23:57:58.843620 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/cddd0f90-9fae-4f2f-9306-a987564be70c-flannel-cfg\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843977 kubelet[1792]: I0513 23:57:58.843635 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cddd0f90-9fae-4f2f-9306-a987564be70c-xtables-lock\") pod \"kube-flannel-ds-wd5vp\" (UID: \"cddd0f90-9fae-4f2f-9306-a987564be70c\") " pod="kube-flannel/kube-flannel-ds-wd5vp" May 13 23:57:58.843977 kubelet[1792]: I0513 23:57:58.843663 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/bd4ed178-2fd8-4371-b530-38291411e0a2-kube-proxy\") pod \"kube-proxy-mk5bb\" (UID: \"bd4ed178-2fd8-4371-b530-38291411e0a2\") " pod="kube-system/kube-proxy-mk5bb" May 13 23:57:58.843977 kubelet[1792]: I0513 23:57:58.843677 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7f8l7\" (UniqueName: \"kubernetes.io/projected/bd4ed178-2fd8-4371-b530-38291411e0a2-kube-api-access-7f8l7\") pod \"kube-proxy-mk5bb\" (UID: \"bd4ed178-2fd8-4371-b530-38291411e0a2\") " pod="kube-system/kube-proxy-mk5bb" May 13 23:57:59.135650 kubelet[1792]: E0513 23:57:59.134838 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:57:59.136716 containerd[1488]: time="2025-05-13T23:57:59.136569661Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mk5bb,Uid:bd4ed178-2fd8-4371-b530-38291411e0a2,Namespace:kube-system,Attempt:0,}" May 13 23:57:59.142964 kubelet[1792]: E0513 23:57:59.142541 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:57:59.143372 containerd[1488]: time="2025-05-13T23:57:59.143136891Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-wd5vp,Uid:cddd0f90-9fae-4f2f-9306-a987564be70c,Namespace:kube-flannel,Attempt:0,}" May 13 23:57:59.717636 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount253637777.mount: Deactivated successfully. May 13 23:57:59.725501 containerd[1488]: time="2025-05-13T23:57:59.725450788Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 13 23:57:59.726465 containerd[1488]: time="2025-05-13T23:57:59.726394658Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" May 13 23:57:59.727923 containerd[1488]: time="2025-05-13T23:57:59.727436957Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 13 23:57:59.728387 containerd[1488]: time="2025-05-13T23:57:59.728345984Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 13 23:57:59.728837 containerd[1488]: time="2025-05-13T23:57:59.728812045Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 13 23:57:59.732397 containerd[1488]: time="2025-05-13T23:57:59.732357432Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 580.777332ms" May 13 23:57:59.733642 containerd[1488]: time="2025-05-13T23:57:59.733615182Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 13 23:57:59.736829 containerd[1488]: time="2025-05-13T23:57:59.736791152Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 591.30775ms" May 13 23:57:59.762849 containerd[1488]: time="2025-05-13T23:57:59.762226278Z" level=info msg="connecting to shim 9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496" address="unix:///run/containerd/s/3bce90e4e2e2a25e6be0894c2661841133e0ad328eb9c3db0751ee6d0cce22e1" namespace=k8s.io protocol=ttrpc version=3 May 13 23:57:59.767355 containerd[1488]: time="2025-05-13T23:57:59.767322016Z" level=info msg="connecting to shim 3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c" address="unix:///run/containerd/s/596c11b8d5c52684019591c4eb22e9add3cc61a99181a7582bbe1cc362a7eac4" namespace=k8s.io protocol=ttrpc version=3 May 13 23:57:59.791113 systemd[1]: Started cri-containerd-9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496.scope - libcontainer container 9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496. May 13 23:57:59.816081 systemd[1]: Started cri-containerd-3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c.scope - libcontainer container 3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c. May 13 23:57:59.819103 kubelet[1792]: E0513 23:57:59.817914 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:57:59.867588 containerd[1488]: time="2025-05-13T23:57:59.867340877Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mk5bb,Uid:bd4ed178-2fd8-4371-b530-38291411e0a2,Namespace:kube-system,Attempt:0,} returns sandbox id \"3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c\"" May 13 23:57:59.868763 kubelet[1792]: E0513 23:57:59.868323 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:57:59.870126 containerd[1488]: time="2025-05-13T23:57:59.869866574Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 13 23:57:59.878154 containerd[1488]: time="2025-05-13T23:57:59.878126759Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-wd5vp,Uid:cddd0f90-9fae-4f2f-9306-a987564be70c,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\"" May 13 23:57:59.878869 kubelet[1792]: E0513 23:57:59.878850 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:00.818773 kubelet[1792]: E0513 23:58:00.818710 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:00.861572 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1404817465.mount: Deactivated successfully. May 13 23:58:01.422048 containerd[1488]: time="2025-05-13T23:58:01.422001251Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:01.423388 containerd[1488]: time="2025-05-13T23:58:01.423170209Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.8: active requests=0, bytes read=30354625" May 13 23:58:01.425102 containerd[1488]: time="2025-05-13T23:58:01.425068085Z" level=info msg="ImageCreate event name:\"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:01.428314 containerd[1488]: time="2025-05-13T23:58:01.428284794Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.8\" with image id \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\", repo tag \"registry.k8s.io/kube-proxy:v1.31.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\", size \"30353644\" in 1.558368492s" May 13 23:58:01.428523 containerd[1488]: time="2025-05-13T23:58:01.428408972Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\"" May 13 23:58:01.428523 containerd[1488]: time="2025-05-13T23:58:01.428301736Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:01.430318 containerd[1488]: time="2025-05-13T23:58:01.430119006Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 13 23:58:01.431193 containerd[1488]: time="2025-05-13T23:58:01.431168071Z" level=info msg="CreateContainer within sandbox \"3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 13 23:58:01.442411 containerd[1488]: time="2025-05-13T23:58:01.442373166Z" level=info msg="Container 18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:01.462907 containerd[1488]: time="2025-05-13T23:58:01.462851610Z" level=info msg="CreateContainer within sandbox \"3f8c41ee7d228817618adc095d0bed5f05126576aed5b22748b455085716853c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c\"" May 13 23:58:01.463783 containerd[1488]: time="2025-05-13T23:58:01.463732329Z" level=info msg="StartContainer for \"18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c\"" May 13 23:58:01.465670 containerd[1488]: time="2025-05-13T23:58:01.465603609Z" level=info msg="connecting to shim 18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c" address="unix:///run/containerd/s/596c11b8d5c52684019591c4eb22e9add3cc61a99181a7582bbe1cc362a7eac4" protocol=ttrpc version=3 May 13 23:58:01.495109 systemd[1]: Started cri-containerd-18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c.scope - libcontainer container 18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c. May 13 23:58:01.543699 containerd[1488]: time="2025-05-13T23:58:01.543631978Z" level=info msg="StartContainer for \"18ed453d685bbfa5d0339f8c8bab64ff7ff12d773de71b5eac05eab9b8e56d5c\" returns successfully" May 13 23:58:01.820011 kubelet[1792]: E0513 23:58:01.819964 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:01.931930 kubelet[1792]: E0513 23:58:01.931562 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:02.820208 kubelet[1792]: E0513 23:58:02.820146 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:02.933660 kubelet[1792]: E0513 23:58:02.933624 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:03.432707 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2556068644.mount: Deactivated successfully. May 13 23:58:03.477465 containerd[1488]: time="2025-05-13T23:58:03.476589223Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:03.478483 containerd[1488]: time="2025-05-13T23:58:03.478424680Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" May 13 23:58:03.479270 containerd[1488]: time="2025-05-13T23:58:03.479237313Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:03.481882 containerd[1488]: time="2025-05-13T23:58:03.481856285Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:03.482962 containerd[1488]: time="2025-05-13T23:58:03.482935528Z" 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.052771771s" May 13 23:58:03.483080 containerd[1488]: time="2025-05-13T23:58:03.483064529Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 13 23:58:03.486233 containerd[1488]: time="2025-05-13T23:58:03.485907482Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 13 23:58:03.493866 containerd[1488]: time="2025-05-13T23:58:03.493840141Z" level=info msg="Container e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:03.500213 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4245503774.mount: Deactivated successfully. May 13 23:58:03.508776 containerd[1488]: time="2025-05-13T23:58:03.508668364Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\"" May 13 23:58:03.510512 containerd[1488]: time="2025-05-13T23:58:03.509481251Z" level=info msg="StartContainer for \"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\"" May 13 23:58:03.510512 containerd[1488]: time="2025-05-13T23:58:03.510266016Z" level=info msg="connecting to shim e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4" address="unix:///run/containerd/s/3bce90e4e2e2a25e6be0894c2661841133e0ad328eb9c3db0751ee6d0cce22e1" protocol=ttrpc version=3 May 13 23:58:03.545103 systemd[1]: Started cri-containerd-e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4.scope - libcontainer container e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4. May 13 23:58:03.591227 systemd[1]: cri-containerd-e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4.scope: Deactivated successfully. May 13 23:58:03.593564 containerd[1488]: time="2025-05-13T23:58:03.593511029Z" level=info msg="received exit event container_id:\"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\" id:\"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\" pid:2121 exited_at:{seconds:1747180683 nanos:592307009}" May 13 23:58:03.594549 containerd[1488]: time="2025-05-13T23:58:03.593759304Z" level=info msg="TaskExit event in podsandbox handler container_id:\"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\" id:\"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\" pid:2121 exited_at:{seconds:1747180683 nanos:592307009}" May 13 23:58:03.596403 containerd[1488]: time="2025-05-13T23:58:03.596258083Z" level=info msg="StartContainer for \"e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4\" returns successfully" May 13 23:58:03.820798 kubelet[1792]: E0513 23:58:03.820723 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:03.936622 kubelet[1792]: E0513 23:58:03.936580 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:03.937405 containerd[1488]: time="2025-05-13T23:58:03.937369327Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 13 23:58:03.949968 kubelet[1792]: I0513 23:58:03.949906 1792 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-mk5bb" podStartSLOduration=4.389602292 podStartE2EDuration="5.94987224s" podCreationTimestamp="2025-05-13 23:57:58 +0000 UTC" firstStartedPulling="2025-05-13 23:57:59.869466465 +0000 UTC m=+2.835089406" lastFinishedPulling="2025-05-13 23:58:01.429736403 +0000 UTC m=+4.395359354" observedRunningTime="2025-05-13 23:58:01.944533763 +0000 UTC m=+4.910156716" watchObservedRunningTime="2025-05-13 23:58:03.94987224 +0000 UTC m=+6.915495191" May 13 23:58:04.342055 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e627ae40a3e2c905ea49733518429c7622d01f1b075a03e933dda4445c9274e4-rootfs.mount: Deactivated successfully. May 13 23:58:04.821537 kubelet[1792]: E0513 23:58:04.821470 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:05.325176 systemd-resolved[1339]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. May 13 23:58:05.823116 kubelet[1792]: E0513 23:58:05.822373 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:05.951847 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3463141559.mount: Deactivated successfully. May 13 23:58:06.748024 containerd[1488]: time="2025-05-13T23:58:06.747964957Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:06.749043 containerd[1488]: time="2025-05-13T23:58:06.748987179Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 13 23:58:06.749689 containerd[1488]: time="2025-05-13T23:58:06.749648270Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:06.751921 containerd[1488]: time="2025-05-13T23:58:06.751766586Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:06.753152 containerd[1488]: time="2025-05-13T23:58:06.752610541Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 2.815200503s" May 13 23:58:06.753152 containerd[1488]: time="2025-05-13T23:58:06.752649068Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 13 23:58:06.754668 containerd[1488]: time="2025-05-13T23:58:06.754642103Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 13 23:58:06.765036 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3710356745.mount: Deactivated successfully. May 13 23:58:06.767931 containerd[1488]: time="2025-05-13T23:58:06.766331868Z" level=info msg="Container 3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:06.775622 containerd[1488]: time="2025-05-13T23:58:06.775583923Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\"" May 13 23:58:06.776287 containerd[1488]: time="2025-05-13T23:58:06.776264611Z" level=info msg="StartContainer for \"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\"" May 13 23:58:06.777829 containerd[1488]: time="2025-05-13T23:58:06.777804183Z" level=info msg="connecting to shim 3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63" address="unix:///run/containerd/s/3bce90e4e2e2a25e6be0894c2661841133e0ad328eb9c3db0751ee6d0cce22e1" protocol=ttrpc version=3 May 13 23:58:06.805094 systemd[1]: Started cri-containerd-3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63.scope - libcontainer container 3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63. May 13 23:58:06.823500 kubelet[1792]: E0513 23:58:06.823454 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:06.851371 systemd[1]: cri-containerd-3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63.scope: Deactivated successfully. May 13 23:58:06.854540 containerd[1488]: time="2025-05-13T23:58:06.854313080Z" level=info msg="received exit event container_id:\"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\" id:\"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\" pid:2194 exited_at:{seconds:1747180686 nanos:854023014}" May 13 23:58:06.854789 containerd[1488]: time="2025-05-13T23:58:06.854562401Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\" id:\"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\" pid:2194 exited_at:{seconds:1747180686 nanos:854023014}" May 13 23:58:06.863727 containerd[1488]: time="2025-05-13T23:58:06.862875321Z" level=info msg="StartContainer for \"3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63\" returns successfully" May 13 23:58:06.877382 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3371a9472c4e2c8c194c9fd4d5b8418676e4b904dd89eb4749fc508168230c63-rootfs.mount: Deactivated successfully. May 13 23:58:06.931661 kubelet[1792]: I0513 23:58:06.931446 1792 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 13 23:58:06.943813 kubelet[1792]: E0513 23:58:06.943677 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:06.945421 containerd[1488]: time="2025-05-13T23:58:06.945379578Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 13 23:58:06.955928 containerd[1488]: time="2025-05-13T23:58:06.953321016Z" level=info msg="Container ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:06.962828 containerd[1488]: time="2025-05-13T23:58:06.962768651Z" level=info msg="CreateContainer within sandbox \"9d0b0718c9f737ac679f24de8b74af28cd15712ac4fc90cd4ccbf71ca6da4496\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f\"" May 13 23:58:06.964311 containerd[1488]: time="2025-05-13T23:58:06.963208306Z" level=info msg="StartContainer for \"ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f\"" May 13 23:58:06.964311 containerd[1488]: time="2025-05-13T23:58:06.964022116Z" level=info msg="connecting to shim ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f" address="unix:///run/containerd/s/3bce90e4e2e2a25e6be0894c2661841133e0ad328eb9c3db0751ee6d0cce22e1" protocol=ttrpc version=3 May 13 23:58:06.987079 systemd[1]: Started cri-containerd-ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f.scope - libcontainer container ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f. May 13 23:58:07.018788 containerd[1488]: time="2025-05-13T23:58:07.018669407Z" level=info msg="StartContainer for \"ff6ccc2a603bd1ce2658713fc9b973b1785144f264501e5df52450b7a9a6182f\" returns successfully" May 13 23:58:07.824007 kubelet[1792]: E0513 23:58:07.823937 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:07.947152 kubelet[1792]: E0513 23:58:07.947084 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:08.086188 systemd-networkd[1383]: flannel.1: Link UP May 13 23:58:08.086198 systemd-networkd[1383]: flannel.1: Gained carrier May 13 23:58:08.397193 systemd-resolved[1339]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3. May 13 23:58:08.825168 kubelet[1792]: E0513 23:58:08.825105 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:08.949209 kubelet[1792]: E0513 23:58:08.949114 1792 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" May 13 23:58:09.035543 kubelet[1792]: I0513 23:58:09.035404 1792 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-wd5vp" podStartSLOduration=4.161637827 podStartE2EDuration="11.035371117s" podCreationTimestamp="2025-05-13 23:57:58 +0000 UTC" firstStartedPulling="2025-05-13 23:57:59.879640742 +0000 UTC m=+2.845263672" lastFinishedPulling="2025-05-13 23:58:06.753374033 +0000 UTC m=+9.718996962" observedRunningTime="2025-05-13 23:58:07.960410911 +0000 UTC m=+10.926033866" watchObservedRunningTime="2025-05-13 23:58:09.035371117 +0000 UTC m=+12.000994062" May 13 23:58:09.041675 systemd[1]: Created slice kubepods-besteffort-podbcb02a92_2e5e_4d35_ac05_3d26f8b0762c.slice - libcontainer container kubepods-besteffort-podbcb02a92_2e5e_4d35_ac05_3d26f8b0762c.slice. May 13 23:58:09.217911 kubelet[1792]: I0513 23:58:09.217821 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cx7gg\" (UniqueName: \"kubernetes.io/projected/bcb02a92-2e5e-4d35-ac05-3d26f8b0762c-kube-api-access-cx7gg\") pod \"nginx-deployment-8587fbcb89-ck2bs\" (UID: \"bcb02a92-2e5e-4d35-ac05-3d26f8b0762c\") " pod="default/nginx-deployment-8587fbcb89-ck2bs" May 13 23:58:09.229105 systemd-networkd[1383]: flannel.1: Gained IPv6LL May 13 23:58:09.344928 containerd[1488]: time="2025-05-13T23:58:09.344819190Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-ck2bs,Uid:bcb02a92-2e5e-4d35-ac05-3d26f8b0762c,Namespace:default,Attempt:0,}" May 13 23:58:09.359074 systemd-networkd[1383]: cni0: Link UP May 13 23:58:09.359082 systemd-networkd[1383]: cni0: Gained carrier May 13 23:58:09.359369 systemd-networkd[1383]: cni0: Lost carrier May 13 23:58:09.366690 kernel: cni0: port 1(veth4e2e3413) entered blocking state May 13 23:58:09.366768 kernel: cni0: port 1(veth4e2e3413) entered disabled state May 13 23:58:09.366788 kernel: veth4e2e3413: entered allmulticast mode May 13 23:58:09.368469 kernel: veth4e2e3413: entered promiscuous mode May 13 23:58:09.368535 kernel: cni0: port 1(veth4e2e3413) entered blocking state May 13 23:58:09.368552 kernel: cni0: port 1(veth4e2e3413) entered forwarding state May 13 23:58:09.369915 kernel: cni0: port 1(veth4e2e3413) entered disabled state May 13 23:58:09.373056 systemd-networkd[1383]: veth4e2e3413: Link UP May 13 23:58:09.379408 kernel: cni0: port 1(veth4e2e3413) entered blocking state May 13 23:58:09.379463 kernel: cni0: port 1(veth4e2e3413) entered forwarding state May 13 23:58:09.379737 systemd-networkd[1383]: veth4e2e3413: Gained carrier May 13 23:58:09.380553 systemd-networkd[1383]: cni0: Gained carrier May 13 23:58:09.386086 containerd[1488]: 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"} May 13 23:58:09.386086 containerd[1488]: delegateAdd: netconf sent to delegate plugin: May 13 23:58:09.410962 containerd[1488]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T23:58:09.410887249Z" level=info msg="connecting to shim 0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58" address="unix:///run/containerd/s/79ffa1d28cc69db510bde9574d901cff03f7e26868ee3b4baec1dcc76815b17a" namespace=k8s.io protocol=ttrpc version=3 May 13 23:58:09.441082 systemd[1]: Started cri-containerd-0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58.scope - libcontainer container 0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58. May 13 23:58:09.488429 containerd[1488]: time="2025-05-13T23:58:09.488322726Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-ck2bs,Uid:bcb02a92-2e5e-4d35-ac05-3d26f8b0762c,Namespace:default,Attempt:0,} returns sandbox id \"0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58\"" May 13 23:58:09.490553 containerd[1488]: time="2025-05-13T23:58:09.490526524Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 23:58:09.825529 kubelet[1792]: E0513 23:58:09.825362 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:10.826543 kubelet[1792]: E0513 23:58:10.826486 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:10.830988 systemd-networkd[1383]: veth4e2e3413: Gained IPv6LL May 13 23:58:11.021178 systemd-networkd[1383]: cni0: Gained IPv6LL May 13 23:58:11.469113 systemd-resolved[1339]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2. May 13 23:58:11.827306 kubelet[1792]: E0513 23:58:11.827173 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:12.218020 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3354731116.mount: Deactivated successfully. May 13 23:58:12.828037 kubelet[1792]: E0513 23:58:12.827959 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:13.637930 containerd[1488]: time="2025-05-13T23:58:13.636741771Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:13.637930 containerd[1488]: time="2025-05-13T23:58:13.637653035Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:13.637930 containerd[1488]: time="2025-05-13T23:58:13.637704205Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 13 23:58:13.640130 containerd[1488]: time="2025-05-13T23:58:13.640066422Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:13.641193 containerd[1488]: time="2025-05-13T23:58:13.640804146Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 4.150245504s" May 13 23:58:13.641193 containerd[1488]: time="2025-05-13T23:58:13.640840591Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 13 23:58:13.643917 containerd[1488]: time="2025-05-13T23:58:13.643175084Z" level=info msg="CreateContainer within sandbox \"0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 13 23:58:13.654980 containerd[1488]: time="2025-05-13T23:58:13.654943448Z" level=info msg="Container c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:13.660745 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1829530099.mount: Deactivated successfully. May 13 23:58:13.664450 containerd[1488]: time="2025-05-13T23:58:13.664407948Z" level=info msg="CreateContainer within sandbox \"0c180ac6a19db459ce2ed6dd13129adc2d270de5ad4508a716d950b9407aac58\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914\"" May 13 23:58:13.665078 containerd[1488]: time="2025-05-13T23:58:13.664975917Z" level=info msg="StartContainer for \"c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914\"" May 13 23:58:13.666450 containerd[1488]: time="2025-05-13T23:58:13.666214601Z" level=info msg="connecting to shim c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914" address="unix:///run/containerd/s/79ffa1d28cc69db510bde9574d901cff03f7e26868ee3b4baec1dcc76815b17a" protocol=ttrpc version=3 May 13 23:58:13.692109 systemd[1]: Started cri-containerd-c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914.scope - libcontainer container c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914. May 13 23:58:13.737071 containerd[1488]: time="2025-05-13T23:58:13.736551525Z" level=info msg="StartContainer for \"c7fad469112a3f93e8c0a47510aa1fd8f979679de2fc847728f4a81ceb8bd914\" returns successfully" May 13 23:58:13.828856 kubelet[1792]: E0513 23:58:13.828774 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:14.829247 kubelet[1792]: E0513 23:58:14.829181 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:15.830137 kubelet[1792]: E0513 23:58:15.830082 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:16.261141 kubelet[1792]: I0513 23:58:16.261062 1792 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-ck2bs" podStartSLOduration=3.109276145 podStartE2EDuration="7.261042175s" podCreationTimestamp="2025-05-13 23:58:09 +0000 UTC" firstStartedPulling="2025-05-13 23:58:09.490119853 +0000 UTC m=+12.455742782" lastFinishedPulling="2025-05-13 23:58:13.641885883 +0000 UTC m=+16.607508812" observedRunningTime="2025-05-13 23:58:13.975960711 +0000 UTC m=+16.941583664" watchObservedRunningTime="2025-05-13 23:58:16.261042175 +0000 UTC m=+19.226665128" May 13 23:58:16.268296 systemd[1]: Created slice kubepods-besteffort-pode2a60807_0328_42e2_9534_c331bc0977e3.slice - libcontainer container kubepods-besteffort-pode2a60807_0328_42e2_9534_c331bc0977e3.slice. May 13 23:58:16.454527 kubelet[1792]: I0513 23:58:16.454447 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/e2a60807-0328-42e2-9534-c331bc0977e3-data\") pod \"nfs-server-provisioner-0\" (UID: \"e2a60807-0328-42e2-9534-c331bc0977e3\") " pod="default/nfs-server-provisioner-0" May 13 23:58:16.454527 kubelet[1792]: I0513 23:58:16.454529 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r8fr5\" (UniqueName: \"kubernetes.io/projected/e2a60807-0328-42e2-9534-c331bc0977e3-kube-api-access-r8fr5\") pod \"nfs-server-provisioner-0\" (UID: \"e2a60807-0328-42e2-9534-c331bc0977e3\") " pod="default/nfs-server-provisioner-0" May 13 23:58:16.571759 containerd[1488]: time="2025-05-13T23:58:16.571568017Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e2a60807-0328-42e2-9534-c331bc0977e3,Namespace:default,Attempt:0,}" May 13 23:58:16.585155 systemd-networkd[1383]: veth77c107a2: Link UP May 13 23:58:16.588931 kernel: cni0: port 2(veth77c107a2) entered blocking state May 13 23:58:16.589070 kernel: cni0: port 2(veth77c107a2) entered disabled state May 13 23:58:16.591911 kernel: veth77c107a2: entered allmulticast mode May 13 23:58:16.591978 kernel: veth77c107a2: entered promiscuous mode May 13 23:58:16.601545 kernel: cni0: port 2(veth77c107a2) entered blocking state May 13 23:58:16.601639 kernel: cni0: port 2(veth77c107a2) entered forwarding state May 13 23:58:16.601207 systemd-networkd[1383]: veth77c107a2: Gained carrier May 13 23:58:16.603541 containerd[1488]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} May 13 23:58:16.603541 containerd[1488]: delegateAdd: netconf sent to delegate plugin: May 13 23:58:16.630924 containerd[1488]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T23:58:16.630260404Z" level=info msg="connecting to shim 071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200" address="unix:///run/containerd/s/6fad91b9e6d4b0350145bf791d23ac9f8d729b04529e8c426998314a0ae93ff1" namespace=k8s.io protocol=ttrpc version=3 May 13 23:58:16.666145 systemd[1]: Started cri-containerd-071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200.scope - libcontainer container 071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200. May 13 23:58:16.723586 containerd[1488]: time="2025-05-13T23:58:16.723509523Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e2a60807-0328-42e2-9534-c331bc0977e3,Namespace:default,Attempt:0,} returns sandbox id \"071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200\"" May 13 23:58:16.725880 containerd[1488]: time="2025-05-13T23:58:16.725702630Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 13 23:58:16.830686 kubelet[1792]: E0513 23:58:16.830219 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:17.816801 kubelet[1792]: E0513 23:58:17.816712 1792 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:17.831349 kubelet[1792]: E0513 23:58:17.831286 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:18.445046 systemd-networkd[1383]: veth77c107a2: Gained IPv6LL May 13 23:58:18.831811 kubelet[1792]: E0513 23:58:18.831692 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:18.890298 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2489042632.mount: Deactivated successfully. May 13 23:58:19.832775 kubelet[1792]: E0513 23:58:19.832723 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:20.833314 kubelet[1792]: E0513 23:58:20.833248 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:21.006795 containerd[1488]: time="2025-05-13T23:58:21.005999840Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:21.008108 containerd[1488]: time="2025-05-13T23:58:21.008049280Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" May 13 23:58:21.008785 containerd[1488]: time="2025-05-13T23:58:21.008710961Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:21.010724 containerd[1488]: time="2025-05-13T23:58:21.010668682Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:21.011715 containerd[1488]: time="2025-05-13T23:58:21.011577714Z" 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.285843156s" May 13 23:58:21.011715 containerd[1488]: time="2025-05-13T23:58:21.011616256Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 13 23:58:21.016221 containerd[1488]: time="2025-05-13T23:58:21.014633798Z" level=info msg="CreateContainer within sandbox \"071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 13 23:58:21.026289 containerd[1488]: time="2025-05-13T23:58:21.026257493Z" level=info msg="Container 419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:21.036878 containerd[1488]: time="2025-05-13T23:58:21.036845126Z" level=info msg="CreateContainer within sandbox \"071b49e38edcf8b7472a738c7a954dccbe8db07087a803bbda1e74b3b86bb200\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa\"" May 13 23:58:21.037678 containerd[1488]: time="2025-05-13T23:58:21.037648330Z" level=info msg="StartContainer for \"419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa\"" May 13 23:58:21.038626 containerd[1488]: time="2025-05-13T23:58:21.038586455Z" level=info msg="connecting to shim 419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa" address="unix:///run/containerd/s/6fad91b9e6d4b0350145bf791d23ac9f8d729b04529e8c426998314a0ae93ff1" protocol=ttrpc version=3 May 13 23:58:21.072178 systemd[1]: Started cri-containerd-419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa.scope - libcontainer container 419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa. May 13 23:58:21.114164 containerd[1488]: time="2025-05-13T23:58:21.113929578Z" level=info msg="StartContainer for \"419837a702c32058c5cc3aced583ec862aefbec68f6a0c97533401a5b966b0aa\" returns successfully" May 13 23:58:21.834044 kubelet[1792]: E0513 23:58:21.833982 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:22.834245 kubelet[1792]: E0513 23:58:22.834158 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:23.835370 kubelet[1792]: E0513 23:58:23.835293 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:24.835846 kubelet[1792]: E0513 23:58:24.835776 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:25.836598 kubelet[1792]: E0513 23:58:25.836533 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:26.837051 kubelet[1792]: E0513 23:58:26.836979 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:27.837211 kubelet[1792]: E0513 23:58:27.837156 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:28.837772 kubelet[1792]: E0513 23:58:28.837708 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:29.318997 update_engine[1473]: I20250513 23:58:29.318543 1473 update_attempter.cc:509] Updating boot flags... May 13 23:58:29.367923 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2755) May 13 23:58:29.442585 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2757) May 13 23:58:29.508948 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2757) May 13 23:58:29.838362 kubelet[1792]: E0513 23:58:29.838304 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:30.805698 kubelet[1792]: I0513 23:58:30.805617 1792 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.518377034 podStartE2EDuration="14.805599789s" podCreationTimestamp="2025-05-13 23:58:16 +0000 UTC" firstStartedPulling="2025-05-13 23:58:16.725322697 +0000 UTC m=+19.690945629" lastFinishedPulling="2025-05-13 23:58:21.012545443 +0000 UTC m=+23.978168384" observedRunningTime="2025-05-13 23:58:22.010852651 +0000 UTC m=+24.976475607" watchObservedRunningTime="2025-05-13 23:58:30.805599789 +0000 UTC m=+33.771222750" May 13 23:58:30.812358 systemd[1]: Created slice kubepods-besteffort-pod506d7839_996b_4789_9eff_ff7d3d3b5d64.slice - libcontainer container kubepods-besteffort-pod506d7839_996b_4789_9eff_ff7d3d3b5d64.slice. May 13 23:58:30.838987 kubelet[1792]: E0513 23:58:30.838937 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:30.931746 kubelet[1792]: I0513 23:58:30.931636 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-51453f46-fb65-4eac-9d34-cc106c607948\" (UniqueName: \"kubernetes.io/nfs/506d7839-996b-4789-9eff-ff7d3d3b5d64-pvc-51453f46-fb65-4eac-9d34-cc106c607948\") pod \"test-pod-1\" (UID: \"506d7839-996b-4789-9eff-ff7d3d3b5d64\") " pod="default/test-pod-1" May 13 23:58:30.931746 kubelet[1792]: I0513 23:58:30.931688 1792 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rjmjx\" (UniqueName: \"kubernetes.io/projected/506d7839-996b-4789-9eff-ff7d3d3b5d64-kube-api-access-rjmjx\") pod \"test-pod-1\" (UID: \"506d7839-996b-4789-9eff-ff7d3d3b5d64\") " pod="default/test-pod-1" May 13 23:58:31.060187 kernel: FS-Cache: Loaded May 13 23:58:31.128260 kernel: RPC: Registered named UNIX socket transport module. May 13 23:58:31.128391 kernel: RPC: Registered udp transport module. May 13 23:58:31.128413 kernel: RPC: Registered tcp transport module. May 13 23:58:31.129204 kernel: RPC: Registered tcp-with-tls transport module. May 13 23:58:31.130252 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 13 23:58:31.339099 kernel: NFS: Registering the id_resolver key type May 13 23:58:31.339223 kernel: Key type id_resolver registered May 13 23:58:31.340929 kernel: Key type id_legacy registered May 13 23:58:31.372119 nfsidmap[2777]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-n-b1a48b09a9' May 13 23:58:31.378167 nfsidmap[2778]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-n-b1a48b09a9' May 13 23:58:31.416021 containerd[1488]: time="2025-05-13T23:58:31.415798402Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:506d7839-996b-4789-9eff-ff7d3d3b5d64,Namespace:default,Attempt:0,}" May 13 23:58:31.436694 systemd-networkd[1383]: veth380d9670: Link UP May 13 23:58:31.441705 kernel: cni0: port 3(veth380d9670) entered blocking state May 13 23:58:31.441794 kernel: cni0: port 3(veth380d9670) entered disabled state May 13 23:58:31.441925 kernel: veth380d9670: entered allmulticast mode May 13 23:58:31.441944 kernel: veth380d9670: entered promiscuous mode May 13 23:58:31.448413 kernel: cni0: port 3(veth380d9670) entered blocking state May 13 23:58:31.448469 kernel: cni0: port 3(veth380d9670) entered forwarding state May 13 23:58:31.448114 systemd-networkd[1383]: veth380d9670: Gained carrier May 13 23:58:31.450982 containerd[1488]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} May 13 23:58:31.450982 containerd[1488]: delegateAdd: netconf sent to delegate plugin: May 13 23:58:31.492577 containerd[1488]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-13T23:58:31.492081773Z" level=info msg="connecting to shim 5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44" address="unix:///run/containerd/s/6b71e55d61603027afe92ada683bd853551b4ed75d5a0dd211a9585a8b4db1be" namespace=k8s.io protocol=ttrpc version=3 May 13 23:58:31.540101 systemd[1]: Started cri-containerd-5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44.scope - libcontainer container 5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44. May 13 23:58:31.592208 containerd[1488]: time="2025-05-13T23:58:31.592104034Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:506d7839-996b-4789-9eff-ff7d3d3b5d64,Namespace:default,Attempt:0,} returns sandbox id \"5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44\"" May 13 23:58:31.594855 containerd[1488]: time="2025-05-13T23:58:31.594496917Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 23:58:31.839984 kubelet[1792]: E0513 23:58:31.839922 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:31.986939 containerd[1488]: time="2025-05-13T23:58:31.986536000Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:58:31.987471 containerd[1488]: time="2025-05-13T23:58:31.987411890Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 13 23:58:31.989674 containerd[1488]: time="2025-05-13T23:58:31.989642486Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 394.976687ms" May 13 23:58:31.989715 containerd[1488]: time="2025-05-13T23:58:31.989678926Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 13 23:58:31.991924 containerd[1488]: time="2025-05-13T23:58:31.991872742Z" level=info msg="CreateContainer within sandbox \"5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 13 23:58:32.000145 containerd[1488]: time="2025-05-13T23:58:32.000101516Z" level=info msg="Container 5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc: CDI devices from CRI Config.CDIDevices: []" May 13 23:58:32.019505 containerd[1488]: time="2025-05-13T23:58:32.019455535Z" level=info msg="CreateContainer within sandbox \"5c17411704e9e1c2b85fe78320eddc654feaee8d10c21b514aad9475703aeb44\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc\"" May 13 23:58:32.020174 containerd[1488]: time="2025-05-13T23:58:32.020119393Z" level=info msg="StartContainer for \"5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc\"" May 13 23:58:32.021105 containerd[1488]: time="2025-05-13T23:58:32.021081480Z" level=info msg="connecting to shim 5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc" address="unix:///run/containerd/s/6b71e55d61603027afe92ada683bd853551b4ed75d5a0dd211a9585a8b4db1be" protocol=ttrpc version=3 May 13 23:58:32.043108 systemd[1]: Started cri-containerd-5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc.scope - libcontainer container 5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc. May 13 23:58:32.088731 containerd[1488]: time="2025-05-13T23:58:32.088148504Z" level=info msg="StartContainer for \"5418bc98dc34f60c097ae86254a99ff6be3b0b4796f231a92177c21bff956dcc\" returns successfully" May 13 23:58:32.589126 systemd-networkd[1383]: veth380d9670: Gained IPv6LL May 13 23:58:32.840431 kubelet[1792]: E0513 23:58:32.840272 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:33.840927 kubelet[1792]: E0513 23:58:33.840824 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:34.841864 kubelet[1792]: E0513 23:58:34.841780 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:35.842150 kubelet[1792]: E0513 23:58:35.842103 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:36.843124 kubelet[1792]: E0513 23:58:36.843054 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:37.817574 kubelet[1792]: E0513 23:58:37.817515 1792 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:37.844288 kubelet[1792]: E0513 23:58:37.844221 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:38.844806 kubelet[1792]: E0513 23:58:38.844747 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:58:39.845919 kubelet[1792]: E0513 23:58:39.845835 1792 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"