Jan 16 08:54:33.015927 kernel: Linux version 6.6.71-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Jan 13 19:01:45 -00 2025 Jan 16 08:54:33.016036 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=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07 Jan 16 08:54:33.016054 kernel: BIOS-provided physical RAM map: Jan 16 08:54:33.016065 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Jan 16 08:54:33.016075 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Jan 16 08:54:33.016086 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jan 16 08:54:33.016098 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdafff] usable Jan 16 08:54:33.016110 kernel: BIOS-e820: [mem 0x000000007ffdb000-0x000000007fffffff] reserved Jan 16 08:54:33.016120 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jan 16 08:54:33.016135 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jan 16 08:54:33.016146 kernel: NX (Execute Disable) protection: active Jan 16 08:54:33.016157 kernel: APIC: Static calls initialized Jan 16 08:54:33.016173 kernel: SMBIOS 2.8 present. Jan 16 08:54:33.016184 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Jan 16 08:54:33.016198 kernel: Hypervisor detected: KVM Jan 16 08:54:33.016214 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jan 16 08:54:33.016230 kernel: kvm-clock: using sched offset of 4442641554 cycles Jan 16 08:54:33.016243 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jan 16 08:54:33.016255 kernel: tsc: Detected 2294.606 MHz processor Jan 16 08:54:33.016267 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 16 08:54:33.016280 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 16 08:54:33.016292 kernel: last_pfn = 0x7ffdb max_arch_pfn = 0x400000000 Jan 16 08:54:33.016304 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Jan 16 08:54:33.016316 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 16 08:54:33.016333 kernel: ACPI: Early table checksum verification disabled Jan 16 08:54:33.016345 kernel: ACPI: RSDP 0x00000000000F5950 000014 (v00 BOCHS ) Jan 16 08:54:33.016356 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016369 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016381 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016393 kernel: ACPI: FACS 0x000000007FFE0000 000040 Jan 16 08:54:33.016406 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016418 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016430 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016446 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jan 16 08:54:33.016513 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Jan 16 08:54:33.016528 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Jan 16 08:54:33.016540 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Jan 16 08:54:33.016552 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Jan 16 08:54:33.016564 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Jan 16 08:54:33.019438 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Jan 16 08:54:33.019485 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Jan 16 08:54:33.019499 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jan 16 08:54:33.019512 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jan 16 08:54:33.019525 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Jan 16 08:54:33.019539 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Jan 16 08:54:33.019560 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffdafff] -> [mem 0x00000000-0x7ffdafff] Jan 16 08:54:33.019589 kernel: NODE_DATA(0) allocated [mem 0x7ffd5000-0x7ffdafff] Jan 16 08:54:33.019607 kernel: Zone ranges: Jan 16 08:54:33.019621 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 16 08:54:33.019634 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdafff] Jan 16 08:54:33.019647 kernel: Normal empty Jan 16 08:54:33.019660 kernel: Movable zone start for each node Jan 16 08:54:33.019675 kernel: Early memory node ranges Jan 16 08:54:33.019688 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jan 16 08:54:33.019703 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdafff] Jan 16 08:54:33.019716 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdafff] Jan 16 08:54:33.019735 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 16 08:54:33.019748 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jan 16 08:54:33.019766 kernel: On node 0, zone DMA32: 37 pages in unavailable ranges Jan 16 08:54:33.019779 kernel: ACPI: PM-Timer IO Port: 0x608 Jan 16 08:54:33.019792 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jan 16 08:54:33.019805 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Jan 16 08:54:33.019819 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jan 16 08:54:33.019832 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jan 16 08:54:33.019845 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 16 08:54:33.019862 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jan 16 08:54:33.019875 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jan 16 08:54:33.019889 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 16 08:54:33.019902 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jan 16 08:54:33.019915 kernel: TSC deadline timer available Jan 16 08:54:33.019928 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Jan 16 08:54:33.019941 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jan 16 08:54:33.019955 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Jan 16 08:54:33.019971 kernel: Booting paravirtualized kernel on KVM Jan 16 08:54:33.019985 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 16 08:54:33.020002 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jan 16 08:54:33.020016 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 Jan 16 08:54:33.020029 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 Jan 16 08:54:33.020042 kernel: pcpu-alloc: [0] 0 1 Jan 16 08:54:33.020055 kernel: kvm-guest: PV spinlocks disabled, no host support Jan 16 08:54:33.020071 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=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07 Jan 16 08:54:33.020085 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jan 16 08:54:33.020102 kernel: random: crng init done Jan 16 08:54:33.020115 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 16 08:54:33.020128 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 16 08:54:33.020142 kernel: Fallback order for Node 0: 0 Jan 16 08:54:33.020155 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515803 Jan 16 08:54:33.020168 kernel: Policy zone: DMA32 Jan 16 08:54:33.020181 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 16 08:54:33.020195 kernel: Memory: 1971204K/2096612K available (12288K kernel code, 2299K rwdata, 22736K rodata, 42976K init, 2216K bss, 125148K reserved, 0K cma-reserved) Jan 16 08:54:33.020208 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jan 16 08:54:33.020226 kernel: Kernel/User page tables isolation: enabled Jan 16 08:54:33.020239 kernel: ftrace: allocating 37920 entries in 149 pages Jan 16 08:54:33.020253 kernel: ftrace: allocated 149 pages with 4 groups Jan 16 08:54:33.020266 kernel: Dynamic Preempt: voluntary Jan 16 08:54:33.020279 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 16 08:54:33.020294 kernel: rcu: RCU event tracing is enabled. Jan 16 08:54:33.020307 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jan 16 08:54:33.020321 kernel: Trampoline variant of Tasks RCU enabled. Jan 16 08:54:33.020334 kernel: Rude variant of Tasks RCU enabled. Jan 16 08:54:33.020352 kernel: Tracing variant of Tasks RCU enabled. Jan 16 08:54:33.020367 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 16 08:54:33.020380 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jan 16 08:54:33.020394 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Jan 16 08:54:33.020406 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 16 08:54:33.020424 kernel: Console: colour VGA+ 80x25 Jan 16 08:54:33.020437 kernel: printk: console [tty0] enabled Jan 16 08:54:33.020450 kernel: printk: console [ttyS0] enabled Jan 16 08:54:33.020464 kernel: ACPI: Core revision 20230628 Jan 16 08:54:33.020477 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Jan 16 08:54:33.020496 kernel: APIC: Switch to symmetric I/O mode setup Jan 16 08:54:33.020509 kernel: x2apic enabled Jan 16 08:54:33.020523 kernel: APIC: Switched APIC routing to: physical x2apic Jan 16 08:54:33.020536 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jan 16 08:54:33.020549 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x21134dbeb26, max_idle_ns: 440795298546 ns Jan 16 08:54:33.020563 kernel: Calibrating delay loop (skipped) preset value.. 4589.21 BogoMIPS (lpj=2294606) Jan 16 08:54:33.020744 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Jan 16 08:54:33.020761 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Jan 16 08:54:33.020793 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 16 08:54:33.020807 kernel: Spectre V2 : Mitigation: Retpolines Jan 16 08:54:33.020821 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Jan 16 08:54:33.020839 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Jan 16 08:54:33.020853 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Jan 16 08:54:33.020867 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Jan 16 08:54:33.020881 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Jan 16 08:54:33.020896 kernel: MDS: Mitigation: Clear CPU buffers Jan 16 08:54:33.020911 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 16 08:54:33.020933 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 16 08:54:33.020947 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 16 08:54:33.020961 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 16 08:54:33.020976 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 16 08:54:33.020990 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jan 16 08:54:33.021004 kernel: Freeing SMP alternatives memory: 32K Jan 16 08:54:33.021018 kernel: pid_max: default: 32768 minimum: 301 Jan 16 08:54:33.021032 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jan 16 08:54:33.021050 kernel: landlock: Up and running. Jan 16 08:54:33.021064 kernel: SELinux: Initializing. Jan 16 08:54:33.021078 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jan 16 08:54:33.021092 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jan 16 08:54:33.021107 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Jan 16 08:54:33.021121 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 16 08:54:33.021136 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 16 08:54:33.021151 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 16 08:54:33.021169 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jan 16 08:54:33.021183 kernel: signal: max sigframe size: 1776 Jan 16 08:54:33.021197 kernel: rcu: Hierarchical SRCU implementation. Jan 16 08:54:33.021212 kernel: rcu: Max phase no-delay instances is 400. Jan 16 08:54:33.021227 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 16 08:54:33.021241 kernel: smp: Bringing up secondary CPUs ... Jan 16 08:54:33.021255 kernel: smpboot: x86: Booting SMP configuration: Jan 16 08:54:33.021269 kernel: .... node #0, CPUs: #1 Jan 16 08:54:33.021283 kernel: smp: Brought up 1 node, 2 CPUs Jan 16 08:54:33.021303 kernel: smpboot: Max logical packages: 1 Jan 16 08:54:33.021321 kernel: smpboot: Total of 2 processors activated (9178.42 BogoMIPS) Jan 16 08:54:33.021356 kernel: devtmpfs: initialized Jan 16 08:54:33.021378 kernel: x86/mm: Memory block size: 128MB Jan 16 08:54:33.021392 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 16 08:54:33.021407 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jan 16 08:54:33.021420 kernel: pinctrl core: initialized pinctrl subsystem Jan 16 08:54:33.021435 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 16 08:54:33.021450 kernel: audit: initializing netlink subsys (disabled) Jan 16 08:54:33.021464 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 16 08:54:33.021483 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 16 08:54:33.021497 kernel: audit: type=2000 audit(1737017672.088:1): state=initialized audit_enabled=0 res=1 Jan 16 08:54:33.021511 kernel: cpuidle: using governor menu Jan 16 08:54:33.021525 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 16 08:54:33.021540 kernel: dca service started, version 1.12.1 Jan 16 08:54:33.021554 kernel: PCI: Using configuration type 1 for base access Jan 16 08:54:33.021568 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 16 08:54:33.021595 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 16 08:54:33.021617 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 16 08:54:33.021685 kernel: ACPI: Added _OSI(Module Device) Jan 16 08:54:33.021700 kernel: ACPI: Added _OSI(Processor Device) Jan 16 08:54:33.021714 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jan 16 08:54:33.021728 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 16 08:54:33.021742 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 16 08:54:33.021756 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jan 16 08:54:33.021771 kernel: ACPI: Interpreter enabled Jan 16 08:54:33.021785 kernel: ACPI: PM: (supports S0 S5) Jan 16 08:54:33.021800 kernel: ACPI: Using IOAPIC for interrupt routing Jan 16 08:54:33.021819 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 16 08:54:33.021834 kernel: PCI: Using E820 reservations for host bridge windows Jan 16 08:54:33.021848 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Jan 16 08:54:33.021862 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jan 16 08:54:33.022168 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Jan 16 08:54:33.022335 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Jan 16 08:54:33.022481 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Jan 16 08:54:33.022505 kernel: acpiphp: Slot [3] registered Jan 16 08:54:33.022519 kernel: acpiphp: Slot [4] registered Jan 16 08:54:33.022534 kernel: acpiphp: Slot [5] registered Jan 16 08:54:33.022548 kernel: acpiphp: Slot [6] registered Jan 16 08:54:33.022562 kernel: acpiphp: Slot [7] registered Jan 16 08:54:33.023197 kernel: acpiphp: Slot [8] registered Jan 16 08:54:33.023215 kernel: acpiphp: Slot [9] registered Jan 16 08:54:33.023230 kernel: acpiphp: Slot [10] registered Jan 16 08:54:33.023244 kernel: acpiphp: Slot [11] registered Jan 16 08:54:33.023266 kernel: acpiphp: Slot [12] registered Jan 16 08:54:33.023280 kernel: acpiphp: Slot [13] registered Jan 16 08:54:33.023294 kernel: acpiphp: Slot [14] registered Jan 16 08:54:33.023308 kernel: acpiphp: Slot [15] registered Jan 16 08:54:33.023323 kernel: acpiphp: Slot [16] registered Jan 16 08:54:33.023337 kernel: acpiphp: Slot [17] registered Jan 16 08:54:33.023351 kernel: acpiphp: Slot [18] registered Jan 16 08:54:33.023365 kernel: acpiphp: Slot [19] registered Jan 16 08:54:33.023380 kernel: acpiphp: Slot [20] registered Jan 16 08:54:33.023394 kernel: acpiphp: Slot [21] registered Jan 16 08:54:33.023413 kernel: acpiphp: Slot [22] registered Jan 16 08:54:33.023428 kernel: acpiphp: Slot [23] registered Jan 16 08:54:33.023442 kernel: acpiphp: Slot [24] registered Jan 16 08:54:33.023456 kernel: acpiphp: Slot [25] registered Jan 16 08:54:33.023471 kernel: acpiphp: Slot [26] registered Jan 16 08:54:33.023485 kernel: acpiphp: Slot [27] registered Jan 16 08:54:33.023499 kernel: acpiphp: Slot [28] registered Jan 16 08:54:33.023513 kernel: acpiphp: Slot [29] registered Jan 16 08:54:33.023528 kernel: acpiphp: Slot [30] registered Jan 16 08:54:33.023546 kernel: acpiphp: Slot [31] registered Jan 16 08:54:33.023560 kernel: PCI host bridge to bus 0000:00 Jan 16 08:54:33.023834 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jan 16 08:54:33.023977 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jan 16 08:54:33.024110 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jan 16 08:54:33.024241 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Jan 16 08:54:33.024370 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Jan 16 08:54:33.024498 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 16 08:54:33.025879 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Jan 16 08:54:33.026862 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Jan 16 08:54:33.027037 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Jan 16 08:54:33.027184 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] Jan 16 08:54:33.027327 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Jan 16 08:54:33.027469 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Jan 16 08:54:33.027640 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Jan 16 08:54:33.027786 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Jan 16 08:54:33.027951 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Jan 16 08:54:33.028094 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] Jan 16 08:54:33.028268 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Jan 16 08:54:33.028414 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Jan 16 08:54:33.028571 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Jan 16 08:54:33.029870 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Jan 16 08:54:33.030026 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Jan 16 08:54:33.030187 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Jan 16 08:54:33.030334 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] Jan 16 08:54:33.030478 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Jan 16 08:54:33.032741 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jan 16 08:54:33.032963 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Jan 16 08:54:33.033116 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] Jan 16 08:54:33.033261 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] Jan 16 08:54:33.033406 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Jan 16 08:54:33.033649 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Jan 16 08:54:33.033812 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] Jan 16 08:54:33.033966 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] Jan 16 08:54:33.034110 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Jan 16 08:54:33.034294 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Jan 16 08:54:33.034445 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] Jan 16 08:54:33.034605 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] Jan 16 08:54:33.034751 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Jan 16 08:54:33.034913 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 Jan 16 08:54:33.035069 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] Jan 16 08:54:33.035210 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] Jan 16 08:54:33.035351 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Jan 16 08:54:33.035513 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 Jan 16 08:54:33.038933 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] Jan 16 08:54:33.039106 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] Jan 16 08:54:33.039251 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] Jan 16 08:54:33.039416 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 Jan 16 08:54:33.039592 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] Jan 16 08:54:33.039741 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] Jan 16 08:54:33.039760 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jan 16 08:54:33.039774 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jan 16 08:54:33.039789 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jan 16 08:54:33.039803 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jan 16 08:54:33.039824 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Jan 16 08:54:33.039838 kernel: iommu: Default domain type: Translated Jan 16 08:54:33.039853 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 16 08:54:33.039867 kernel: PCI: Using ACPI for IRQ routing Jan 16 08:54:33.039881 kernel: PCI: pci_cache_line_size set to 64 bytes Jan 16 08:54:33.039895 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Jan 16 08:54:33.039909 kernel: e820: reserve RAM buffer [mem 0x7ffdb000-0x7fffffff] Jan 16 08:54:33.040062 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jan 16 08:54:33.040209 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jan 16 08:54:33.040359 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jan 16 08:54:33.040378 kernel: vgaarb: loaded Jan 16 08:54:33.040393 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Jan 16 08:54:33.040407 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Jan 16 08:54:33.040422 kernel: clocksource: Switched to clocksource kvm-clock Jan 16 08:54:33.040436 kernel: VFS: Disk quotas dquot_6.6.0 Jan 16 08:54:33.040450 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 16 08:54:33.040465 kernel: pnp: PnP ACPI init Jan 16 08:54:33.040479 kernel: pnp: PnP ACPI: found 4 devices Jan 16 08:54:33.040499 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 16 08:54:33.040513 kernel: NET: Registered PF_INET protocol family Jan 16 08:54:33.040528 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Jan 16 08:54:33.040542 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Jan 16 08:54:33.040557 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 16 08:54:33.040571 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 16 08:54:33.042647 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Jan 16 08:54:33.042664 kernel: TCP: Hash tables configured (established 16384 bind 16384) Jan 16 08:54:33.042680 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Jan 16 08:54:33.042705 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Jan 16 08:54:33.042719 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 16 08:54:33.042734 kernel: NET: Registered PF_XDP protocol family Jan 16 08:54:33.042939 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jan 16 08:54:33.043072 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jan 16 08:54:33.043199 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jan 16 08:54:33.043327 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Jan 16 08:54:33.043453 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Jan 16 08:54:33.043650 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Jan 16 08:54:33.043805 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Jan 16 08:54:33.043826 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Jan 16 08:54:33.043969 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 38389 usecs Jan 16 08:54:33.043988 kernel: PCI: CLS 0 bytes, default 64 Jan 16 08:54:33.044003 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 16 08:54:33.044018 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21134dbeb26, max_idle_ns: 440795298546 ns Jan 16 08:54:33.044032 kernel: Initialise system trusted keyrings Jan 16 08:54:33.044053 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Jan 16 08:54:33.044067 kernel: Key type asymmetric registered Jan 16 08:54:33.044081 kernel: Asymmetric key parser 'x509' registered Jan 16 08:54:33.044096 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jan 16 08:54:33.044110 kernel: io scheduler mq-deadline registered Jan 16 08:54:33.044124 kernel: io scheduler kyber registered Jan 16 08:54:33.044138 kernel: io scheduler bfq registered Jan 16 08:54:33.044153 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 16 08:54:33.044167 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Jan 16 08:54:33.044187 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Jan 16 08:54:33.044202 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Jan 16 08:54:33.044216 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 16 08:54:33.044230 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 16 08:54:33.044244 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jan 16 08:54:33.044259 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jan 16 08:54:33.044273 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jan 16 08:54:33.044478 kernel: rtc_cmos 00:03: RTC can wake from S4 Jan 16 08:54:33.044501 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Jan 16 08:54:33.044656 kernel: rtc_cmos 00:03: registered as rtc0 Jan 16 08:54:33.044791 kernel: rtc_cmos 00:03: setting system clock to 2025-01-16T08:54:32 UTC (1737017672) Jan 16 08:54:33.044921 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Jan 16 08:54:33.044939 kernel: intel_pstate: CPU model not supported Jan 16 08:54:33.044954 kernel: NET: Registered PF_INET6 protocol family Jan 16 08:54:33.044967 kernel: Segment Routing with IPv6 Jan 16 08:54:33.044982 kernel: In-situ OAM (IOAM) with IPv6 Jan 16 08:54:33.044996 kernel: NET: Registered PF_PACKET protocol family Jan 16 08:54:33.045016 kernel: Key type dns_resolver registered Jan 16 08:54:33.045030 kernel: IPI shorthand broadcast: enabled Jan 16 08:54:33.045044 kernel: sched_clock: Marking stable (1195002676, 176515946)->(1414409953, -42891331) Jan 16 08:54:33.045059 kernel: registered taskstats version 1 Jan 16 08:54:33.045073 kernel: Loading compiled-in X.509 certificates Jan 16 08:54:33.045088 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: 98739e9049f62881f4df7ffd1e39335f7f55b344' Jan 16 08:54:33.045102 kernel: Key type .fscrypt registered Jan 16 08:54:33.045115 kernel: Key type fscrypt-provisioning registered Jan 16 08:54:33.045129 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 16 08:54:33.045148 kernel: ima: Allocated hash algorithm: sha1 Jan 16 08:54:33.045163 kernel: ima: No architecture policies found Jan 16 08:54:33.045176 kernel: clk: Disabling unused clocks Jan 16 08:54:33.045191 kernel: Freeing unused kernel image (initmem) memory: 42976K Jan 16 08:54:33.045205 kernel: Write protecting the kernel read-only data: 36864k Jan 16 08:54:33.045248 kernel: Freeing unused kernel image (rodata/data gap) memory: 1840K Jan 16 08:54:33.045267 kernel: Run /init as init process Jan 16 08:54:33.045282 kernel: with arguments: Jan 16 08:54:33.045298 kernel: /init Jan 16 08:54:33.045317 kernel: with environment: Jan 16 08:54:33.045332 kernel: HOME=/ Jan 16 08:54:33.045347 kernel: TERM=linux Jan 16 08:54:33.045362 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jan 16 08:54:33.045380 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Jan 16 08:54:33.045400 systemd[1]: Detected virtualization kvm. Jan 16 08:54:33.045415 systemd[1]: Detected architecture x86-64. Jan 16 08:54:33.045435 systemd[1]: Running in initrd. Jan 16 08:54:33.045451 systemd[1]: No hostname configured, using default hostname. Jan 16 08:54:33.045466 systemd[1]: Hostname set to . Jan 16 08:54:33.045481 systemd[1]: Initializing machine ID from VM UUID. Jan 16 08:54:33.045497 systemd[1]: Queued start job for default target initrd.target. Jan 16 08:54:33.045513 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 16 08:54:33.045529 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 16 08:54:33.045546 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 16 08:54:33.045565 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 16 08:54:33.046327 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 16 08:54:33.046347 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 16 08:54:33.046366 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jan 16 08:54:33.046382 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 16 08:54:33.046399 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 16 08:54:33.046415 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 16 08:54:33.046438 systemd[1]: Reached target paths.target - Path Units. Jan 16 08:54:33.046453 systemd[1]: Reached target slices.target - Slice Units. Jan 16 08:54:33.046469 systemd[1]: Reached target swap.target - Swaps. Jan 16 08:54:33.046491 systemd[1]: Reached target timers.target - Timer Units. Jan 16 08:54:33.046506 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 16 08:54:33.046522 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 16 08:54:33.046541 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 16 08:54:33.046557 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jan 16 08:54:33.046585 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 16 08:54:33.046601 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 16 08:54:33.046617 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 16 08:54:33.046633 systemd[1]: Reached target sockets.target - Socket Units. Jan 16 08:54:33.046648 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 16 08:54:33.046664 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 16 08:54:33.046685 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 16 08:54:33.046700 systemd[1]: Starting systemd-fsck-usr.service... Jan 16 08:54:33.046716 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 16 08:54:33.046732 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 16 08:54:33.046748 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:33.046764 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 16 08:54:33.046823 systemd-journald[183]: Collecting audit messages is disabled. Jan 16 08:54:33.046863 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 16 08:54:33.046879 systemd[1]: Finished systemd-fsck-usr.service. Jan 16 08:54:33.046897 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 16 08:54:33.046919 systemd-journald[183]: Journal started Jan 16 08:54:33.046954 systemd-journald[183]: Runtime Journal (/run/log/journal/e5a45872635d4d13a41ac394ffe9f5d5) is 4.9M, max 39.3M, 34.4M free. Jan 16 08:54:33.008952 systemd-modules-load[184]: Inserted module 'overlay' Jan 16 08:54:33.109429 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 16 08:54:33.109485 kernel: Bridge firewalling registered Jan 16 08:54:33.067730 systemd-modules-load[184]: Inserted module 'br_netfilter' Jan 16 08:54:33.113095 systemd[1]: Started systemd-journald.service - Journal Service. Jan 16 08:54:33.114474 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 16 08:54:33.120566 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:33.122000 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 16 08:54:33.130875 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 16 08:54:33.134835 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 16 08:54:33.136902 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 16 08:54:33.142830 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 16 08:54:33.168438 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 16 08:54:33.170743 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 16 08:54:33.171754 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 16 08:54:33.190925 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 16 08:54:33.193266 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 16 08:54:33.196826 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 16 08:54:33.226512 dracut-cmdline[219]: dracut-dracut-053 Jan 16 08:54:33.230512 dracut-cmdline[219]: 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=1175b5bd4028ce8485b23b7d346f787308cbfa43cca7b1fefd4254406dce7d07 Jan 16 08:54:33.239330 systemd-resolved[216]: Positive Trust Anchors: Jan 16 08:54:33.239354 systemd-resolved[216]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 16 08:54:33.239459 systemd-resolved[216]: 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 Jan 16 08:54:33.249726 systemd-resolved[216]: Defaulting to hostname 'linux'. Jan 16 08:54:33.251747 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 16 08:54:33.252530 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 16 08:54:33.344620 kernel: SCSI subsystem initialized Jan 16 08:54:33.358615 kernel: Loading iSCSI transport class v2.0-870. Jan 16 08:54:33.374717 kernel: iscsi: registered transport (tcp) Jan 16 08:54:33.405470 kernel: iscsi: registered transport (qla4xxx) Jan 16 08:54:33.405602 kernel: QLogic iSCSI HBA Driver Jan 16 08:54:33.475722 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 16 08:54:33.483911 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 16 08:54:33.533694 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 16 08:54:33.533797 kernel: device-mapper: uevent: version 1.0.3 Jan 16 08:54:33.537626 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jan 16 08:54:33.585620 kernel: raid6: avx2x4 gen() 17384 MB/s Jan 16 08:54:33.603617 kernel: raid6: avx2x2 gen() 17159 MB/s Jan 16 08:54:33.622358 kernel: raid6: avx2x1 gen() 11122 MB/s Jan 16 08:54:33.622458 kernel: raid6: using algorithm avx2x4 gen() 17384 MB/s Jan 16 08:54:33.641302 kernel: raid6: .... xor() 5998 MB/s, rmw enabled Jan 16 08:54:33.641405 kernel: raid6: using avx2x2 recovery algorithm Jan 16 08:54:33.665630 kernel: xor: automatically using best checksumming function avx Jan 16 08:54:33.851636 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 16 08:54:33.869043 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 16 08:54:33.877930 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 16 08:54:33.923230 systemd-udevd[401]: Using default interface naming scheme 'v255'. Jan 16 08:54:33.937112 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 16 08:54:33.947798 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 16 08:54:33.991691 dracut-pre-trigger[409]: rd.md=0: removing MD RAID activation Jan 16 08:54:34.037538 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 16 08:54:34.043819 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 16 08:54:34.131067 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 16 08:54:34.140145 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 16 08:54:34.167914 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 16 08:54:34.175630 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 16 08:54:34.176832 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 16 08:54:34.178594 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 16 08:54:34.186158 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 16 08:54:34.222489 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 16 08:54:34.251604 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Jan 16 08:54:34.328160 kernel: scsi host0: Virtio SCSI HBA Jan 16 08:54:34.328398 kernel: cryptd: max_cpu_qlen set to 1000 Jan 16 08:54:34.328428 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Jan 16 08:54:34.328616 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jan 16 08:54:34.328643 kernel: GPT:9289727 != 125829119 Jan 16 08:54:34.328669 kernel: GPT:Alternate GPT header not at the end of the disk. Jan 16 08:54:34.328695 kernel: GPT:9289727 != 125829119 Jan 16 08:54:34.328726 kernel: GPT: Use GNU Parted to correct GPT errors. Jan 16 08:54:34.328751 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jan 16 08:54:34.328777 kernel: AVX2 version of gcm_enc/dec engaged. Jan 16 08:54:34.328804 kernel: AES CTR mode by8 optimization enabled Jan 16 08:54:34.301001 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 16 08:54:34.301195 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 16 08:54:34.333168 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Jan 16 08:54:34.373859 kernel: virtio_blk virtio5: [vdb] 920 512-byte logical blocks (471 kB/460 KiB) Jan 16 08:54:34.302147 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 16 08:54:34.483874 kernel: libata version 3.00 loaded. Jan 16 08:54:34.483922 kernel: ata_piix 0000:00:01.1: version 2.13 Jan 16 08:54:34.484205 kernel: scsi host1: ata_piix Jan 16 08:54:34.484409 kernel: scsi host2: ata_piix Jan 16 08:54:34.484589 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 Jan 16 08:54:34.484617 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 Jan 16 08:54:34.484643 kernel: ACPI: bus type USB registered Jan 16 08:54:34.484669 kernel: usbcore: registered new interface driver usbfs Jan 16 08:54:34.484696 kernel: usbcore: registered new interface driver hub Jan 16 08:54:34.484722 kernel: usbcore: registered new device driver usb Jan 16 08:54:34.484753 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (454) Jan 16 08:54:34.484780 kernel: BTRFS: device fsid 5e7921ba-229a-48a0-bc77-9b30aaa34aeb devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (449) Jan 16 08:54:34.302890 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 16 08:54:34.303099 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:34.303822 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:34.319176 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:34.465056 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jan 16 08:54:34.485782 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:34.501999 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jan 16 08:54:34.510431 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jan 16 08:54:34.519247 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jan 16 08:54:34.520094 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jan 16 08:54:34.536969 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 16 08:54:34.546836 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 16 08:54:34.556380 disk-uuid[542]: Primary Header is updated. Jan 16 08:54:34.556380 disk-uuid[542]: Secondary Entries is updated. Jan 16 08:54:34.556380 disk-uuid[542]: Secondary Header is updated. Jan 16 08:54:34.575775 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jan 16 08:54:34.578523 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 16 08:54:34.591603 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jan 16 08:54:34.596084 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Jan 16 08:54:34.611165 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Jan 16 08:54:34.611439 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Jan 16 08:54:34.612047 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Jan 16 08:54:34.612188 kernel: hub 1-0:1.0: USB hub found Jan 16 08:54:34.612731 kernel: hub 1-0:1.0: 2 ports detected Jan 16 08:54:35.602723 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jan 16 08:54:35.603656 disk-uuid[543]: The operation has completed successfully. Jan 16 08:54:35.672454 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 16 08:54:35.672628 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 16 08:54:35.676789 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 16 08:54:35.686721 sh[562]: Success Jan 16 08:54:35.704719 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jan 16 08:54:35.803602 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 16 08:54:35.806738 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 16 08:54:35.809925 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 16 08:54:35.853876 kernel: BTRFS info (device dm-0): first mount of filesystem 5e7921ba-229a-48a0-bc77-9b30aaa34aeb Jan 16 08:54:35.853983 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 16 08:54:35.854018 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jan 16 08:54:35.856222 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 16 08:54:35.859317 kernel: BTRFS info (device dm-0): using free space tree Jan 16 08:54:35.875189 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 16 08:54:35.876849 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 16 08:54:35.891902 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 16 08:54:35.895816 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 16 08:54:35.911623 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e Jan 16 08:54:35.911707 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jan 16 08:54:35.911731 kernel: BTRFS info (device vda6): using free space tree Jan 16 08:54:35.918617 kernel: BTRFS info (device vda6): auto enabling async discard Jan 16 08:54:35.938335 kernel: BTRFS info (device vda6): last unmount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e Jan 16 08:54:35.937857 systemd[1]: mnt-oem.mount: Deactivated successfully. Jan 16 08:54:35.971128 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 16 08:54:35.980913 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 16 08:54:36.066761 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 16 08:54:36.078340 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 16 08:54:36.127520 systemd-networkd[746]: lo: Link UP Jan 16 08:54:36.127656 systemd-networkd[746]: lo: Gained carrier Jan 16 08:54:36.131898 systemd-networkd[746]: Enumeration completed Jan 16 08:54:36.132290 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Jan 16 08:54:36.132295 systemd-networkd[746]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Jan 16 08:54:36.133490 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 16 08:54:36.134423 systemd[1]: Reached target network.target - Network. Jan 16 08:54:36.138995 systemd-networkd[746]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 16 08:54:36.139002 systemd-networkd[746]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 16 08:54:36.139950 systemd-networkd[746]: eth0: Link UP Jan 16 08:54:36.139956 systemd-networkd[746]: eth0: Gained carrier Jan 16 08:54:36.139970 systemd-networkd[746]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Jan 16 08:54:36.145066 systemd-networkd[746]: eth1: Link UP Jan 16 08:54:36.145072 systemd-networkd[746]: eth1: Gained carrier Jan 16 08:54:36.145092 systemd-networkd[746]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 16 08:54:36.163425 systemd-networkd[746]: eth0: DHCPv4 address 64.23.133.99/20, gateway 64.23.128.1 acquired from 169.254.169.253 Jan 16 08:54:36.168699 systemd-networkd[746]: eth1: DHCPv4 address 10.124.0.15/20 acquired from 169.254.169.253 Jan 16 08:54:36.168908 ignition[684]: Ignition 2.20.0 Jan 16 08:54:36.168918 ignition[684]: Stage: fetch-offline Jan 16 08:54:36.168968 ignition[684]: no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:36.172408 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 16 08:54:36.168981 ignition[684]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:36.169128 ignition[684]: parsed url from cmdline: "" Jan 16 08:54:36.169134 ignition[684]: no config URL provided Jan 16 08:54:36.169142 ignition[684]: reading system config file "/usr/lib/ignition/user.ign" Jan 16 08:54:36.169155 ignition[684]: no config at "/usr/lib/ignition/user.ign" Jan 16 08:54:36.169165 ignition[684]: failed to fetch config: resource requires networking Jan 16 08:54:36.169437 ignition[684]: Ignition finished successfully Jan 16 08:54:36.180884 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 16 08:54:36.204109 ignition[756]: Ignition 2.20.0 Jan 16 08:54:36.204946 ignition[756]: Stage: fetch Jan 16 08:54:36.205250 ignition[756]: no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:36.205265 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:36.205390 ignition[756]: parsed url from cmdline: "" Jan 16 08:54:36.205395 ignition[756]: no config URL provided Jan 16 08:54:36.205402 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" Jan 16 08:54:36.205413 ignition[756]: no config at "/usr/lib/ignition/user.ign" Jan 16 08:54:36.205443 ignition[756]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Jan 16 08:54:36.224677 ignition[756]: GET result: OK Jan 16 08:54:36.224803 ignition[756]: parsing config with SHA512: 5c56a79683ae070aa195f775b8ee0a92fb5b759827992d01f7675b1bc2326134706167b2d65876e21b846f7bba7cb5427f666f43287f5351cc31b884206edd1a Jan 16 08:54:36.230950 unknown[756]: fetched base config from "system" Jan 16 08:54:36.230964 unknown[756]: fetched base config from "system" Jan 16 08:54:36.231585 ignition[756]: fetch: fetch complete Jan 16 08:54:36.230971 unknown[756]: fetched user config from "digitalocean" Jan 16 08:54:36.231593 ignition[756]: fetch: fetch passed Jan 16 08:54:36.233625 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 16 08:54:36.231648 ignition[756]: Ignition finished successfully Jan 16 08:54:36.248755 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 16 08:54:36.274020 ignition[763]: Ignition 2.20.0 Jan 16 08:54:36.274066 ignition[763]: Stage: kargs Jan 16 08:54:36.274371 ignition[763]: no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:36.274390 ignition[763]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:36.275673 ignition[763]: kargs: kargs passed Jan 16 08:54:36.275762 ignition[763]: Ignition finished successfully Jan 16 08:54:36.279077 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 16 08:54:36.286819 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 16 08:54:36.326013 ignition[769]: Ignition 2.20.0 Jan 16 08:54:36.326045 ignition[769]: Stage: disks Jan 16 08:54:36.326394 ignition[769]: no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:36.326414 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:36.329274 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 16 08:54:36.327665 ignition[769]: disks: disks passed Jan 16 08:54:36.336925 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 16 08:54:36.327742 ignition[769]: Ignition finished successfully Jan 16 08:54:36.337520 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 16 08:54:36.338089 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 16 08:54:36.338548 systemd[1]: Reached target sysinit.target - System Initialization. Jan 16 08:54:36.339059 systemd[1]: Reached target basic.target - Basic System. Jan 16 08:54:36.348953 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 16 08:54:36.382296 systemd-fsck[777]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jan 16 08:54:36.390267 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 16 08:54:36.394744 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 16 08:54:36.548617 kernel: EXT4-fs (vda9): mounted filesystem 84bcd1b2-5573-4e91-8fd5-f97782397085 r/w with ordered data mode. Quota mode: none. Jan 16 08:54:36.549116 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 16 08:54:36.550304 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 16 08:54:36.555704 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 16 08:54:36.571848 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 16 08:54:36.577071 systemd[1]: Starting flatcar-afterburn-network.service - Flatcar Afterburn network service... Jan 16 08:54:36.584886 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 16 08:54:36.587679 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 16 08:54:36.587721 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 16 08:54:36.590371 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 16 08:54:36.598621 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (785) Jan 16 08:54:36.602652 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e Jan 16 08:54:36.602963 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 16 08:54:36.614510 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jan 16 08:54:36.614622 kernel: BTRFS info (device vda6): using free space tree Jan 16 08:54:36.620607 kernel: BTRFS info (device vda6): auto enabling async discard Jan 16 08:54:36.625085 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 16 08:54:36.705691 initrd-setup-root[815]: cut: /sysroot/etc/passwd: No such file or directory Jan 16 08:54:36.717271 initrd-setup-root[822]: cut: /sysroot/etc/group: No such file or directory Jan 16 08:54:36.719284 coreos-metadata[787]: Jan 16 08:54:36.718 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Jan 16 08:54:36.724633 coreos-metadata[788]: Jan 16 08:54:36.724 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Jan 16 08:54:36.727716 initrd-setup-root[829]: cut: /sysroot/etc/shadow: No such file or directory Jan 16 08:54:36.731198 coreos-metadata[787]: Jan 16 08:54:36.731 INFO Fetch successful Jan 16 08:54:36.736902 initrd-setup-root[836]: cut: /sysroot/etc/gshadow: No such file or directory Jan 16 08:54:36.739078 coreos-metadata[788]: Jan 16 08:54:36.739 INFO Fetch successful Jan 16 08:54:36.742872 systemd[1]: flatcar-afterburn-network.service: Deactivated successfully. Jan 16 08:54:36.743032 systemd[1]: Finished flatcar-afterburn-network.service - Flatcar Afterburn network service. Jan 16 08:54:36.748895 coreos-metadata[788]: Jan 16 08:54:36.748 INFO wrote hostname ci-4152.2.0-7-785042947c to /sysroot/etc/hostname Jan 16 08:54:36.751654 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 16 08:54:36.879041 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 16 08:54:36.891895 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 16 08:54:36.897062 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 16 08:54:36.910616 kernel: BTRFS info (device vda6): last unmount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e Jan 16 08:54:36.910486 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 16 08:54:36.946269 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 16 08:54:36.957431 ignition[905]: INFO : Ignition 2.20.0 Jan 16 08:54:36.957431 ignition[905]: INFO : Stage: mount Jan 16 08:54:36.959003 ignition[905]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:36.959003 ignition[905]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:36.959003 ignition[905]: INFO : mount: mount passed Jan 16 08:54:36.961044 ignition[905]: INFO : Ignition finished successfully Jan 16 08:54:36.960476 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 16 08:54:36.969724 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 16 08:54:36.980658 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 16 08:54:37.015642 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (917) Jan 16 08:54:37.019012 kernel: BTRFS info (device vda6): first mount of filesystem 1066b41d-395d-4ccb-b5ae-be36ea0fc11e Jan 16 08:54:37.019097 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jan 16 08:54:37.020803 kernel: BTRFS info (device vda6): using free space tree Jan 16 08:54:37.026627 kernel: BTRFS info (device vda6): auto enabling async discard Jan 16 08:54:37.030281 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 16 08:54:37.070270 ignition[934]: INFO : Ignition 2.20.0 Jan 16 08:54:37.071170 ignition[934]: INFO : Stage: files Jan 16 08:54:37.072008 ignition[934]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:37.073592 ignition[934]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:37.074673 ignition[934]: DEBUG : files: compiled without relabeling support, skipping Jan 16 08:54:37.076129 ignition[934]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 16 08:54:37.076129 ignition[934]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 16 08:54:37.080806 ignition[934]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 16 08:54:37.081950 ignition[934]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 16 08:54:37.083235 ignition[934]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 16 08:54:37.083133 unknown[934]: wrote ssh authorized keys file for user: core Jan 16 08:54:37.085438 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jan 16 08:54:37.085438 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jan 16 08:54:37.089119 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 16 08:54:37.089119 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 16 08:54:37.089119 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 16 08:54:37.089119 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 16 08:54:37.089119 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 16 08:54:37.089119 ignition[934]: 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 Jan 16 08:54:37.352879 systemd-networkd[746]: eth1: Gained IPv6LL Jan 16 08:54:37.586826 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jan 16 08:54:37.879658 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 16 08:54:37.881134 ignition[934]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 16 08:54:37.881134 ignition[934]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 16 08:54:37.881134 ignition[934]: INFO : files: files passed Jan 16 08:54:37.881134 ignition[934]: INFO : Ignition finished successfully Jan 16 08:54:37.881684 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 16 08:54:37.891879 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 16 08:54:37.899821 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 16 08:54:37.901069 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 16 08:54:37.903190 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 16 08:54:37.929166 initrd-setup-root-after-ignition[962]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 16 08:54:37.929166 initrd-setup-root-after-ignition[962]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 16 08:54:37.932680 initrd-setup-root-after-ignition[966]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 16 08:54:37.934474 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 16 08:54:37.937084 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 16 08:54:37.949908 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 16 08:54:38.000743 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 16 08:54:38.000985 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 16 08:54:38.003438 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 16 08:54:38.004184 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 16 08:54:38.005498 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 16 08:54:38.009839 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 16 08:54:38.038277 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 16 08:54:38.044899 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 16 08:54:38.074524 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 16 08:54:38.075692 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 16 08:54:38.077254 systemd[1]: Stopped target timers.target - Timer Units. Jan 16 08:54:38.078457 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 16 08:54:38.078722 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 16 08:54:38.080360 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 16 08:54:38.081195 systemd[1]: Stopped target basic.target - Basic System. Jan 16 08:54:38.082519 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 16 08:54:38.083856 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 16 08:54:38.084998 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 16 08:54:38.086266 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 16 08:54:38.087544 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 16 08:54:38.088994 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 16 08:54:38.090347 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 16 08:54:38.091682 systemd[1]: Stopped target swap.target - Swaps. Jan 16 08:54:38.093074 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 16 08:54:38.093371 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 16 08:54:38.095096 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 16 08:54:38.096556 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 16 08:54:38.097778 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 16 08:54:38.098068 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 16 08:54:38.099220 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 16 08:54:38.099506 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 16 08:54:38.101067 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 16 08:54:38.101413 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 16 08:54:38.102807 systemd[1]: ignition-files.service: Deactivated successfully. Jan 16 08:54:38.103062 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 16 08:54:38.104097 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 16 08:54:38.104317 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 16 08:54:38.120849 systemd-networkd[746]: eth0: Gained IPv6LL Jan 16 08:54:38.123050 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 16 08:54:38.124674 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 16 08:54:38.124936 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 16 08:54:38.131159 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 16 08:54:38.131975 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 16 08:54:38.132266 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 16 08:54:38.135972 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 16 08:54:38.136170 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 16 08:54:38.146797 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 16 08:54:38.148936 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 16 08:54:38.152600 ignition[986]: INFO : Ignition 2.20.0 Jan 16 08:54:38.152600 ignition[986]: INFO : Stage: umount Jan 16 08:54:38.155091 ignition[986]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 16 08:54:38.155091 ignition[986]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Jan 16 08:54:38.159857 ignition[986]: INFO : umount: umount passed Jan 16 08:54:38.159857 ignition[986]: INFO : Ignition finished successfully Jan 16 08:54:38.159120 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 16 08:54:38.159328 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 16 08:54:38.160770 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 16 08:54:38.160850 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 16 08:54:38.161590 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 16 08:54:38.161659 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 16 08:54:38.162776 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 16 08:54:38.162853 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 16 08:54:38.164108 systemd[1]: Stopped target network.target - Network. Jan 16 08:54:38.165674 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 16 08:54:38.165774 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 16 08:54:38.166705 systemd[1]: Stopped target paths.target - Path Units. Jan 16 08:54:38.167320 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 16 08:54:38.170686 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 16 08:54:38.171762 systemd[1]: Stopped target slices.target - Slice Units. Jan 16 08:54:38.172934 systemd[1]: Stopped target sockets.target - Socket Units. Jan 16 08:54:38.174212 systemd[1]: iscsid.socket: Deactivated successfully. Jan 16 08:54:38.174285 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 16 08:54:38.175734 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 16 08:54:38.175800 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 16 08:54:38.176700 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 16 08:54:38.176792 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 16 08:54:38.180070 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 16 08:54:38.180164 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 16 08:54:38.181617 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 16 08:54:38.182842 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 16 08:54:38.185691 systemd-networkd[746]: eth0: DHCPv6 lease lost Jan 16 08:54:38.189746 systemd-networkd[746]: eth1: DHCPv6 lease lost Jan 16 08:54:38.193085 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 16 08:54:38.193481 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 16 08:54:38.199879 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 16 08:54:38.200065 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 16 08:54:38.208635 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 16 08:54:38.212960 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 16 08:54:38.213065 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 16 08:54:38.223875 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 16 08:54:38.225034 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 16 08:54:38.225147 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 16 08:54:38.228554 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 16 08:54:38.228681 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 16 08:54:38.230202 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 16 08:54:38.230290 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 16 08:54:38.231430 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 16 08:54:38.231503 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 16 08:54:38.233016 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 16 08:54:38.237263 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 16 08:54:38.237442 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 16 08:54:38.247492 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 16 08:54:38.247893 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 16 08:54:38.253144 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 16 08:54:38.253249 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 16 08:54:38.254224 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 16 08:54:38.254288 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 16 08:54:38.255725 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 16 08:54:38.255812 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 16 08:54:38.257874 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 16 08:54:38.258013 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 16 08:54:38.259431 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 16 08:54:38.259521 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 16 08:54:38.260829 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 16 08:54:38.260918 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 16 08:54:38.267938 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 16 08:54:38.269798 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 16 08:54:38.269919 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 16 08:54:38.270688 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 16 08:54:38.270770 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:38.272289 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 16 08:54:38.272480 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 16 08:54:38.287134 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 16 08:54:38.287319 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 16 08:54:38.289533 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 16 08:54:38.296894 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 16 08:54:38.312176 systemd[1]: Switching root. Jan 16 08:54:38.418565 systemd-journald[183]: Journal stopped Jan 16 08:54:39.874097 systemd-journald[183]: Received SIGTERM from PID 1 (systemd). Jan 16 08:54:39.874186 kernel: SELinux: policy capability network_peer_controls=1 Jan 16 08:54:39.874235 kernel: SELinux: policy capability open_perms=1 Jan 16 08:54:39.874266 kernel: SELinux: policy capability extended_socket_class=1 Jan 16 08:54:39.874291 kernel: SELinux: policy capability always_check_network=0 Jan 16 08:54:39.874315 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 16 08:54:39.874345 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 16 08:54:39.874369 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 16 08:54:39.874394 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 16 08:54:39.874424 kernel: audit: type=1403 audit(1737017678.604:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 16 08:54:39.874463 systemd[1]: Successfully loaded SELinux policy in 49.863ms. Jan 16 08:54:39.874500 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 18.529ms. Jan 16 08:54:39.874533 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Jan 16 08:54:39.874565 systemd[1]: Detected virtualization kvm. Jan 16 08:54:39.874628 systemd[1]: Detected architecture x86-64. Jan 16 08:54:39.874655 systemd[1]: Detected first boot. Jan 16 08:54:39.874682 systemd[1]: Hostname set to . Jan 16 08:54:39.874709 systemd[1]: Initializing machine ID from VM UUID. Jan 16 08:54:39.874736 zram_generator::config[1030]: No configuration found. Jan 16 08:54:39.874767 systemd[1]: Populated /etc with preset unit settings. Jan 16 08:54:39.874795 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 16 08:54:39.874822 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 16 08:54:39.874848 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 16 08:54:39.874877 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 16 08:54:39.874905 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 16 08:54:39.874931 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 16 08:54:39.874958 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 16 08:54:39.874988 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 16 08:54:39.875018 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 16 08:54:39.875043 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 16 08:54:39.875067 systemd[1]: Created slice user.slice - User and Session Slice. Jan 16 08:54:39.875103 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 16 08:54:39.875133 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 16 08:54:39.875161 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 16 08:54:39.875183 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 16 08:54:39.875215 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 16 08:54:39.875256 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 16 08:54:39.875277 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 16 08:54:39.875306 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 16 08:54:39.875332 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 16 08:54:39.875362 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 16 08:54:39.875385 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 16 08:54:39.875410 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 16 08:54:39.875438 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 16 08:54:39.875463 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 16 08:54:39.875490 systemd[1]: Reached target slices.target - Slice Units. Jan 16 08:54:39.875519 systemd[1]: Reached target swap.target - Swaps. Jan 16 08:54:39.875546 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 16 08:54:39.875594 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 16 08:54:39.875622 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 16 08:54:39.875648 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 16 08:54:39.875682 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 16 08:54:39.875730 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 16 08:54:39.875755 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 16 08:54:39.875779 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 16 08:54:39.875812 systemd[1]: Mounting media.mount - External Media Directory... Jan 16 08:54:39.875839 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:39.875865 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 16 08:54:39.875899 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 16 08:54:39.875924 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 16 08:54:39.875966 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). Jan 16 08:54:39.875998 systemd[1]: Reached target machines.target - Containers. Jan 16 08:54:39.876025 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 16 08:54:39.876052 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 16 08:54:39.876078 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 16 08:54:39.876106 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 16 08:54:39.876136 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 16 08:54:39.876164 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 16 08:54:39.876199 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 16 08:54:39.876226 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 16 08:54:39.876253 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 16 08:54:39.876282 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 16 08:54:39.876309 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 16 08:54:39.876347 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 16 08:54:39.876377 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 16 08:54:39.876405 systemd[1]: Stopped systemd-fsck-usr.service. Jan 16 08:54:39.876438 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 16 08:54:39.876469 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 16 08:54:39.876496 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 16 08:54:39.876523 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 16 08:54:39.876628 systemd-journald[1103]: Collecting audit messages is disabled. Jan 16 08:54:39.876690 systemd-journald[1103]: Journal started Jan 16 08:54:39.876749 systemd-journald[1103]: Runtime Journal (/run/log/journal/e5a45872635d4d13a41ac394ffe9f5d5) is 4.9M, max 39.3M, 34.4M free. Jan 16 08:54:39.520213 systemd[1]: Queued start job for default target multi-user.target. Jan 16 08:54:39.542704 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jan 16 08:54:39.543391 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 16 08:54:39.900781 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 16 08:54:39.900894 systemd[1]: verity-setup.service: Deactivated successfully. Jan 16 08:54:39.900922 systemd[1]: Stopped verity-setup.service. Jan 16 08:54:39.900946 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:39.902606 kernel: loop: module loaded Jan 16 08:54:39.905660 kernel: ACPI: bus type drm_connector registered Jan 16 08:54:39.909699 systemd[1]: Started systemd-journald.service - Journal Service. Jan 16 08:54:39.912414 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 16 08:54:39.915982 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 16 08:54:39.916880 systemd[1]: Mounted media.mount - External Media Directory. Jan 16 08:54:39.919506 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 16 08:54:39.920457 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 16 08:54:39.921409 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 16 08:54:39.924054 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 16 08:54:39.925199 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 16 08:54:39.926727 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 16 08:54:39.927720 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 16 08:54:39.927878 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 16 08:54:39.929250 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 16 08:54:39.932975 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 16 08:54:39.934000 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 16 08:54:39.934159 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 16 08:54:39.935218 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 16 08:54:39.935389 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 16 08:54:39.936622 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 16 08:54:39.942568 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 16 08:54:39.943559 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 16 08:54:39.951615 kernel: fuse: init (API version 7.39) Jan 16 08:54:39.954438 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 16 08:54:39.956804 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 16 08:54:39.968952 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 16 08:54:39.982537 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 16 08:54:39.990670 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 16 08:54:39.991419 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 16 08:54:39.991472 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 16 08:54:39.995048 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Jan 16 08:54:40.003776 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 16 08:54:40.013824 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 16 08:54:40.014800 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 16 08:54:40.018774 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 16 08:54:40.025843 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 16 08:54:40.027010 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 16 08:54:40.028719 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 16 08:54:40.029874 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 16 08:54:40.034793 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 16 08:54:40.049284 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 16 08:54:40.051962 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 16 08:54:40.053421 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 16 08:54:40.055823 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 16 08:54:40.056821 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 16 08:54:40.064857 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 16 08:54:40.071724 systemd-journald[1103]: Time spent on flushing to /var/log/journal/e5a45872635d4d13a41ac394ffe9f5d5 is 113.208ms for 970 entries. Jan 16 08:54:40.071724 systemd-journald[1103]: System Journal (/var/log/journal/e5a45872635d4d13a41ac394ffe9f5d5) is 8.0M, max 195.6M, 187.6M free. Jan 16 08:54:40.210385 systemd-journald[1103]: Received client request to flush runtime journal. Jan 16 08:54:40.210460 kernel: loop0: detected capacity change from 0 to 205544 Jan 16 08:54:40.210488 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 16 08:54:40.126720 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 16 08:54:40.128268 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 16 08:54:40.146749 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Jan 16 08:54:40.149635 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 16 08:54:40.162862 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jan 16 08:54:40.179290 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 16 08:54:40.193107 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 16 08:54:40.220987 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 16 08:54:40.222543 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 16 08:54:40.237889 udevadm[1160]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jan 16 08:54:40.246802 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 16 08:54:40.248623 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Jan 16 08:54:40.256741 kernel: loop1: detected capacity change from 0 to 140992 Jan 16 08:54:40.284194 systemd-tmpfiles[1166]: ACLs are not supported, ignoring. Jan 16 08:54:40.284238 systemd-tmpfiles[1166]: ACLs are not supported, ignoring. Jan 16 08:54:40.294564 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 16 08:54:40.309703 kernel: loop2: detected capacity change from 0 to 8 Jan 16 08:54:40.331603 kernel: loop3: detected capacity change from 0 to 138184 Jan 16 08:54:40.442599 kernel: loop4: detected capacity change from 0 to 205544 Jan 16 08:54:40.470865 kernel: loop5: detected capacity change from 0 to 140992 Jan 16 08:54:40.503605 kernel: loop6: detected capacity change from 0 to 8 Jan 16 08:54:40.511602 kernel: loop7: detected capacity change from 0 to 138184 Jan 16 08:54:40.570798 (sd-merge)[1175]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Jan 16 08:54:40.571713 (sd-merge)[1175]: Merged extensions into '/usr'. Jan 16 08:54:40.579641 systemd[1]: Reloading requested from client PID 1149 ('systemd-sysext') (unit systemd-sysext.service)... Jan 16 08:54:40.579790 systemd[1]: Reloading... Jan 16 08:54:40.765496 zram_generator::config[1201]: No configuration found. Jan 16 08:54:41.048784 ldconfig[1144]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 16 08:54:41.056990 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. Jan 16 08:54:41.148083 systemd[1]: Reloading finished in 566 ms. Jan 16 08:54:41.175275 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 16 08:54:41.179424 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 16 08:54:41.188960 systemd[1]: Starting ensure-sysext.service... Jan 16 08:54:41.196885 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 16 08:54:41.215836 systemd[1]: Reloading requested from client PID 1244 ('systemctl') (unit ensure-sysext.service)... Jan 16 08:54:41.215856 systemd[1]: Reloading... Jan 16 08:54:41.255047 systemd-tmpfiles[1245]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 16 08:54:41.255412 systemd-tmpfiles[1245]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 16 08:54:41.256354 systemd-tmpfiles[1245]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 16 08:54:41.256769 systemd-tmpfiles[1245]: ACLs are not supported, ignoring. Jan 16 08:54:41.256837 systemd-tmpfiles[1245]: ACLs are not supported, ignoring. Jan 16 08:54:41.261403 systemd-tmpfiles[1245]: Detected autofs mount point /boot during canonicalization of boot. Jan 16 08:54:41.261419 systemd-tmpfiles[1245]: Skipping /boot Jan 16 08:54:41.302362 systemd-tmpfiles[1245]: Detected autofs mount point /boot during canonicalization of boot. Jan 16 08:54:41.302379 systemd-tmpfiles[1245]: Skipping /boot Jan 16 08:54:41.326672 zram_generator::config[1268]: No configuration found. Jan 16 08:54:41.473038 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. Jan 16 08:54:41.574326 systemd[1]: Reloading finished in 357 ms. Jan 16 08:54:41.594793 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 16 08:54:41.601260 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 16 08:54:41.619085 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 16 08:54:41.626104 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 16 08:54:41.634995 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 16 08:54:41.640189 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 16 08:54:41.648107 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 16 08:54:41.658099 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 16 08:54:41.665885 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.666224 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 16 08:54:41.680665 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 16 08:54:41.686086 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 16 08:54:41.698126 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 16 08:54:41.699826 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 16 08:54:41.710035 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 16 08:54:41.710879 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.717744 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.718222 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 16 08:54:41.718542 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 16 08:54:41.719349 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.729367 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.730976 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 16 08:54:41.740037 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 16 08:54:41.745037 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 16 08:54:41.745305 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:41.754505 systemd[1]: Finished ensure-sysext.service. Jan 16 08:54:41.756707 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 16 08:54:41.759747 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 16 08:54:41.759994 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 16 08:54:41.767673 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 16 08:54:41.776222 systemd-udevd[1327]: Using default interface naming scheme 'v255'. Jan 16 08:54:41.788866 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jan 16 08:54:41.790709 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). Jan 16 08:54:41.802821 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 16 08:54:41.803071 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 16 08:54:41.804454 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 16 08:54:41.804717 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 16 08:54:41.807222 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 16 08:54:41.807442 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 16 08:54:41.814123 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 16 08:54:41.814232 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 16 08:54:41.820685 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 16 08:54:41.831884 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 16 08:54:41.848335 augenrules[1357]: No rules Jan 16 08:54:41.850935 systemd[1]: audit-rules.service: Deactivated successfully. Jan 16 08:54:41.851681 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 16 08:54:41.855737 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 16 08:54:41.865964 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 16 08:54:41.912696 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 16 08:54:41.940114 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 16 08:54:42.000640 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1374) Jan 16 08:54:42.060905 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Jan 16 08:54:42.061517 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:42.061866 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 16 08:54:42.071884 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 16 08:54:42.075825 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 16 08:54:42.083973 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 16 08:54:42.084899 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 16 08:54:42.085133 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). Jan 16 08:54:42.085263 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 16 08:54:42.085671 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 16 08:54:42.126778 systemd-networkd[1368]: lo: Link UP Jan 16 08:54:42.126789 systemd-networkd[1368]: lo: Gained carrier Jan 16 08:54:42.138643 kernel: ISO 9660 Extensions: RRIP_1991A Jan 16 08:54:42.132266 systemd-networkd[1368]: Enumeration completed Jan 16 08:54:42.132473 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 16 08:54:42.133890 systemd-networkd[1368]: eth0: Configuring with /run/systemd/network/10-ca:77:d8:d7:51:61.network. Jan 16 08:54:42.135891 systemd-networkd[1368]: eth0: Link UP Jan 16 08:54:42.135898 systemd-networkd[1368]: eth0: Gained carrier Jan 16 08:54:42.144792 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 16 08:54:42.150765 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Jan 16 08:54:42.174861 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jan 16 08:54:42.179564 systemd[1]: Reached target time-set.target - System Time Set. Jan 16 08:54:42.181929 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 16 08:54:42.183687 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 16 08:54:42.184876 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 16 08:54:42.185393 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 16 08:54:42.188341 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 16 08:54:42.188686 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 16 08:54:42.192860 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 16 08:54:42.192961 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 16 08:54:42.243811 systemd-resolved[1326]: Positive Trust Anchors: Jan 16 08:54:42.243832 systemd-resolved[1326]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 16 08:54:42.243923 systemd-resolved[1326]: 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 Jan 16 08:54:42.253193 systemd-resolved[1326]: Using system hostname 'ci-4152.2.0-7-785042947c'. Jan 16 08:54:43.112001 systemd-timesyncd[1350]: Contacted time server 204.2.134.163:123 (0.flatcar.pool.ntp.org). Jan 16 08:54:43.112072 systemd-timesyncd[1350]: Initial clock synchronization to Thu 2025-01-16 08:54:43.111872 UTC. Jan 16 08:54:43.113488 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 16 08:54:43.113557 systemd-resolved[1326]: Clock change detected. Flushing caches. Jan 16 08:54:43.114678 systemd[1]: Reached target network.target - Network. Jan 16 08:54:43.116513 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 16 08:54:43.164407 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jan 16 08:54:43.171650 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 16 08:54:43.182384 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Jan 16 08:54:43.196771 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Jan 16 08:54:43.203384 kernel: ACPI: button: Power Button [PWRF] Jan 16 08:54:43.216514 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 16 08:54:43.227222 systemd-networkd[1368]: eth1: Configuring with /run/systemd/network/10-3a:b8:de:45:40:08.network. Jan 16 08:54:43.228463 systemd-networkd[1368]: eth1: Link UP Jan 16 08:54:43.228477 systemd-networkd[1368]: eth1: Gained carrier Jan 16 08:54:43.274854 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Jan 16 08:54:43.321190 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Jan 16 08:54:43.321300 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Jan 16 08:54:43.328629 kernel: Console: switching to colour dummy device 80x25 Jan 16 08:54:43.329731 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Jan 16 08:54:43.329861 kernel: [drm] features: -context_init Jan 16 08:54:43.333609 kernel: [drm] number of scanouts: 1 Jan 16 08:54:43.333706 kernel: [drm] number of cap sets: 0 Jan 16 08:54:43.343394 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Jan 16 08:54:43.362917 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:43.366654 kernel: mousedev: PS/2 mouse device common for all mice Jan 16 08:54:43.378074 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 16 08:54:43.383641 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Jan 16 08:54:43.383741 kernel: Console: switching to colour frame buffer device 128x48 Jan 16 08:54:43.379380 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:43.395603 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:43.401231 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Jan 16 08:54:43.430719 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 16 08:54:43.431022 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:43.458030 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 16 08:54:43.621787 kernel: EDAC MC: Ver: 3.0.0 Jan 16 08:54:43.653738 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jan 16 08:54:43.655606 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 16 08:54:43.661785 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jan 16 08:54:43.698451 lvm[1430]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jan 16 08:54:43.740401 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jan 16 08:54:43.741180 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 16 08:54:43.741400 systemd[1]: Reached target sysinit.target - System Initialization. Jan 16 08:54:43.741809 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 16 08:54:43.742025 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 16 08:54:43.742730 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 16 08:54:43.743817 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 16 08:54:43.743966 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 16 08:54:43.744074 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 16 08:54:43.744121 systemd[1]: Reached target paths.target - Path Units. Jan 16 08:54:43.744238 systemd[1]: Reached target timers.target - Timer Units. Jan 16 08:54:43.746287 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 16 08:54:43.749732 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 16 08:54:43.758327 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 16 08:54:43.760653 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jan 16 08:54:43.762219 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 16 08:54:43.765941 systemd[1]: Reached target sockets.target - Socket Units. Jan 16 08:54:43.766597 systemd[1]: Reached target basic.target - Basic System. Jan 16 08:54:43.767277 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 16 08:54:43.767317 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 16 08:54:43.774608 systemd[1]: Starting containerd.service - containerd container runtime... Jan 16 08:54:43.781425 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 16 08:54:43.788410 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 16 08:54:43.791637 lvm[1434]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jan 16 08:54:43.793064 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 16 08:54:43.803680 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 16 08:54:43.806145 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). Jan 16 08:54:43.816628 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 16 08:54:43.824636 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 16 08:54:43.830641 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 16 08:54:43.847463 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 16 08:54:43.850840 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 16 08:54:43.853769 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 16 08:54:43.863668 systemd[1]: Starting update-engine.service - Update Engine... Jan 16 08:54:43.870556 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 16 08:54:43.876494 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jan 16 08:54:43.897812 jq[1438]: false Jan 16 08:54:43.902762 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 16 08:54:43.911748 coreos-metadata[1436]: Jan 16 08:54:43.907 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Jan 16 08:54:43.927600 jq[1448]: true Jan 16 08:54:43.903062 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 16 08:54:43.927007 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 16 08:54:43.935321 coreos-metadata[1436]: Jan 16 08:54:43.931 INFO Fetch successful Jan 16 08:54:43.928471 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 16 08:54:43.973583 jq[1455]: true Jan 16 08:54:43.985306 update_engine[1447]: I20250116 08:54:43.981851 1447 main.cc:92] Flatcar Update Engine starting Jan 16 08:54:44.015760 systemd[1]: motdgen.service: Deactivated successfully. Jan 16 08:54:44.015914 (ntainerd)[1465]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 16 08:54:44.016149 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 16 08:54:44.027502 dbus-daemon[1437]: [system] SELinux support is enabled Jan 16 08:54:44.041697 extend-filesystems[1439]: Found loop4 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found loop5 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found loop6 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found loop7 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda1 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda2 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda3 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found usr Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda4 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda6 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda7 Jan 16 08:54:44.041697 extend-filesystems[1439]: Found vda9 Jan 16 08:54:44.031633 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 16 08:54:44.100149 update_engine[1447]: I20250116 08:54:44.041716 1447 update_check_scheduler.cc:74] Next update check in 2m53s Jan 16 08:54:44.100211 extend-filesystems[1439]: Checking size of /dev/vda9 Jan 16 08:54:44.044721 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 16 08:54:44.050264 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 16 08:54:44.050864 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). Jan 16 08:54:44.050920 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 16 08:54:44.056667 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). Jan 16 08:54:44.056804 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Jan 16 08:54:44.056836 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 16 08:54:44.069141 systemd[1]: Started update-engine.service - Update Engine. Jan 16 08:54:44.095690 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 16 08:54:44.114661 extend-filesystems[1439]: Resized partition /dev/vda9 Jan 16 08:54:44.123427 extend-filesystems[1484]: resize2fs 1.47.1 (20-May-2024) Jan 16 08:54:44.158473 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Jan 16 08:54:44.231262 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1366) Jan 16 08:54:44.287426 systemd-logind[1445]: New seat seat0. Jan 16 08:54:44.299867 systemd-networkd[1368]: eth0: Gained IPv6LL Jan 16 08:54:44.311336 systemd-logind[1445]: Watching system buttons on /dev/input/event1 (Power Button) Jan 16 08:54:44.311414 systemd-logind[1445]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 16 08:54:44.311942 systemd[1]: Started systemd-logind.service - User Login Management. Jan 16 08:54:44.330211 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 16 08:54:44.337711 systemd[1]: Reached target network-online.target - Network is Online. Jan 16 08:54:44.352840 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 16 08:54:44.361833 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 16 08:54:44.369455 bash[1495]: Updated "/home/core/.ssh/authorized_keys" Jan 16 08:54:44.371166 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 16 08:54:44.394978 systemd[1]: Starting sshkeys.service... Jan 16 08:54:44.477949 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Jan 16 08:54:44.467416 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Jan 16 08:54:44.478380 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Jan 16 08:54:44.528201 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 16 08:54:44.538816 locksmithd[1478]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 16 08:54:44.588053 coreos-metadata[1510]: Jan 16 08:54:44.565 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Jan 16 08:54:44.588053 coreos-metadata[1510]: Jan 16 08:54:44.578 INFO Fetch successful Jan 16 08:54:44.595929 extend-filesystems[1484]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jan 16 08:54:44.595929 extend-filesystems[1484]: old_desc_blocks = 1, new_desc_blocks = 8 Jan 16 08:54:44.595929 extend-filesystems[1484]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Jan 16 08:54:44.623700 extend-filesystems[1439]: Resized filesystem in /dev/vda9 Jan 16 08:54:44.623700 extend-filesystems[1439]: Found vdb Jan 16 08:54:44.598425 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 16 08:54:44.635583 sshd_keygen[1473]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 16 08:54:44.598723 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 16 08:54:44.599513 unknown[1510]: wrote ssh authorized keys file for user: core Jan 16 08:54:44.699930 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 16 08:54:44.711823 update-ssh-keys[1530]: Updated "/home/core/.ssh/authorized_keys" Jan 16 08:54:44.719600 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 16 08:54:44.727280 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Jan 16 08:54:44.736631 systemd[1]: Finished sshkeys.service. Jan 16 08:54:44.768959 systemd[1]: issuegen.service: Deactivated successfully. Jan 16 08:54:44.771319 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 16 08:54:44.786551 containerd[1465]: time="2025-01-16T08:54:44.783721693Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jan 16 08:54:44.788507 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 16 08:54:44.835843 containerd[1465]: time="2025-01-16T08:54:44.835724077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839137541Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.71-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839256004Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839285206Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839576821Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839610063Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839696096Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839718172Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.839986622Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.840010849Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.840032540Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841136 containerd[1465]: time="2025-01-16T08:54:44.840045870Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.839546 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840175787Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840447922Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840643064Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840666618Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840805832Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jan 16 08:54:44.841890 containerd[1465]: time="2025-01-16T08:54:44.840888969Z" level=info msg="metadata content store policy set" policy=shared Jan 16 08:54:44.851034 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 16 08:54:44.859969 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 16 08:54:44.860880 systemd[1]: Reached target getty.target - Login Prompts. Jan 16 08:54:44.865491 systemd-networkd[1368]: eth1: Gained IPv6LL Jan 16 08:54:44.865695 containerd[1465]: time="2025-01-16T08:54:44.865632493Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jan 16 08:54:44.865764 containerd[1465]: time="2025-01-16T08:54:44.865731555Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jan 16 08:54:44.865837 containerd[1465]: time="2025-01-16T08:54:44.865761963Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jan 16 08:54:44.865837 containerd[1465]: time="2025-01-16T08:54:44.865785798Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jan 16 08:54:44.865837 containerd[1465]: time="2025-01-16T08:54:44.865807930Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866012274Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866407515Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866564298Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866584610Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866605720Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866627106Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866646621Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866667999Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866688011Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866707964Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866727957Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866756007Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866771848Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jan 16 08:54:44.868404 containerd[1465]: time="2025-01-16T08:54:44.866799960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.869182 containerd[1465]: time="2025-01-16T08:54:44.866817788Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.869182 containerd[1465]: time="2025-01-16T08:54:44.866835248Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870710341Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870787127Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870819532Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870840942Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870869597Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870902366Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870936751Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.870962474Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871106054Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871139966Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871171446Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871219142Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871248998Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.872932 containerd[1465]: time="2025-01-16T08:54:44.871271794Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jan 16 08:54:44.873776 containerd[1465]: time="2025-01-16T08:54:44.871352898Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jan 16 08:54:44.873776 containerd[1465]: time="2025-01-16T08:54:44.873691923Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Jan 16 08:54:44.873776 containerd[1465]: time="2025-01-16T08:54:44.873724524Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jan 16 08:54:44.873776 containerd[1465]: time="2025-01-16T08:54:44.873748948Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jan 16 08:54:44.873974 containerd[1465]: time="2025-01-16T08:54:44.873795154Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.873974 containerd[1465]: time="2025-01-16T08:54:44.873823629Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jan 16 08:54:44.873974 containerd[1465]: time="2025-01-16T08:54:44.873851773Z" level=info msg="NRI interface is disabled by configuration." Jan 16 08:54:44.873974 containerd[1465]: time="2025-01-16T08:54:44.873872759Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jan 16 08:54:44.874785 containerd[1465]: time="2025-01-16T08:54:44.874681006Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jan 16 08:54:44.874785 containerd[1465]: time="2025-01-16T08:54:44.874768926Z" level=info msg="Connect containerd service" Jan 16 08:54:44.875160 containerd[1465]: time="2025-01-16T08:54:44.874817700Z" level=info msg="using legacy CRI server" Jan 16 08:54:44.875160 containerd[1465]: time="2025-01-16T08:54:44.874827795Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 16 08:54:44.875160 containerd[1465]: time="2025-01-16T08:54:44.875014660Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jan 16 08:54:44.876059 containerd[1465]: time="2025-01-16T08:54:44.876003695Z" 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" Jan 16 08:54:44.877201 containerd[1465]: time="2025-01-16T08:54:44.877167333Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 16 08:54:44.877746 containerd[1465]: time="2025-01-16T08:54:44.877316832Z" level=info msg="Start subscribing containerd event" Jan 16 08:54:44.877746 containerd[1465]: time="2025-01-16T08:54:44.877640999Z" level=info msg="Start recovering state" Jan 16 08:54:44.878257 containerd[1465]: time="2025-01-16T08:54:44.878066623Z" level=info msg="Start event monitor" Jan 16 08:54:44.878257 containerd[1465]: time="2025-01-16T08:54:44.878129628Z" level=info msg="Start snapshots syncer" Jan 16 08:54:44.878257 containerd[1465]: time="2025-01-16T08:54:44.878148276Z" level=info msg="Start cni network conf syncer for default" Jan 16 08:54:44.878257 containerd[1465]: time="2025-01-16T08:54:44.878176266Z" level=info msg="Start streaming server" Jan 16 08:54:44.878817 containerd[1465]: time="2025-01-16T08:54:44.878733319Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 16 08:54:44.879120 systemd[1]: Started containerd.service - containerd container runtime. Jan 16 08:54:44.882440 containerd[1465]: time="2025-01-16T08:54:44.882348378Z" level=info msg="containerd successfully booted in 0.099789s" Jan 16 08:54:46.130765 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jan 16 08:54:46.133839 (kubelet)[1554]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jan 16 08:54:46.135001 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 16 08:54:46.140535 systemd[1]: Startup finished in 1.357s (kernel) + 5.865s (initrd) + 6.728s (userspace) = 13.952s. Jan 16 08:54:47.044530 kubelet[1554]: E0116 08:54:47.044450 1554 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" Jan 16 08:54:47.047271 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jan 16 08:54:47.047541 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jan 16 08:54:47.048034 systemd[1]: kubelet.service: Consumed 1.399s CPU time. Jan 16 08:54:53.743032 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 16 08:54:53.748743 systemd[1]: Started sshd@0-64.23.133.99:22-147.75.109.163:48292.service - OpenSSH per-connection server daemon (147.75.109.163:48292). Jan 16 08:54:53.863332 sshd[1566]: Accepted publickey for core from 147.75.109.163 port 48292 ssh2: RSA SHA256:JFCq2iHRoEjWAf+XB9dAYBdNgVKarxLIY/Gd6UT86ms Jan 16 08:54:53.866984 sshd-session[1566]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 16 08:54:53.882692 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 16 08:54:53.892352 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 16 08:54:53.899201 systemd-logind[1445]: New session 1 of user core. Jan 16 08:54:53.915193 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 16 08:54:53.923956 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 16 08:54:53.938997 (systemd)[1570]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 16 08:54:54.135028 systemd[1570]: Queued start job for default target default.target. Jan 16 08:54:54.147921 systemd[1570]: Created slice app.slice - User Application Slice. Jan 16 08:54:54.147973 systemd[1570]: Reached target paths.target - Paths. Jan 16 08:54:54.148001 systemd[1570]: Reached target timers.target - Timers. Jan 16 08:54:54.150101 systemd[1570]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 16 08:54:54.177946 systemd[1570]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 16 08:54:54.178187 systemd[1570]: Reached target sockets.target - Sockets. Jan 16 08:54:54.178226 systemd[1570]: Reached target basic.target - Basic System. Jan 16 08:54:54.178326 systemd[1570]: Reached target default.target - Main User Target. Jan 16 08:54:54.178412 systemd[1570]: Startup finished in 229ms. Jan 16 08:54:54.178582 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 16 08:54:54.193039 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 16 08:54:54.270542 systemd[1]: Started sshd@1-64.23.133.99:22-147.75.109.163:48304.service - OpenSSH per-connection server daemon (147.75.109.163:48304). Jan 16 08:54:54.339598 sshd[1581]: Accepted publickey for core from 147.75.109.163 port 48304 ssh2: RSA SHA256:JFCq2iHRoEjWAf+XB9dAYBdNgVKarxLIY/Gd6UT86ms Jan 16 08:54:54.341809 sshd-session[1581]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 16 08:54:54.351053 systemd-logind[1445]: New session 2 of user core. Jan 16 08:54:54.356750 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 16 08:54:54.422548 sshd[1583]: Connection closed by 147.75.109.163 port 48304 Jan 16 08:54:54.423650 sshd-session[1581]: pam_unix(sshd:session): session closed for user core Jan 16 08:54:54.435303 systemd[1]: sshd@1-64.23.133.99:22-147.75.109.163:48304.service: Deactivated successfully. Jan 16 08:54:54.438439 systemd[1]: session-2.scope: Deactivated successfully. Jan 16 08:54:54.440841 systemd-logind[1445]: Session 2 logged out. Waiting for processes to exit. Jan 16 08:54:54.446857 systemd[1]: Started sshd@2-64.23.133.99:22-147.75.109.163:48306.service - OpenSSH per-connection server daemon (147.75.109.163:48306). Jan 16 08:54:54.449430 systemd-logind[1445]: Removed session 2. Jan 16 08:54:54.514254 sshd[1588]: Accepted publickey for core from 147.75.109.163 port 48306 ssh2: RSA SHA256:JFCq2iHRoEjWAf+XB9dAYBdNgVKarxLIY/Gd6UT86ms Jan 16 08:54:54.516741 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 16 08:54:54.524183 systemd-logind[1445]: New session 3 of user core. Jan 16 08:54:54.532689 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 16 08:54:54.594115 sshd[1590]: Connection closed by 147.75.109.163 port 48306 Jan 16 08:54:54.593021 sshd-session[1588]: pam_unix(sshd:session): session closed for user core Jan 16 08:54:54.606530 systemd[1]: sshd@2-64.23.133.99:22-147.75.109.163:48306.service: Deactivated successfully. Jan 16 08:54:54.609370 systemd[1]: session-3.scope: Deactivated successfully. Jan 16 08:54:54.611609 systemd-logind[1445]: Session 3 logged out. Waiting for processes to exit. Jan 16 08:54:54.617988 systemd[1]: Started sshd@3-64.23.133.99:22-147.75.109.163:48316.service - OpenSSH per-connection server daemon (147.75.109.163:48316). Jan 16 08:54:54.620886 systemd-logind[1445]: Removed session 3. Jan 16 08:54:54.669901 sshd[1595]: Accepted publickey for core from 147.75.109.163 port 48316 ssh2: RSA SHA256:JFCq2iHRoEjWAf+XB9dAYBdNgVKarxLIY/Gd6UT86ms Jan 16 08:54:54.672211 sshd-session[1595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 16 08:54:54.680791 systemd-logind[1445]: New session 4 of user core. Jan 16 08:54:54.691717 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 16 08:54:54.759423 sshd[1597]: Connection closed by 147.75.109.163 port 48316 Jan 16 08:54:54.760382 sshd-session[1595]: pam_unix(sshd:session): session closed for user core Jan 16 08:54:54.777035 systemd[1]: sshd@3-64.23.133.99:22-147.75.109.163:48316.service: Deactivated successfully. Jan 16 08:54:54.780779 systemd[1]: session-4.scope: Deactivated successfully. Jan 16 08:54:54.783564 systemd-logind[1445]: Session 4 logged out. Waiting for processes to exit. Jan 16 08:54:54.789931 systemd[1]: Started sshd@4-64.23.133.99:22-147.75.109.163:48328.service - OpenSSH per-connection server daemon (147.75.109.163:48328). Jan 16 08:54:54.792066 systemd-logind[1445]: Removed session 4. Jan 16 08:54:54.852626 sshd[1602]: Accepted publickey for core from 147.75.109.163 port 48328 ssh2: RSA SHA256:JFCq2iHRoEjWAf+XB9dAYBdNgVKarxLIY/Gd6UT86ms Jan 16 08:54:54.854873 sshd-session[1602]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 16 08:54:54.862317 systemd-logind[1445]: New session 5 of user core. Jan 16 08:54:54.869715 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 16 08:54:54.952533 sudo[1605]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jan 16 08:54:54.953107 sudo[1605]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 16 08:54:55.840329 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jan 16 08:54:55.841261 systemd[1]: kubelet.service: Consumed 1.399s CPU time. Jan 16 08:54:55.848806 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 16 08:54:55.894280 systemd[1]: Reloading requested from client PID 1638 ('systemctl') (unit session-5.scope)... Jan 16 08:54:55.894306 systemd[1]: Reloading... Jan 16 08:54:56.032481 zram_generator::config[1676]: No configuration found. Jan 16 08:54:56.228893 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. Jan 16 08:54:56.366349 systemd[1]: Reloading finished in 471 ms. Jan 16 08:54:56.430436 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jan 16 08:54:56.430537 systemd[1]: kubelet.service: Failed with result 'signal'. Jan 16 08:54:56.430864 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jan 16 08:54:56.437963 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 16 08:54:56.603576 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jan 16 08:54:56.617948 (kubelet)[1731]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jan 16 08:54:56.680653 kubelet[1731]: 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. Jan 16 08:54:56.680653 kubelet[1731]: 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. Jan 16 08:54:56.680653 kubelet[1731]: 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. Jan 16 08:54:56.682298 kubelet[1731]: I0116 08:54:56.682182 1731 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" Jan 16 08:54:57.169421 kubelet[1731]: I0116 08:54:57.168709 1731 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Jan 16 08:54:57.169421 kubelet[1731]: I0116 08:54:57.168761 1731 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jan 16 08:54:57.169421 kubelet[1731]: I0116 08:54:57.169142 1731 server.go:929] "Client rotation is on, will bootstrap in background" Jan 16 08:54:57.215204 kubelet[1731]: I0116 08:54:57.214998 1731 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jan 16 08:54:57.233239 kubelet[1731]: E0116 08:54:57.233173 1731 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jan 16 08:54:57.233239 kubelet[1731]: I0116 08:54:57.233233 1731 server.go:1403] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jan 16 08:54:57.240869 kubelet[1731]: I0116 08:54:57.240696 1731 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jan 16 08:54:57.242300 kubelet[1731]: I0116 08:54:57.242235 1731 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jan 16 08:54:57.243156 kubelet[1731]: I0116 08:54:57.242556 1731 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jan 16 08:54:57.243156 kubelet[1731]: I0116 08:54:57.242612 1731 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"64.23.133.99","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.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},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"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} Jan 16 08:54:57.243156 kubelet[1731]: I0116 08:54:57.242897 1731 topology_manager.go:138] "Creating topology manager with none policy" Jan 16 08:54:57.243156 kubelet[1731]: I0116 08:54:57.242913 1731 container_manager_linux.go:300] "Creating device plugin manager" Jan 16 08:54:57.243557 kubelet[1731]: I0116 08:54:57.243082 1731 state_mem.go:36] "Initialized new in-memory state store" Jan 16 08:54:57.244707 kubelet[1731]: I0116 08:54:57.244652 1731 kubelet.go:408] "Attempting to sync node with API server" Jan 16 08:54:57.244707 kubelet[1731]: I0116 08:54:57.244690 1731 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jan 16 08:54:57.244861 kubelet[1731]: I0116 08:54:57.244732 1731 kubelet.go:314] "Adding apiserver pod source" Jan 16 08:54:57.244861 kubelet[1731]: I0116 08:54:57.244752 1731 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jan 16 08:54:57.246704 kubelet[1731]: E0116 08:54:57.246092 1731 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:54:57.246704 kubelet[1731]: E0116 08:54:57.246195 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:54:57.250524 kubelet[1731]: I0116 08:54:57.250483 1731 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Jan 16 08:54:57.253408 kubelet[1731]: I0116 08:54:57.253025 1731 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jan 16 08:54:57.253408 kubelet[1731]: W0116 08:54:57.253321 1731 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "64.23.133.99" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jan 16 08:54:57.253408 kubelet[1731]: E0116 08:54:57.253382 1731 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"64.23.133.99\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Jan 16 08:54:57.253708 kubelet[1731]: W0116 08:54:57.253524 1731 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jan 16 08:54:57.253708 kubelet[1731]: E0116 08:54:57.253544 1731 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Jan 16 08:54:57.254404 kubelet[1731]: W0116 08:54:57.254186 1731 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jan 16 08:54:57.255396 kubelet[1731]: I0116 08:54:57.255263 1731 server.go:1269] "Started kubelet" Jan 16 08:54:57.257286 kubelet[1731]: I0116 08:54:57.257242 1731 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jan 16 08:54:57.267128 kubelet[1731]: I0116 08:54:57.267043 1731 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jan 16 08:54:57.268586 kubelet[1731]: I0116 08:54:57.268544 1731 server.go:460] "Adding debug handlers to kubelet server" Jan 16 08:54:57.270257 kubelet[1731]: I0116 08:54:57.270069 1731 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jan 16 08:54:57.270424 kubelet[1731]: I0116 08:54:57.270389 1731 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jan 16 08:54:57.270687 kubelet[1731]: I0116 08:54:57.270663 1731 volume_manager.go:289] "Starting Kubelet Volume Manager" Jan 16 08:54:57.273447 kubelet[1731]: E0116 08:54:57.271761 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:57.273447 kubelet[1731]: I0116 08:54:57.270685 1731 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jan 16 08:54:57.273447 kubelet[1731]: I0116 08:54:57.272212 1731 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Jan 16 08:54:57.273447 kubelet[1731]: I0116 08:54:57.272597 1731 reconciler.go:26] "Reconciler: start to sync state" Jan 16 08:54:57.279762 kubelet[1731]: E0116 08:54:57.279701 1731 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jan 16 08:54:57.281913 kubelet[1731]: I0116 08:54:57.281838 1731 factory.go:221] Registration of the containerd container factory successfully Jan 16 08:54:57.281913 kubelet[1731]: I0116 08:54:57.281865 1731 factory.go:221] Registration of the systemd container factory successfully Jan 16 08:54:57.283558 kubelet[1731]: I0116 08:54:57.281988 1731 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 Jan 16 08:54:57.289430 kubelet[1731]: E0116 08:54:57.288190 1731 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"64.23.133.99\" not found" node="64.23.133.99" Jan 16 08:54:57.307490 kubelet[1731]: I0116 08:54:57.305960 1731 cpu_manager.go:214] "Starting CPU manager" policy="none" Jan 16 08:54:57.307490 kubelet[1731]: I0116 08:54:57.305982 1731 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jan 16 08:54:57.307490 kubelet[1731]: I0116 08:54:57.306003 1731 state_mem.go:36] "Initialized new in-memory state store" Jan 16 08:54:57.312733 kubelet[1731]: I0116 08:54:57.312694 1731 policy_none.go:49] "None policy: Start" Jan 16 08:54:57.313807 kubelet[1731]: I0116 08:54:57.313767 1731 memory_manager.go:170] "Starting memorymanager" policy="None" Jan 16 08:54:57.313807 kubelet[1731]: I0116 08:54:57.313799 1731 state_mem.go:35] "Initializing new in-memory state store" Jan 16 08:54:57.327964 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jan 16 08:54:57.349017 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jan 16 08:54:57.356081 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jan 16 08:54:57.363219 kubelet[1731]: I0116 08:54:57.363004 1731 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jan 16 08:54:57.365397 kubelet[1731]: I0116 08:54:57.364933 1731 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jan 16 08:54:57.365397 kubelet[1731]: I0116 08:54:57.364980 1731 status_manager.go:217] "Starting to sync pod status with apiserver" Jan 16 08:54:57.365397 kubelet[1731]: I0116 08:54:57.365006 1731 kubelet.go:2321] "Starting kubelet main sync loop" Jan 16 08:54:57.365397 kubelet[1731]: E0116 08:54:57.365121 1731 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jan 16 08:54:57.368208 kubelet[1731]: I0116 08:54:57.368069 1731 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jan 16 08:54:57.368348 kubelet[1731]: I0116 08:54:57.368299 1731 eviction_manager.go:189] "Eviction manager: starting control loop" Jan 16 08:54:57.368348 kubelet[1731]: I0116 08:54:57.368313 1731 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jan 16 08:54:57.369940 kubelet[1731]: I0116 08:54:57.369917 1731 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jan 16 08:54:57.375351 kubelet[1731]: E0116 08:54:57.374939 1731 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"64.23.133.99\" not found" Jan 16 08:54:57.472337 kubelet[1731]: I0116 08:54:57.470257 1731 kubelet_node_status.go:72] "Attempting to register node" node="64.23.133.99" Jan 16 08:54:57.480820 kubelet[1731]: I0116 08:54:57.480618 1731 kubelet_node_status.go:75] "Successfully registered node" node="64.23.133.99" Jan 16 08:54:57.480820 kubelet[1731]: E0116 08:54:57.480665 1731 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"64.23.133.99\": node \"64.23.133.99\" not found" Jan 16 08:54:57.500699 kubelet[1731]: E0116 08:54:57.500660 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:57.601300 kubelet[1731]: E0116 08:54:57.601219 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:57.643975 sudo[1605]: pam_unix(sudo:session): session closed for user root Jan 16 08:54:57.647398 sshd[1604]: Connection closed by 147.75.109.163 port 48328 Jan 16 08:54:57.648090 sshd-session[1602]: pam_unix(sshd:session): session closed for user core Jan 16 08:54:57.652941 systemd-logind[1445]: Session 5 logged out. Waiting for processes to exit. Jan 16 08:54:57.653125 systemd[1]: sshd@4-64.23.133.99:22-147.75.109.163:48328.service: Deactivated successfully. Jan 16 08:54:57.655590 systemd[1]: session-5.scope: Deactivated successfully. Jan 16 08:54:57.657954 systemd-logind[1445]: Removed session 5. Jan 16 08:54:57.701549 kubelet[1731]: E0116 08:54:57.701478 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:57.802120 kubelet[1731]: E0116 08:54:57.801958 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:57.902880 kubelet[1731]: E0116 08:54:57.902819 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:58.003823 kubelet[1731]: E0116 08:54:58.003768 1731 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"64.23.133.99\" not found" Jan 16 08:54:58.105109 kubelet[1731]: I0116 08:54:58.104937 1731 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jan 16 08:54:58.105771 containerd[1465]: time="2025-01-16T08:54:58.105645677Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jan 16 08:54:58.106144 kubelet[1731]: I0116 08:54:58.105875 1731 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jan 16 08:54:58.171933 kubelet[1731]: I0116 08:54:58.171862 1731 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jan 16 08:54:58.172107 kubelet[1731]: W0116 08:54:58.172059 1731 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 Jan 16 08:54:58.172107 kubelet[1731]: W0116 08:54:58.172096 1731 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Node 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 Jan 16 08:54:58.172238 kubelet[1731]: W0116 08:54:58.172122 1731 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 Jan 16 08:54:58.246805 kubelet[1731]: E0116 08:54:58.246714 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:54:58.246805 kubelet[1731]: I0116 08:54:58.246764 1731 apiserver.go:52] "Watching apiserver" Jan 16 08:54:58.270118 systemd[1]: Created slice kubepods-burstable-podaf451321_9ca8_4fbc_8b15_86451a247ef5.slice - libcontainer container kubepods-burstable-podaf451321_9ca8_4fbc_8b15_86451a247ef5.slice. Jan 16 08:54:58.273070 kubelet[1731]: I0116 08:54:58.272943 1731 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Jan 16 08:54:58.278705 kubelet[1731]: I0116 08:54:58.278640 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/af451321-9ca8-4fbc-8b15-86451a247ef5-cni-plugin\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.278705 kubelet[1731]: I0116 08:54:58.278694 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gd576\" (UniqueName: \"kubernetes.io/projected/af451321-9ca8-4fbc-8b15-86451a247ef5-kube-api-access-gd576\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.278951 kubelet[1731]: I0116 08:54:58.278727 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/7ed7c685-c049-48f7-86db-e154853acb08-xtables-lock\") pod \"kube-proxy-qj6fl\" (UID: \"7ed7c685-c049-48f7-86db-e154853acb08\") " pod="kube-system/kube-proxy-qj6fl" Jan 16 08:54:58.278951 kubelet[1731]: I0116 08:54:58.278758 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dspnj\" (UniqueName: \"kubernetes.io/projected/7ed7c685-c049-48f7-86db-e154853acb08-kube-api-access-dspnj\") pod \"kube-proxy-qj6fl\" (UID: \"7ed7c685-c049-48f7-86db-e154853acb08\") " pod="kube-system/kube-proxy-qj6fl" Jan 16 08:54:58.278951 kubelet[1731]: I0116 08:54:58.278788 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/af451321-9ca8-4fbc-8b15-86451a247ef5-run\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.278951 kubelet[1731]: I0116 08:54:58.278805 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/af451321-9ca8-4fbc-8b15-86451a247ef5-cni\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.278951 kubelet[1731]: I0116 08:54:58.278826 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/af451321-9ca8-4fbc-8b15-86451a247ef5-flannel-cfg\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.279255 kubelet[1731]: I0116 08:54:58.278844 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/af451321-9ca8-4fbc-8b15-86451a247ef5-xtables-lock\") pod \"kube-flannel-ds-8d8pl\" (UID: \"af451321-9ca8-4fbc-8b15-86451a247ef5\") " pod="kube-flannel/kube-flannel-ds-8d8pl" Jan 16 08:54:58.279255 kubelet[1731]: I0116 08:54:58.278865 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/7ed7c685-c049-48f7-86db-e154853acb08-kube-proxy\") pod \"kube-proxy-qj6fl\" (UID: \"7ed7c685-c049-48f7-86db-e154853acb08\") " pod="kube-system/kube-proxy-qj6fl" Jan 16 08:54:58.279255 kubelet[1731]: I0116 08:54:58.278883 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/7ed7c685-c049-48f7-86db-e154853acb08-lib-modules\") pod \"kube-proxy-qj6fl\" (UID: \"7ed7c685-c049-48f7-86db-e154853acb08\") " pod="kube-system/kube-proxy-qj6fl" Jan 16 08:54:58.286317 systemd[1]: Created slice kubepods-besteffort-pod7ed7c685_c049_48f7_86db_e154853acb08.slice - libcontainer container kubepods-besteffort-pod7ed7c685_c049_48f7_86db_e154853acb08.slice. Jan 16 08:54:58.583065 kubelet[1731]: E0116 08:54:58.582225 1731 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" Jan 16 08:54:58.583227 containerd[1465]: time="2025-01-16T08:54:58.583153748Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8d8pl,Uid:af451321-9ca8-4fbc-8b15-86451a247ef5,Namespace:kube-flannel,Attempt:0,}" Jan 16 08:54:58.597440 kubelet[1731]: E0116 08:54:58.597398 1731 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" Jan 16 08:54:58.598539 containerd[1465]: time="2025-01-16T08:54:58.597939421Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qj6fl,Uid:7ed7c685-c049-48f7-86db-e154853acb08,Namespace:kube-system,Attempt:0,}" Jan 16 08:54:59.247418 kubelet[1731]: E0116 08:54:59.247329 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:54:59.356545 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2670432530.mount: Deactivated successfully. Jan 16 08:54:59.384779 containerd[1465]: time="2025-01-16T08:54:59.384688591Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jan 16 08:54:59.395299 containerd[1465]: time="2025-01-16T08:54:59.395217844Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Jan 16 08:54:59.410447 containerd[1465]: time="2025-01-16T08:54:59.410347979Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jan 16 08:54:59.417503 containerd[1465]: time="2025-01-16T08:54:59.417433322Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jan 16 08:54:59.420548 containerd[1465]: time="2025-01-16T08:54:59.420479148Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Jan 16 08:54:59.426420 containerd[1465]: time="2025-01-16T08:54:59.426301396Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jan 16 08:54:59.427842 containerd[1465]: time="2025-01-16T08:54:59.427476158Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 829.317303ms" Jan 16 08:54:59.436073 containerd[1465]: time="2025-01-16T08:54:59.435999818Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 852.711235ms" Jan 16 08:54:59.685665 containerd[1465]: time="2025-01-16T08:54:59.685172894Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 16 08:54:59.685665 containerd[1465]: time="2025-01-16T08:54:59.685255383Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 16 08:54:59.685665 containerd[1465]: time="2025-01-16T08:54:59.685286125Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:54:59.685665 containerd[1465]: time="2025-01-16T08:54:59.685414378Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:54:59.687904 containerd[1465]: time="2025-01-16T08:54:59.687658840Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 16 08:54:59.687904 containerd[1465]: time="2025-01-16T08:54:59.687741056Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 16 08:54:59.687904 containerd[1465]: time="2025-01-16T08:54:59.687758309Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:54:59.688616 containerd[1465]: time="2025-01-16T08:54:59.687859919Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:54:59.817691 systemd[1]: Started cri-containerd-4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb.scope - libcontainer container 4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb. Jan 16 08:54:59.823396 systemd[1]: Started cri-containerd-aa6877c7089d2241f44d3bd5667fbca4a22f169c12442d66bb1c5c5c5c723a44.scope - libcontainer container aa6877c7089d2241f44d3bd5667fbca4a22f169c12442d66bb1c5c5c5c723a44. Jan 16 08:54:59.885554 containerd[1465]: time="2025-01-16T08:54:59.885469752Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-qj6fl,Uid:7ed7c685-c049-48f7-86db-e154853acb08,Namespace:kube-system,Attempt:0,} returns sandbox id \"aa6877c7089d2241f44d3bd5667fbca4a22f169c12442d66bb1c5c5c5c723a44\"" Jan 16 08:54:59.890078 kubelet[1731]: E0116 08:54:59.889874 1731 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" Jan 16 08:54:59.898858 containerd[1465]: time="2025-01-16T08:54:59.898590046Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.5\"" Jan 16 08:54:59.904450 containerd[1465]: time="2025-01-16T08:54:59.903087136Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8d8pl,Uid:af451321-9ca8-4fbc-8b15-86451a247ef5,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\"" Jan 16 08:54:59.907085 kubelet[1731]: E0116 08:54:59.907040 1731 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" Jan 16 08:55:00.247732 kubelet[1731]: E0116 08:55:00.247662 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:00.485370 systemd[1]: run-containerd-runc-k8s.io-4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb-runc.fBkJBr.mount: Deactivated successfully. Jan 16 08:55:01.249761 kubelet[1731]: E0116 08:55:01.249637 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:01.503401 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount942595082.mount: Deactivated successfully. Jan 16 08:55:02.249941 kubelet[1731]: E0116 08:55:02.249864 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:02.707626 containerd[1465]: time="2025-01-16T08:55:02.706525717Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:02.713083 containerd[1465]: time="2025-01-16T08:55:02.712604377Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.5: active requests=0, bytes read=30231128" Jan 16 08:55:02.716777 containerd[1465]: time="2025-01-16T08:55:02.716653953Z" level=info msg="ImageCreate event name:\"sha256:34018aef09a62f8b40bdd1d2e1bf6c48f359cab492d51059a09e20745ab02ce2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:02.732954 containerd[1465]: time="2025-01-16T08:55:02.732763932Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:c00685cc45c1fb539c5bbd8d24d2577f96e9399efac1670f688f654b30f8c64c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:02.734828 containerd[1465]: time="2025-01-16T08:55:02.734076437Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.5\" with image id \"sha256:34018aef09a62f8b40bdd1d2e1bf6c48f359cab492d51059a09e20745ab02ce2\", repo tag \"registry.k8s.io/kube-proxy:v1.31.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:c00685cc45c1fb539c5bbd8d24d2577f96e9399efac1670f688f654b30f8c64c\", size \"30230147\" in 2.835435116s" Jan 16 08:55:02.734828 containerd[1465]: time="2025-01-16T08:55:02.734144741Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.5\" returns image reference \"sha256:34018aef09a62f8b40bdd1d2e1bf6c48f359cab492d51059a09e20745ab02ce2\"" Jan 16 08:55:02.736162 containerd[1465]: time="2025-01-16T08:55:02.736106634Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jan 16 08:55:02.738656 containerd[1465]: time="2025-01-16T08:55:02.738504763Z" level=info msg="CreateContainer within sandbox \"aa6877c7089d2241f44d3bd5667fbca4a22f169c12442d66bb1c5c5c5c723a44\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jan 16 08:55:02.841158 containerd[1465]: time="2025-01-16T08:55:02.841032089Z" level=info msg="CreateContainer within sandbox \"aa6877c7089d2241f44d3bd5667fbca4a22f169c12442d66bb1c5c5c5c723a44\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93\"" Jan 16 08:55:02.842349 containerd[1465]: time="2025-01-16T08:55:02.842207688Z" level=info msg="StartContainer for \"c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93\"" Jan 16 08:55:02.908014 systemd[1]: run-containerd-runc-k8s.io-c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93-runc.3Fvr7z.mount: Deactivated successfully. Jan 16 08:55:02.935763 systemd[1]: Started cri-containerd-c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93.scope - libcontainer container c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93. Jan 16 08:55:03.001518 containerd[1465]: time="2025-01-16T08:55:03.000291952Z" level=info msg="StartContainer for \"c56cae7da7b197b446610b95affad4a3d0d1423cbe8b7f53256923fbaa256c93\" returns successfully" Jan 16 08:55:03.255553 kubelet[1731]: E0116 08:55:03.250155 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:03.398393 kubelet[1731]: E0116 08:55:03.397729 1731 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" Jan 16 08:55:03.443448 kubelet[1731]: I0116 08:55:03.441423 1731 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-qj6fl" podStartSLOduration=3.60223098 podStartE2EDuration="6.441397606s" podCreationTimestamp="2025-01-16 08:54:57 +0000 UTC" firstStartedPulling="2025-01-16 08:54:59.896662473 +0000 UTC m=+3.273217860" lastFinishedPulling="2025-01-16 08:55:02.735829108 +0000 UTC m=+6.112384486" observedRunningTime="2025-01-16 08:55:03.440633737 +0000 UTC m=+6.817189137" watchObservedRunningTime="2025-01-16 08:55:03.441397606 +0000 UTC m=+6.817953005" Jan 16 08:55:04.250909 kubelet[1731]: E0116 08:55:04.250817 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:04.403492 kubelet[1731]: E0116 08:55:04.403351 1731 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" Jan 16 08:55:04.768961 systemd-resolved[1326]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Jan 16 08:55:05.252105 kubelet[1731]: E0116 08:55:05.251515 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:05.363489 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1473646502.mount: Deactivated successfully. Jan 16 08:55:05.664450 containerd[1465]: time="2025-01-16T08:55:05.664161358Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:05.676436 containerd[1465]: time="2025-01-16T08:55:05.676306028Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852936" Jan 16 08:55:05.687881 containerd[1465]: time="2025-01-16T08:55:05.687763423Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:05.708009 containerd[1465]: time="2025-01-16T08:55:05.707862836Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:05.710091 containerd[1465]: time="2025-01-16T08:55:05.709860505Z" 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.973697188s" Jan 16 08:55:05.710091 containerd[1465]: time="2025-01-16T08:55:05.709925820Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Jan 16 08:55:05.714199 containerd[1465]: time="2025-01-16T08:55:05.714126944Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jan 16 08:55:05.872900 containerd[1465]: time="2025-01-16T08:55:05.872737280Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d\"" Jan 16 08:55:05.874519 containerd[1465]: time="2025-01-16T08:55:05.874229630Z" level=info msg="StartContainer for \"409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d\"" Jan 16 08:55:05.930758 systemd[1]: Started cri-containerd-409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d.scope - libcontainer container 409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d. Jan 16 08:55:05.994330 systemd[1]: cri-containerd-409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d.scope: Deactivated successfully. Jan 16 08:55:06.004069 containerd[1465]: time="2025-01-16T08:55:06.003897373Z" level=info msg="StartContainer for \"409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d\" returns successfully" Jan 16 08:55:06.205514 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d-rootfs.mount: Deactivated successfully. Jan 16 08:55:06.261292 kubelet[1731]: E0116 08:55:06.261219 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:06.262612 containerd[1465]: time="2025-01-16T08:55:06.262516870Z" level=info msg="shim disconnected" id=409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d namespace=k8s.io Jan 16 08:55:06.262612 containerd[1465]: time="2025-01-16T08:55:06.262599452Z" level=warning msg="cleaning up after shim disconnected" id=409fe17d4715143af3dd213387d85db5acd9f51fc744a684bec6cee6ab88405d namespace=k8s.io Jan 16 08:55:06.262612 containerd[1465]: time="2025-01-16T08:55:06.262617945Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jan 16 08:55:06.411409 kubelet[1731]: E0116 08:55:06.411285 1731 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" Jan 16 08:55:06.413024 containerd[1465]: time="2025-01-16T08:55:06.412964847Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jan 16 08:55:07.262157 kubelet[1731]: E0116 08:55:07.262067 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:08.142080 systemd-resolved[1326]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2. Jan 16 08:55:08.262743 kubelet[1731]: E0116 08:55:08.262492 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:08.819904 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3668052710.mount: Deactivated successfully. Jan 16 08:55:09.263825 kubelet[1731]: E0116 08:55:09.263681 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:10.268891 kubelet[1731]: E0116 08:55:10.268834 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:10.285646 containerd[1465]: time="2025-01-16T08:55:10.285424141Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:10.290027 containerd[1465]: time="2025-01-16T08:55:10.289880568Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Jan 16 08:55:10.296869 containerd[1465]: time="2025-01-16T08:55:10.296721831Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:10.309112 containerd[1465]: time="2025-01-16T08:55:10.308980969Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:10.313739 containerd[1465]: time="2025-01-16T08:55:10.313524980Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.90048781s" Jan 16 08:55:10.313739 containerd[1465]: time="2025-01-16T08:55:10.313592590Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Jan 16 08:55:10.317220 containerd[1465]: time="2025-01-16T08:55:10.317164005Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jan 16 08:55:10.366274 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2998475417.mount: Deactivated successfully. Jan 16 08:55:10.378430 containerd[1465]: time="2025-01-16T08:55:10.378197375Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065\"" Jan 16 08:55:10.379654 containerd[1465]: time="2025-01-16T08:55:10.379531799Z" level=info msg="StartContainer for \"3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065\"" Jan 16 08:55:10.453800 systemd[1]: Started cri-containerd-3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065.scope - libcontainer container 3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065. Jan 16 08:55:10.505335 systemd[1]: cri-containerd-3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065.scope: Deactivated successfully. Jan 16 08:55:10.513070 containerd[1465]: time="2025-01-16T08:55:10.512865260Z" level=info msg="StartContainer for \"3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065\" returns successfully" Jan 16 08:55:10.547683 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065-rootfs.mount: Deactivated successfully. Jan 16 08:55:10.593164 kubelet[1731]: I0116 08:55:10.593123 1731 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jan 16 08:55:10.641223 containerd[1465]: time="2025-01-16T08:55:10.640959202Z" level=info msg="shim disconnected" id=3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065 namespace=k8s.io Jan 16 08:55:10.641223 containerd[1465]: time="2025-01-16T08:55:10.641035976Z" level=warning msg="cleaning up after shim disconnected" id=3590201db607399727319fa91f09ae4b70ccda0af1a13ebdbbee74ffbeab0065 namespace=k8s.io Jan 16 08:55:10.641223 containerd[1465]: time="2025-01-16T08:55:10.641053997Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jan 16 08:55:11.269860 kubelet[1731]: E0116 08:55:11.269730 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:11.433001 kubelet[1731]: E0116 08:55:11.432939 1731 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" Jan 16 08:55:11.436709 containerd[1465]: time="2025-01-16T08:55:11.436236956Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jan 16 08:55:11.498437 containerd[1465]: time="2025-01-16T08:55:11.498175799Z" level=info msg="CreateContainer within sandbox \"4753269072b5e9fb15609788f78868bd586ba177ea2af2ab850abb4823e9ddbb\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"1a5f71b09f197dfdf83297ba23810f04de28c089632750d386e07012f7eac727\"" Jan 16 08:55:11.500520 containerd[1465]: time="2025-01-16T08:55:11.499258889Z" level=info msg="StartContainer for \"1a5f71b09f197dfdf83297ba23810f04de28c089632750d386e07012f7eac727\"" Jan 16 08:55:11.544958 systemd[1]: Started cri-containerd-1a5f71b09f197dfdf83297ba23810f04de28c089632750d386e07012f7eac727.scope - libcontainer container 1a5f71b09f197dfdf83297ba23810f04de28c089632750d386e07012f7eac727. Jan 16 08:55:11.596908 containerd[1465]: time="2025-01-16T08:55:11.596846278Z" level=info msg="StartContainer for \"1a5f71b09f197dfdf83297ba23810f04de28c089632750d386e07012f7eac727\" returns successfully" Jan 16 08:55:12.270556 kubelet[1731]: E0116 08:55:12.270471 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:12.438234 kubelet[1731]: E0116 08:55:12.438191 1731 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" Jan 16 08:55:12.454736 kubelet[1731]: I0116 08:55:12.454658 1731 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-8d8pl" podStartSLOduration=5.048158807 podStartE2EDuration="15.454614564s" podCreationTimestamp="2025-01-16 08:54:57 +0000 UTC" firstStartedPulling="2025-01-16 08:54:59.908552679 +0000 UTC m=+3.285108066" lastFinishedPulling="2025-01-16 08:55:10.315008434 +0000 UTC m=+13.691563823" observedRunningTime="2025-01-16 08:55:12.454475359 +0000 UTC m=+15.831030757" watchObservedRunningTime="2025-01-16 08:55:12.454614564 +0000 UTC m=+15.831169959" Jan 16 08:55:12.701542 systemd-networkd[1368]: flannel.1: Link UP Jan 16 08:55:12.701550 systemd-networkd[1368]: flannel.1: Gained carrier Jan 16 08:55:13.271607 kubelet[1731]: E0116 08:55:13.271548 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:13.440092 kubelet[1731]: E0116 08:55:13.440022 1731 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" Jan 16 08:55:14.112725 systemd-networkd[1368]: flannel.1: Gained IPv6LL Jan 16 08:55:14.199141 systemd[1]: Created slice kubepods-besteffort-pod2b01fe6c_a190_4056_b039_80b175203dc4.slice - libcontainer container kubepods-besteffort-pod2b01fe6c_a190_4056_b039_80b175203dc4.slice. Jan 16 08:55:14.226906 kubelet[1731]: I0116 08:55:14.226829 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cmjnh\" (UniqueName: \"kubernetes.io/projected/2b01fe6c-a190-4056-b039-80b175203dc4-kube-api-access-cmjnh\") pod \"nginx-deployment-8587fbcb89-cmcpv\" (UID: \"2b01fe6c-a190-4056-b039-80b175203dc4\") " pod="default/nginx-deployment-8587fbcb89-cmcpv" Jan 16 08:55:14.272729 kubelet[1731]: E0116 08:55:14.272663 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:14.504241 containerd[1465]: time="2025-01-16T08:55:14.503742593Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-cmcpv,Uid:2b01fe6c-a190-4056-b039-80b175203dc4,Namespace:default,Attempt:0,}" Jan 16 08:55:14.565055 systemd-networkd[1368]: cni0: Link UP Jan 16 08:55:14.565066 systemd-networkd[1368]: cni0: Gained carrier Jan 16 08:55:14.568846 systemd-networkd[1368]: cni0: Lost carrier Jan 16 08:55:14.576318 systemd-networkd[1368]: veth7486b681: Link UP Jan 16 08:55:14.580033 kernel: cni0: port 1(veth7486b681) entered blocking state Jan 16 08:55:14.580135 kernel: cni0: port 1(veth7486b681) entered disabled state Jan 16 08:55:14.585925 kernel: veth7486b681: entered allmulticast mode Jan 16 08:55:14.586049 kernel: veth7486b681: entered promiscuous mode Jan 16 08:55:14.591328 kernel: cni0: port 1(veth7486b681) entered blocking state Jan 16 08:55:14.591444 kernel: cni0: port 1(veth7486b681) entered forwarding state Jan 16 08:55:14.591480 kernel: cni0: port 1(veth7486b681) entered disabled state Jan 16 08:55:14.605069 kernel: cni0: port 1(veth7486b681) entered blocking state Jan 16 08:55:14.605192 kernel: cni0: port 1(veth7486b681) entered forwarding state Jan 16 08:55:14.605351 systemd-networkd[1368]: veth7486b681: Gained carrier Jan 16 08:55:14.606113 systemd-networkd[1368]: cni0: Gained carrier Jan 16 08:55:14.610298 containerd[1465]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} Jan 16 08:55:14.610298 containerd[1465]: delegateAdd: netconf sent to delegate plugin: Jan 16 08:55:14.644830 containerd[1465]: {"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-01-16T08:55:14.644678137Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 16 08:55:14.644830 containerd[1465]: time="2025-01-16T08:55:14.644758371Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 16 08:55:14.644830 containerd[1465]: time="2025-01-16T08:55:14.644774559Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:14.650507 containerd[1465]: time="2025-01-16T08:55:14.646491821Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:14.688618 systemd[1]: Started cri-containerd-c64125828775a847f196c4f64a80a2213f6e876fbefca5cad91c67658d268616.scope - libcontainer container c64125828775a847f196c4f64a80a2213f6e876fbefca5cad91c67658d268616. Jan 16 08:55:14.754949 containerd[1465]: time="2025-01-16T08:55:14.754695076Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-cmcpv,Uid:2b01fe6c-a190-4056-b039-80b175203dc4,Namespace:default,Attempt:0,} returns sandbox id \"c64125828775a847f196c4f64a80a2213f6e876fbefca5cad91c67658d268616\"" Jan 16 08:55:14.758621 containerd[1465]: time="2025-01-16T08:55:14.758506774Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jan 16 08:55:15.272908 kubelet[1731]: E0116 08:55:15.272838 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:16.274165 kubelet[1731]: E0116 08:55:16.274006 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:16.290962 systemd-networkd[1368]: cni0: Gained IPv6LL Jan 16 08:55:16.482057 systemd-networkd[1368]: veth7486b681: Gained IPv6LL Jan 16 08:55:17.245631 kubelet[1731]: E0116 08:55:17.245587 1731 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:17.275312 kubelet[1731]: E0116 08:55:17.275221 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:17.755890 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1943180571.mount: Deactivated successfully. Jan 16 08:55:18.275610 kubelet[1731]: E0116 08:55:18.275564 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:19.276651 kubelet[1731]: E0116 08:55:19.276597 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:19.579109 containerd[1465]: time="2025-01-16T08:55:19.579029979Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:19.582517 containerd[1465]: time="2025-01-16T08:55:19.582165456Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71036018" Jan 16 08:55:19.584879 containerd[1465]: time="2025-01-16T08:55:19.584824146Z" level=info msg="ImageCreate event name:\"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:19.591407 containerd[1465]: time="2025-01-16T08:55:19.591301158Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:19.593256 containerd[1465]: time="2025-01-16T08:55:19.593061188Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 4.834436475s" Jan 16 08:55:19.593256 containerd[1465]: time="2025-01-16T08:55:19.593114262Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\"" Jan 16 08:55:19.596327 containerd[1465]: time="2025-01-16T08:55:19.596270536Z" level=info msg="CreateContainer within sandbox \"c64125828775a847f196c4f64a80a2213f6e876fbefca5cad91c67658d268616\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jan 16 08:55:19.636304 containerd[1465]: time="2025-01-16T08:55:19.636245303Z" level=info msg="CreateContainer within sandbox \"c64125828775a847f196c4f64a80a2213f6e876fbefca5cad91c67658d268616\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674\"" Jan 16 08:55:19.638663 containerd[1465]: time="2025-01-16T08:55:19.637451289Z" level=info msg="StartContainer for \"840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674\"" Jan 16 08:55:19.682482 systemd[1]: run-containerd-runc-k8s.io-840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674-runc.uSRInq.mount: Deactivated successfully. Jan 16 08:55:19.697722 systemd[1]: Started cri-containerd-840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674.scope - libcontainer container 840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674. Jan 16 08:55:19.744847 containerd[1465]: time="2025-01-16T08:55:19.744798638Z" level=info msg="StartContainer for \"840108903a3a51220b833511b46e7c3005c78b328098be4c0de253ac0b191674\" returns successfully" Jan 16 08:55:20.277106 kubelet[1731]: E0116 08:55:20.277000 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:21.277253 kubelet[1731]: E0116 08:55:21.277198 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:22.277512 kubelet[1731]: E0116 08:55:22.277452 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:23.278638 kubelet[1731]: E0116 08:55:23.278567 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:24.279423 kubelet[1731]: E0116 08:55:24.279344 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:25.280012 kubelet[1731]: E0116 08:55:25.279934 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:26.280540 kubelet[1731]: E0116 08:55:26.280475 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:27.238656 kubelet[1731]: I0116 08:55:27.238580 1731 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-cmcpv" podStartSLOduration=8.401990709 podStartE2EDuration="13.238555347s" podCreationTimestamp="2025-01-16 08:55:14 +0000 UTC" firstStartedPulling="2025-01-16 08:55:14.758132097 +0000 UTC m=+18.134687485" lastFinishedPulling="2025-01-16 08:55:19.594696736 +0000 UTC m=+22.971252123" observedRunningTime="2025-01-16 08:55:20.47632332 +0000 UTC m=+23.852878731" watchObservedRunningTime="2025-01-16 08:55:27.238555347 +0000 UTC m=+30.615110722" Jan 16 08:55:27.247479 systemd[1]: Created slice kubepods-besteffort-podd5434bf8_7177_4ca4_8d37_75c45866b9ae.slice - libcontainer container kubepods-besteffort-podd5434bf8_7177_4ca4_8d37_75c45866b9ae.slice. Jan 16 08:55:27.280928 kubelet[1731]: E0116 08:55:27.280871 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:27.313449 kubelet[1731]: I0116 08:55:27.313294 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d5434bf8-7177-4ca4-8d37-75c45866b9ae-data\") pod \"nfs-server-provisioner-0\" (UID: \"d5434bf8-7177-4ca4-8d37-75c45866b9ae\") " pod="default/nfs-server-provisioner-0" Jan 16 08:55:27.313449 kubelet[1731]: I0116 08:55:27.313385 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r97p2\" (UniqueName: \"kubernetes.io/projected/d5434bf8-7177-4ca4-8d37-75c45866b9ae-kube-api-access-r97p2\") pod \"nfs-server-provisioner-0\" (UID: \"d5434bf8-7177-4ca4-8d37-75c45866b9ae\") " pod="default/nfs-server-provisioner-0" Jan 16 08:55:27.553253 containerd[1465]: time="2025-01-16T08:55:27.552515714Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d5434bf8-7177-4ca4-8d37-75c45866b9ae,Namespace:default,Attempt:0,}" Jan 16 08:55:27.606038 kernel: cni0: port 2(vethe99051b4) entered blocking state Jan 16 08:55:27.606151 kernel: cni0: port 2(vethe99051b4) entered disabled state Jan 16 08:55:27.605784 systemd-networkd[1368]: vethe99051b4: Link UP Jan 16 08:55:27.611008 kernel: vethe99051b4: entered allmulticast mode Jan 16 08:55:27.611130 kernel: vethe99051b4: entered promiscuous mode Jan 16 08:55:27.621044 kernel: cni0: port 2(vethe99051b4) entered blocking state Jan 16 08:55:27.621149 kernel: cni0: port 2(vethe99051b4) entered forwarding state Jan 16 08:55:27.624376 systemd-networkd[1368]: vethe99051b4: Gained carrier Jan 16 08:55:27.627264 containerd[1465]: 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)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Jan 16 08:55:27.627264 containerd[1465]: delegateAdd: netconf sent to delegate plugin: Jan 16 08:55:27.663425 containerd[1465]: {"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-01-16T08:55:27.663183810Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 16 08:55:27.663425 containerd[1465]: time="2025-01-16T08:55:27.663271400Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 16 08:55:27.663425 containerd[1465]: time="2025-01-16T08:55:27.663287760Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:27.664576 containerd[1465]: time="2025-01-16T08:55:27.664383787Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:27.710719 systemd[1]: Started cri-containerd-1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5.scope - libcontainer container 1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5. Jan 16 08:55:27.771176 containerd[1465]: time="2025-01-16T08:55:27.771128064Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d5434bf8-7177-4ca4-8d37-75c45866b9ae,Namespace:default,Attempt:0,} returns sandbox id \"1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5\"" Jan 16 08:55:27.773802 containerd[1465]: time="2025-01-16T08:55:27.773747606Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jan 16 08:55:28.282123 kubelet[1731]: E0116 08:55:28.281672 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:28.430900 systemd[1]: run-containerd-runc-k8s.io-1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5-runc.DQC6wq.mount: Deactivated successfully. Jan 16 08:55:29.190423 update_engine[1447]: I20250116 08:55:29.189416 1447 update_attempter.cc:509] Updating boot flags... Jan 16 08:55:29.249214 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2533) Jan 16 08:55:29.284249 kubelet[1731]: E0116 08:55:29.284206 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:29.345617 systemd-networkd[1368]: vethe99051b4: Gained IPv6LL Jan 16 08:55:29.370010 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2532) Jan 16 08:55:29.492040 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2532) Jan 16 08:55:30.287543 kubelet[1731]: E0116 08:55:30.287396 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:30.679594 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3968025848.mount: Deactivated successfully. Jan 16 08:55:31.289332 kubelet[1731]: E0116 08:55:31.288593 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:32.289640 kubelet[1731]: E0116 08:55:32.289583 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:33.290843 kubelet[1731]: E0116 08:55:33.290773 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:34.115095 containerd[1465]: time="2025-01-16T08:55:34.115000205Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Jan 16 08:55:34.117417 containerd[1465]: time="2025-01-16T08:55:34.115135770Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:34.117642 containerd[1465]: time="2025-01-16T08:55:34.117607547Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:34.126845 containerd[1465]: time="2025-01-16T08:55:34.126779999Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:34.128855 containerd[1465]: time="2025-01-16T08:55:34.128799816Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 6.354962079s" Jan 16 08:55:34.129047 containerd[1465]: time="2025-01-16T08:55:34.129028578Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Jan 16 08:55:34.132430 containerd[1465]: time="2025-01-16T08:55:34.132382242Z" level=info msg="CreateContainer within sandbox \"1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jan 16 08:55:34.216016 containerd[1465]: time="2025-01-16T08:55:34.215933222Z" level=info msg="CreateContainer within sandbox \"1422303a76b99b3e5f9054241b7a8d0b1d74bec05fdfb172522d7cca6cc913d5\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"0e9f01b5c0102edf7679065738d7995a41f9d5b472645e425b3dd9c969cd3119\"" Jan 16 08:55:34.217201 containerd[1465]: time="2025-01-16T08:55:34.217150335Z" level=info msg="StartContainer for \"0e9f01b5c0102edf7679065738d7995a41f9d5b472645e425b3dd9c969cd3119\"" Jan 16 08:55:34.269901 systemd[1]: Started cri-containerd-0e9f01b5c0102edf7679065738d7995a41f9d5b472645e425b3dd9c969cd3119.scope - libcontainer container 0e9f01b5c0102edf7679065738d7995a41f9d5b472645e425b3dd9c969cd3119. Jan 16 08:55:34.291840 kubelet[1731]: E0116 08:55:34.291787 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:34.327916 containerd[1465]: time="2025-01-16T08:55:34.327759768Z" level=info msg="StartContainer for \"0e9f01b5c0102edf7679065738d7995a41f9d5b472645e425b3dd9c969cd3119\" returns successfully" Jan 16 08:55:34.526472 kubelet[1731]: I0116 08:55:34.525191 1731 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.16808596 podStartE2EDuration="7.5251635s" podCreationTimestamp="2025-01-16 08:55:27 +0000 UTC" firstStartedPulling="2025-01-16 08:55:27.773179011 +0000 UTC m=+31.149734384" lastFinishedPulling="2025-01-16 08:55:34.130256548 +0000 UTC m=+37.506811924" observedRunningTime="2025-01-16 08:55:34.525002704 +0000 UTC m=+37.901558100" watchObservedRunningTime="2025-01-16 08:55:34.5251635 +0000 UTC m=+37.901718901" Jan 16 08:55:35.292796 kubelet[1731]: E0116 08:55:35.292723 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:36.293833 kubelet[1731]: E0116 08:55:36.293753 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:37.245229 kubelet[1731]: E0116 08:55:37.245165 1731 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:37.294027 kubelet[1731]: E0116 08:55:37.293955 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:38.294261 kubelet[1731]: E0116 08:55:38.294199 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:39.295494 kubelet[1731]: E0116 08:55:39.295434 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:40.296503 kubelet[1731]: E0116 08:55:40.296431 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:41.297118 kubelet[1731]: E0116 08:55:41.297056 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:42.297271 kubelet[1731]: E0116 08:55:42.297215 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:43.298013 kubelet[1731]: E0116 08:55:43.297933 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:44.070768 systemd[1]: Created slice kubepods-besteffort-pod9b23ab0c_9a1c_494e_aa0c_dde8c91cb8c6.slice - libcontainer container kubepods-besteffort-pod9b23ab0c_9a1c_494e_aa0c_dde8c91cb8c6.slice. Jan 16 08:55:44.139600 kubelet[1731]: I0116 08:55:44.139539 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vt96l\" (UniqueName: \"kubernetes.io/projected/9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6-kube-api-access-vt96l\") pod \"test-pod-1\" (UID: \"9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6\") " pod="default/test-pod-1" Jan 16 08:55:44.139600 kubelet[1731]: I0116 08:55:44.139605 1731 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4f4d1a26-a9ab-4026-adbf-bd2d37a6a362\" (UniqueName: \"kubernetes.io/nfs/9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6-pvc-4f4d1a26-a9ab-4026-adbf-bd2d37a6a362\") pod \"test-pod-1\" (UID: \"9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6\") " pod="default/test-pod-1" Jan 16 08:55:44.299866 kernel: FS-Cache: Loaded Jan 16 08:55:44.299992 kubelet[1731]: E0116 08:55:44.298786 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:44.383834 kernel: RPC: Registered named UNIX socket transport module. Jan 16 08:55:44.384036 kernel: RPC: Registered udp transport module. Jan 16 08:55:44.384636 kernel: RPC: Registered tcp transport module. Jan 16 08:55:44.386904 kernel: RPC: Registered tcp-with-tls transport module. Jan 16 08:55:44.388740 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 16 08:55:44.744444 kernel: NFS: Registering the id_resolver key type Jan 16 08:55:44.746408 kernel: Key type id_resolver registered Jan 16 08:55:44.748714 kernel: Key type id_legacy registered Jan 16 08:55:44.798440 nfsidmap[2795]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-7-785042947c' Jan 16 08:55:44.805124 nfsidmap[2796]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-7-785042947c' Jan 16 08:55:44.975904 containerd[1465]: time="2025-01-16T08:55:44.975744506Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6,Namespace:default,Attempt:0,}" Jan 16 08:55:45.050427 systemd-networkd[1368]: veth48176417: Link UP Jan 16 08:55:45.055342 kernel: cni0: port 3(veth48176417) entered blocking state Jan 16 08:55:45.055744 kernel: cni0: port 3(veth48176417) entered disabled state Jan 16 08:55:45.056675 kernel: veth48176417: entered allmulticast mode Jan 16 08:55:45.059536 kernel: veth48176417: entered promiscuous mode Jan 16 08:55:45.074751 kernel: cni0: port 3(veth48176417) entered blocking state Jan 16 08:55:45.074887 kernel: cni0: port 3(veth48176417) entered forwarding state Jan 16 08:55:45.074095 systemd-networkd[1368]: veth48176417: Gained carrier Jan 16 08:55:45.078125 containerd[1465]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} Jan 16 08:55:45.078125 containerd[1465]: delegateAdd: netconf sent to delegate plugin: Jan 16 08:55:45.153444 containerd[1465]: {"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-01-16T08:55:45.153284100Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 16 08:55:45.154939 containerd[1465]: time="2025-01-16T08:55:45.154576002Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 16 08:55:45.154939 containerd[1465]: time="2025-01-16T08:55:45.154675024Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:45.155191 containerd[1465]: time="2025-01-16T08:55:45.154889447Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 16 08:55:45.213656 systemd[1]: Started cri-containerd-3c800fcdb571e539b264962ebbaa33d1de1b44372d43026b82282472505a3cd5.scope - libcontainer container 3c800fcdb571e539b264962ebbaa33d1de1b44372d43026b82282472505a3cd5. Jan 16 08:55:45.282292 containerd[1465]: time="2025-01-16T08:55:45.282235506Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9b23ab0c-9a1c-494e-aa0c-dde8c91cb8c6,Namespace:default,Attempt:0,} returns sandbox id \"3c800fcdb571e539b264962ebbaa33d1de1b44372d43026b82282472505a3cd5\"" Jan 16 08:55:45.285314 containerd[1465]: time="2025-01-16T08:55:45.285178259Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jan 16 08:55:45.299433 kubelet[1731]: E0116 08:55:45.299333 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:45.978428 containerd[1465]: time="2025-01-16T08:55:45.977818091Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 16 08:55:45.983458 containerd[1465]: time="2025-01-16T08:55:45.983344660Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jan 16 08:55:45.988375 containerd[1465]: time="2025-01-16T08:55:45.988278616Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 703.05662ms" Jan 16 08:55:45.988375 containerd[1465]: time="2025-01-16T08:55:45.988335283Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\"" Jan 16 08:55:45.991207 containerd[1465]: time="2025-01-16T08:55:45.990864150Z" level=info msg="CreateContainer within sandbox \"3c800fcdb571e539b264962ebbaa33d1de1b44372d43026b82282472505a3cd5\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jan 16 08:55:46.049629 containerd[1465]: time="2025-01-16T08:55:46.049565718Z" level=info msg="CreateContainer within sandbox \"3c800fcdb571e539b264962ebbaa33d1de1b44372d43026b82282472505a3cd5\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"40a36a4d2a36bd2434df59f4454bd6439820879f87fd0f3e740def120b6212f7\"" Jan 16 08:55:46.050595 containerd[1465]: time="2025-01-16T08:55:46.050460543Z" level=info msg="StartContainer for \"40a36a4d2a36bd2434df59f4454bd6439820879f87fd0f3e740def120b6212f7\"" Jan 16 08:55:46.099727 systemd[1]: Started cri-containerd-40a36a4d2a36bd2434df59f4454bd6439820879f87fd0f3e740def120b6212f7.scope - libcontainer container 40a36a4d2a36bd2434df59f4454bd6439820879f87fd0f3e740def120b6212f7. Jan 16 08:55:46.140129 containerd[1465]: time="2025-01-16T08:55:46.139844832Z" level=info msg="StartContainer for \"40a36a4d2a36bd2434df59f4454bd6439820879f87fd0f3e740def120b6212f7\" returns successfully" Jan 16 08:55:46.300744 kubelet[1731]: E0116 08:55:46.300541 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:46.552776 kubelet[1731]: I0116 08:55:46.552571 1731 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.848273742 podStartE2EDuration="19.552546494s" podCreationTimestamp="2025-01-16 08:55:27 +0000 UTC" firstStartedPulling="2025-01-16 08:55:45.284800462 +0000 UTC m=+48.661355838" lastFinishedPulling="2025-01-16 08:55:45.989073203 +0000 UTC m=+49.365628590" observedRunningTime="2025-01-16 08:55:46.552035742 +0000 UTC m=+49.928591204" watchObservedRunningTime="2025-01-16 08:55:46.552546494 +0000 UTC m=+49.929101888" Jan 16 08:55:47.072607 systemd-networkd[1368]: veth48176417: Gained IPv6LL Jan 16 08:55:47.301729 kubelet[1731]: E0116 08:55:47.301659 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:48.302665 kubelet[1731]: E0116 08:55:48.302594 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:49.303095 kubelet[1731]: E0116 08:55:49.303019 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:50.304309 kubelet[1731]: E0116 08:55:50.304224 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 16 08:55:51.305105 kubelet[1731]: E0116 08:55:51.305011 1731 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"