Nov 12 20:49:23.892821 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Tue Nov 12 16:20:46 -00 2024 Nov 12 20:49:23.892854 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=c3abb3a2c1edae861df27d3f75f2daa0ffde49038bd42517f0a3aa15da59cfc7 Nov 12 20:49:23.892871 kernel: BIOS-provided physical RAM map: Nov 12 20:49:23.892880 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Nov 12 20:49:23.892889 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Nov 12 20:49:23.892898 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Nov 12 20:49:23.892911 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffd7fff] usable Nov 12 20:49:23.892919 kernel: BIOS-e820: [mem 0x000000007ffd8000-0x000000007fffffff] reserved Nov 12 20:49:23.892926 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 12 20:49:23.892936 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Nov 12 20:49:23.892943 kernel: NX (Execute Disable) protection: active Nov 12 20:49:23.892950 kernel: APIC: Static calls initialized Nov 12 20:49:23.892957 kernel: SMBIOS 2.8 present. Nov 12 20:49:23.892964 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Nov 12 20:49:23.892972 kernel: Hypervisor detected: KVM Nov 12 20:49:23.892983 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 12 20:49:23.892991 kernel: kvm-clock: using sched offset of 3087528999 cycles Nov 12 20:49:23.893004 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 12 20:49:23.893013 kernel: tsc: Detected 2494.138 MHz processor Nov 12 20:49:23.893020 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 12 20:49:23.893029 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 12 20:49:23.893036 kernel: last_pfn = 0x7ffd8 max_arch_pfn = 0x400000000 Nov 12 20:49:23.893045 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Nov 12 20:49:23.893052 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 12 20:49:23.893063 kernel: ACPI: Early table checksum verification disabled Nov 12 20:49:23.893071 kernel: ACPI: RSDP 0x00000000000F5A50 000014 (v00 BOCHS ) Nov 12 20:49:23.893079 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893087 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893095 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893103 kernel: ACPI: FACS 0x000000007FFE0000 000040 Nov 12 20:49:23.893111 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893118 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893126 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893137 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 12 20:49:23.893144 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Nov 12 20:49:23.893152 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Nov 12 20:49:23.893160 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Nov 12 20:49:23.893168 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Nov 12 20:49:23.893176 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Nov 12 20:49:23.893184 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Nov 12 20:49:23.893196 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Nov 12 20:49:23.893206 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Nov 12 20:49:23.893215 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Nov 12 20:49:23.893227 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Nov 12 20:49:23.893238 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Nov 12 20:49:23.893250 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffd7fff] -> [mem 0x00000000-0x7ffd7fff] Nov 12 20:49:23.893262 kernel: NODE_DATA(0) allocated [mem 0x7ffd2000-0x7ffd7fff] Nov 12 20:49:23.893279 kernel: Zone ranges: Nov 12 20:49:23.893292 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 12 20:49:23.893302 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffd7fff] Nov 12 20:49:23.893310 kernel: Normal empty Nov 12 20:49:23.893319 kernel: Movable zone start for each node Nov 12 20:49:23.893327 kernel: Early memory node ranges Nov 12 20:49:23.893335 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Nov 12 20:49:23.893343 kernel: node 0: [mem 0x0000000000100000-0x000000007ffd7fff] Nov 12 20:49:23.893351 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffd7fff] Nov 12 20:49:23.893362 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 12 20:49:23.893371 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Nov 12 20:49:23.893379 kernel: On node 0, zone DMA32: 40 pages in unavailable ranges Nov 12 20:49:23.893387 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 12 20:49:23.893407 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 12 20:49:23.893416 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 12 20:49:23.893434 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 12 20:49:23.893444 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 12 20:49:23.893452 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 12 20:49:23.893463 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 12 20:49:23.893472 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 12 20:49:23.893480 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 12 20:49:23.893488 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 12 20:49:23.893496 kernel: TSC deadline timer available Nov 12 20:49:23.893504 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Nov 12 20:49:23.893513 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 12 20:49:23.893521 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Nov 12 20:49:23.893529 kernel: Booting paravirtualized kernel on KVM Nov 12 20:49:23.893545 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 12 20:49:23.893553 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Nov 12 20:49:23.893561 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 Nov 12 20:49:23.893569 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 Nov 12 20:49:23.893577 kernel: pcpu-alloc: [0] 0 1 Nov 12 20:49:23.893585 kernel: kvm-guest: PV spinlocks disabled, no host support Nov 12 20:49:23.893595 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=c3abb3a2c1edae861df27d3f75f2daa0ffde49038bd42517f0a3aa15da59cfc7 Nov 12 20:49:23.893603 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 20:49:23.893614 kernel: random: crng init done Nov 12 20:49:23.893623 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 20:49:23.893631 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 12 20:49:23.893639 kernel: Fallback order for Node 0: 0 Nov 12 20:49:23.893648 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515800 Nov 12 20:49:23.893656 kernel: Policy zone: DMA32 Nov 12 20:49:23.893664 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 20:49:23.893673 kernel: Memory: 1971192K/2096600K available (12288K kernel code, 2305K rwdata, 22724K rodata, 42828K init, 2360K bss, 125148K reserved, 0K cma-reserved) Nov 12 20:49:23.893681 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 12 20:49:23.893692 kernel: Kernel/User page tables isolation: enabled Nov 12 20:49:23.893700 kernel: ftrace: allocating 37799 entries in 148 pages Nov 12 20:49:23.893708 kernel: ftrace: allocated 148 pages with 3 groups Nov 12 20:49:23.893716 kernel: Dynamic Preempt: voluntary Nov 12 20:49:23.893724 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 20:49:23.893737 kernel: rcu: RCU event tracing is enabled. Nov 12 20:49:23.893746 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 12 20:49:23.893754 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 20:49:23.893763 kernel: Rude variant of Tasks RCU enabled. Nov 12 20:49:23.893774 kernel: Tracing variant of Tasks RCU enabled. Nov 12 20:49:23.893782 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 20:49:23.893790 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 12 20:49:23.893798 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Nov 12 20:49:23.893807 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 20:49:23.893827 kernel: Console: colour VGA+ 80x25 Nov 12 20:49:23.893838 kernel: printk: console [tty0] enabled Nov 12 20:49:23.893846 kernel: printk: console [ttyS0] enabled Nov 12 20:49:23.893855 kernel: ACPI: Core revision 20230628 Nov 12 20:49:23.893863 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 12 20:49:23.893875 kernel: APIC: Switch to symmetric I/O mode setup Nov 12 20:49:23.893883 kernel: x2apic enabled Nov 12 20:49:23.893892 kernel: APIC: Switched APIC routing to: physical x2apic Nov 12 20:49:23.893900 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 12 20:49:23.893912 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x23f39838d43, max_idle_ns: 440795267131 ns Nov 12 20:49:23.893926 kernel: Calibrating delay loop (skipped) preset value.. 4988.27 BogoMIPS (lpj=2494138) Nov 12 20:49:23.893939 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Nov 12 20:49:23.893950 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Nov 12 20:49:23.893970 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 12 20:49:23.893979 kernel: Spectre V2 : Mitigation: Retpolines Nov 12 20:49:23.893988 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Nov 12 20:49:23.894002 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Nov 12 20:49:23.894018 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Nov 12 20:49:23.894033 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 12 20:49:23.894042 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 12 20:49:23.894051 kernel: MDS: Mitigation: Clear CPU buffers Nov 12 20:49:23.894060 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 12 20:49:23.894072 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 12 20:49:23.894085 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 12 20:49:23.894101 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 12 20:49:23.894137 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 12 20:49:23.894147 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 12 20:49:23.894157 kernel: Freeing SMP alternatives memory: 32K Nov 12 20:49:23.894172 kernel: pid_max: default: 32768 minimum: 301 Nov 12 20:49:23.894189 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 20:49:23.894220 kernel: landlock: Up and running. Nov 12 20:49:23.894233 kernel: SELinux: Initializing. Nov 12 20:49:23.894246 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 12 20:49:23.894259 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 12 20:49:23.894272 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Nov 12 20:49:23.894286 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 20:49:23.894299 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 20:49:23.894313 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 20:49:23.894333 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 12 20:49:23.894346 kernel: signal: max sigframe size: 1776 Nov 12 20:49:23.894358 kernel: rcu: Hierarchical SRCU implementation. Nov 12 20:49:23.894371 kernel: rcu: Max phase no-delay instances is 400. Nov 12 20:49:23.894384 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 12 20:49:23.896020 kernel: smp: Bringing up secondary CPUs ... Nov 12 20:49:23.896053 kernel: smpboot: x86: Booting SMP configuration: Nov 12 20:49:23.896063 kernel: .... node #0, CPUs: #1 Nov 12 20:49:23.896072 kernel: smp: Brought up 1 node, 2 CPUs Nov 12 20:49:23.896082 kernel: smpboot: Max logical packages: 1 Nov 12 20:49:23.896099 kernel: smpboot: Total of 2 processors activated (9976.55 BogoMIPS) Nov 12 20:49:23.896109 kernel: devtmpfs: initialized Nov 12 20:49:23.896118 kernel: x86/mm: Memory block size: 128MB Nov 12 20:49:23.896127 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 20:49:23.896137 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 12 20:49:23.896146 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 20:49:23.896155 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 20:49:23.896165 kernel: audit: initializing netlink subsys (disabled) Nov 12 20:49:23.896174 kernel: audit: type=2000 audit(1731444562.896:1): state=initialized audit_enabled=0 res=1 Nov 12 20:49:23.896186 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 20:49:23.896195 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 12 20:49:23.896204 kernel: cpuidle: using governor menu Nov 12 20:49:23.896213 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 20:49:23.896222 kernel: dca service started, version 1.12.1 Nov 12 20:49:23.896231 kernel: PCI: Using configuration type 1 for base access Nov 12 20:49:23.896240 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 12 20:49:23.896250 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 20:49:23.896258 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 20:49:23.896270 kernel: ACPI: Added _OSI(Module Device) Nov 12 20:49:23.896279 kernel: ACPI: Added _OSI(Processor Device) Nov 12 20:49:23.896288 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 20:49:23.896297 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 20:49:23.896306 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 20:49:23.896315 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Nov 12 20:49:23.896324 kernel: ACPI: Interpreter enabled Nov 12 20:49:23.896333 kernel: ACPI: PM: (supports S0 S5) Nov 12 20:49:23.896342 kernel: ACPI: Using IOAPIC for interrupt routing Nov 12 20:49:23.896354 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 12 20:49:23.896363 kernel: PCI: Using E820 reservations for host bridge windows Nov 12 20:49:23.896372 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Nov 12 20:49:23.896381 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 12 20:49:23.896580 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Nov 12 20:49:23.896687 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Nov 12 20:49:23.896812 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Nov 12 20:49:23.896834 kernel: acpiphp: Slot [3] registered Nov 12 20:49:23.896844 kernel: acpiphp: Slot [4] registered Nov 12 20:49:23.896853 kernel: acpiphp: Slot [5] registered Nov 12 20:49:23.896861 kernel: acpiphp: Slot [6] registered Nov 12 20:49:23.896870 kernel: acpiphp: Slot [7] registered Nov 12 20:49:23.896879 kernel: acpiphp: Slot [8] registered Nov 12 20:49:23.896888 kernel: acpiphp: Slot [9] registered Nov 12 20:49:23.896897 kernel: acpiphp: Slot [10] registered Nov 12 20:49:23.896906 kernel: acpiphp: Slot [11] registered Nov 12 20:49:23.896918 kernel: acpiphp: Slot [12] registered Nov 12 20:49:23.896927 kernel: acpiphp: Slot [13] registered Nov 12 20:49:23.896940 kernel: acpiphp: Slot [14] registered Nov 12 20:49:23.896952 kernel: acpiphp: Slot [15] registered Nov 12 20:49:23.896963 kernel: acpiphp: Slot [16] registered Nov 12 20:49:23.896975 kernel: acpiphp: Slot [17] registered Nov 12 20:49:23.896987 kernel: acpiphp: Slot [18] registered Nov 12 20:49:23.897000 kernel: acpiphp: Slot [19] registered Nov 12 20:49:23.897013 kernel: acpiphp: Slot [20] registered Nov 12 20:49:23.897026 kernel: acpiphp: Slot [21] registered Nov 12 20:49:23.897044 kernel: acpiphp: Slot [22] registered Nov 12 20:49:23.897057 kernel: acpiphp: Slot [23] registered Nov 12 20:49:23.897070 kernel: acpiphp: Slot [24] registered Nov 12 20:49:23.897082 kernel: acpiphp: Slot [25] registered Nov 12 20:49:23.897095 kernel: acpiphp: Slot [26] registered Nov 12 20:49:23.897108 kernel: acpiphp: Slot [27] registered Nov 12 20:49:23.897121 kernel: acpiphp: Slot [28] registered Nov 12 20:49:23.897135 kernel: acpiphp: Slot [29] registered Nov 12 20:49:23.897148 kernel: acpiphp: Slot [30] registered Nov 12 20:49:23.897165 kernel: acpiphp: Slot [31] registered Nov 12 20:49:23.897176 kernel: PCI host bridge to bus 0000:00 Nov 12 20:49:23.897304 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 12 20:49:23.897392 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 12 20:49:23.897504 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 12 20:49:23.897588 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Nov 12 20:49:23.897672 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Nov 12 20:49:23.897756 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 12 20:49:23.897896 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Nov 12 20:49:23.898051 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Nov 12 20:49:23.898160 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Nov 12 20:49:23.898287 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] Nov 12 20:49:23.898385 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Nov 12 20:49:23.899081 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Nov 12 20:49:23.899197 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Nov 12 20:49:23.899293 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Nov 12 20:49:23.899450 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Nov 12 20:49:23.899562 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] Nov 12 20:49:23.899670 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Nov 12 20:49:23.899766 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Nov 12 20:49:23.899866 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Nov 12 20:49:23.899973 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Nov 12 20:49:23.900070 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Nov 12 20:49:23.900167 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Nov 12 20:49:23.900293 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] Nov 12 20:49:23.900457 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Nov 12 20:49:23.900554 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 12 20:49:23.900693 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Nov 12 20:49:23.900790 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] Nov 12 20:49:23.900886 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] Nov 12 20:49:23.901016 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Nov 12 20:49:23.901149 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Nov 12 20:49:23.901249 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] Nov 12 20:49:23.901351 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] Nov 12 20:49:23.903556 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Nov 12 20:49:23.903689 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Nov 12 20:49:23.903791 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] Nov 12 20:49:23.903915 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] Nov 12 20:49:23.904021 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Nov 12 20:49:23.904132 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 Nov 12 20:49:23.904237 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] Nov 12 20:49:23.904332 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] Nov 12 20:49:23.904542 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Nov 12 20:49:23.904650 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 Nov 12 20:49:23.904743 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] Nov 12 20:49:23.904835 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] Nov 12 20:49:23.904927 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] Nov 12 20:49:23.905027 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 Nov 12 20:49:23.905128 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] Nov 12 20:49:23.905221 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] Nov 12 20:49:23.905233 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 12 20:49:23.905243 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 12 20:49:23.905252 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 12 20:49:23.905261 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 12 20:49:23.905274 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Nov 12 20:49:23.905283 kernel: iommu: Default domain type: Translated Nov 12 20:49:23.905292 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 12 20:49:23.905301 kernel: PCI: Using ACPI for IRQ routing Nov 12 20:49:23.905310 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 12 20:49:23.905319 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Nov 12 20:49:23.905328 kernel: e820: reserve RAM buffer [mem 0x7ffd8000-0x7fffffff] Nov 12 20:49:23.905491 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Nov 12 20:49:23.905603 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Nov 12 20:49:23.905701 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 12 20:49:23.905713 kernel: vgaarb: loaded Nov 12 20:49:23.905723 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 12 20:49:23.905732 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 12 20:49:23.905741 kernel: clocksource: Switched to clocksource kvm-clock Nov 12 20:49:23.905751 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 20:49:23.905760 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 20:49:23.905770 kernel: pnp: PnP ACPI init Nov 12 20:49:23.905779 kernel: pnp: PnP ACPI: found 4 devices Nov 12 20:49:23.905791 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 12 20:49:23.905800 kernel: NET: Registered PF_INET protocol family Nov 12 20:49:23.905809 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 20:49:23.905818 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Nov 12 20:49:23.905828 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 20:49:23.905836 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 12 20:49:23.905845 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Nov 12 20:49:23.905854 kernel: TCP: Hash tables configured (established 16384 bind 16384) Nov 12 20:49:23.905863 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 12 20:49:23.905875 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 12 20:49:23.905884 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 20:49:23.905893 kernel: NET: Registered PF_XDP protocol family Nov 12 20:49:23.906043 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 12 20:49:23.906131 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 12 20:49:23.906234 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 12 20:49:23.906322 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Nov 12 20:49:23.908466 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Nov 12 20:49:23.908622 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Nov 12 20:49:23.908728 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Nov 12 20:49:23.908742 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Nov 12 20:49:23.908840 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 27571 usecs Nov 12 20:49:23.908853 kernel: PCI: CLS 0 bytes, default 64 Nov 12 20:49:23.908862 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 12 20:49:23.908872 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x23f39838d43, max_idle_ns: 440795267131 ns Nov 12 20:49:23.908881 kernel: Initialise system trusted keyrings Nov 12 20:49:23.908894 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Nov 12 20:49:23.908904 kernel: Key type asymmetric registered Nov 12 20:49:23.908912 kernel: Asymmetric key parser 'x509' registered Nov 12 20:49:23.908921 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 12 20:49:23.908931 kernel: io scheduler mq-deadline registered Nov 12 20:49:23.908940 kernel: io scheduler kyber registered Nov 12 20:49:23.908949 kernel: io scheduler bfq registered Nov 12 20:49:23.908958 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 12 20:49:23.908967 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Nov 12 20:49:23.908978 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Nov 12 20:49:23.908987 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Nov 12 20:49:23.908996 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 20:49:23.909005 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 12 20:49:23.909014 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 12 20:49:23.909023 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 12 20:49:23.909032 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 12 20:49:23.909041 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 12 20:49:23.909147 kernel: rtc_cmos 00:03: RTC can wake from S4 Nov 12 20:49:23.909241 kernel: rtc_cmos 00:03: registered as rtc0 Nov 12 20:49:23.909328 kernel: rtc_cmos 00:03: setting system clock to 2024-11-12T20:49:23 UTC (1731444563) Nov 12 20:49:23.909427 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Nov 12 20:49:23.909439 kernel: intel_pstate: CPU model not supported Nov 12 20:49:23.909448 kernel: NET: Registered PF_INET6 protocol family Nov 12 20:49:23.909457 kernel: Segment Routing with IPv6 Nov 12 20:49:23.909466 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 20:49:23.909475 kernel: NET: Registered PF_PACKET protocol family Nov 12 20:49:23.909494 kernel: Key type dns_resolver registered Nov 12 20:49:23.909504 kernel: IPI shorthand broadcast: enabled Nov 12 20:49:23.909524 kernel: sched_clock: Marking stable (890006022, 93854175)->(1011068723, -27208526) Nov 12 20:49:23.909543 kernel: registered taskstats version 1 Nov 12 20:49:23.909554 kernel: Loading compiled-in X.509 certificates Nov 12 20:49:23.909563 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 0473a73d840db5324524af106a53c13fc6fc218a' Nov 12 20:49:23.909572 kernel: Key type .fscrypt registered Nov 12 20:49:23.909581 kernel: Key type fscrypt-provisioning registered Nov 12 20:49:23.909590 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 20:49:23.909602 kernel: ima: Allocated hash algorithm: sha1 Nov 12 20:49:23.909611 kernel: ima: No architecture policies found Nov 12 20:49:23.909620 kernel: clk: Disabling unused clocks Nov 12 20:49:23.909629 kernel: Freeing unused kernel image (initmem) memory: 42828K Nov 12 20:49:23.909638 kernel: Write protecting the kernel read-only data: 36864k Nov 12 20:49:23.909668 kernel: Freeing unused kernel image (rodata/data gap) memory: 1852K Nov 12 20:49:23.909680 kernel: Run /init as init process Nov 12 20:49:23.909689 kernel: with arguments: Nov 12 20:49:23.909699 kernel: /init Nov 12 20:49:23.909710 kernel: with environment: Nov 12 20:49:23.909720 kernel: HOME=/ Nov 12 20:49:23.909729 kernel: TERM=linux Nov 12 20:49:23.909738 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 20:49:23.909750 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) Nov 12 20:49:23.909762 systemd[1]: Detected virtualization kvm. Nov 12 20:49:23.909772 systemd[1]: Detected architecture x86-64. Nov 12 20:49:23.909781 systemd[1]: Running in initrd. Nov 12 20:49:23.909793 systemd[1]: No hostname configured, using default hostname. Nov 12 20:49:23.909803 systemd[1]: Hostname set to . Nov 12 20:49:23.909813 systemd[1]: Initializing machine ID from VM UUID. Nov 12 20:49:23.909823 systemd[1]: Queued start job for default target initrd.target. Nov 12 20:49:23.909838 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 20:49:23.909852 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 20:49:23.909866 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 20:49:23.909883 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 20:49:23.909901 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 20:49:23.909915 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 20:49:23.909933 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 20:49:23.909945 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 20:49:23.909955 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 20:49:23.909965 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 20:49:23.909978 systemd[1]: Reached target paths.target - Path Units. Nov 12 20:49:23.909988 systemd[1]: Reached target slices.target - Slice Units. Nov 12 20:49:23.909998 systemd[1]: Reached target swap.target - Swaps. Nov 12 20:49:23.910011 systemd[1]: Reached target timers.target - Timer Units. Nov 12 20:49:23.910021 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 20:49:23.910031 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 20:49:23.910044 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 20:49:23.910053 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 20:49:23.910064 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 20:49:23.910074 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 20:49:23.910084 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 20:49:23.910094 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 20:49:23.910104 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 20:49:23.910114 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 20:49:23.910126 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 20:49:23.910136 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 20:49:23.910146 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 20:49:23.910155 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 20:49:23.910165 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 20:49:23.910175 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 20:49:23.910185 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 20:49:23.910215 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 20:49:23.910278 systemd-journald[184]: Collecting audit messages is disabled. Nov 12 20:49:23.910312 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 20:49:23.910323 systemd-journald[184]: Journal started Nov 12 20:49:23.910345 systemd-journald[184]: Runtime Journal (/run/log/journal/1b167160ce294ce9a265822de3362ed4) is 4.9M, max 39.3M, 34.4M free. Nov 12 20:49:23.911657 systemd-modules-load[185]: Inserted module 'overlay' Nov 12 20:49:23.951305 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 20:49:23.951352 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 20:49:23.951368 kernel: Bridge firewalling registered Nov 12 20:49:23.940395 systemd-modules-load[185]: Inserted module 'br_netfilter' Nov 12 20:49:23.952822 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 20:49:23.953474 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:23.957830 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 20:49:23.964589 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 20:49:23.966301 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 20:49:23.971603 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 20:49:23.977587 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 20:49:23.990066 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 20:49:23.993442 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 20:49:23.996053 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 20:49:24.002740 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 20:49:24.004886 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 20:49:24.008600 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 20:49:24.029914 dracut-cmdline[217]: dracut-dracut-053 Nov 12 20:49:24.034179 dracut-cmdline[217]: 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=c3abb3a2c1edae861df27d3f75f2daa0ffde49038bd42517f0a3aa15da59cfc7 Nov 12 20:49:24.047075 systemd-resolved[220]: Positive Trust Anchors: Nov 12 20:49:24.047089 systemd-resolved[220]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 20:49:24.047124 systemd-resolved[220]: 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 Nov 12 20:49:24.052874 systemd-resolved[220]: Defaulting to hostname 'linux'. Nov 12 20:49:24.055123 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 20:49:24.055930 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 20:49:24.125465 kernel: SCSI subsystem initialized Nov 12 20:49:24.134431 kernel: Loading iSCSI transport class v2.0-870. Nov 12 20:49:24.146447 kernel: iscsi: registered transport (tcp) Nov 12 20:49:24.169438 kernel: iscsi: registered transport (qla4xxx) Nov 12 20:49:24.169530 kernel: QLogic iSCSI HBA Driver Nov 12 20:49:24.221715 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 20:49:24.233724 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 20:49:24.259792 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 20:49:24.259871 kernel: device-mapper: uevent: version 1.0.3 Nov 12 20:49:24.259886 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 20:49:24.302434 kernel: raid6: avx2x4 gen() 17162 MB/s Nov 12 20:49:24.319451 kernel: raid6: avx2x2 gen() 17822 MB/s Nov 12 20:49:24.336702 kernel: raid6: avx2x1 gen() 13640 MB/s Nov 12 20:49:24.336777 kernel: raid6: using algorithm avx2x2 gen() 17822 MB/s Nov 12 20:49:24.354725 kernel: raid6: .... xor() 20398 MB/s, rmw enabled Nov 12 20:49:24.354802 kernel: raid6: using avx2x2 recovery algorithm Nov 12 20:49:24.376435 kernel: xor: automatically using best checksumming function avx Nov 12 20:49:24.539445 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 20:49:24.551992 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 20:49:24.556621 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 20:49:24.590882 systemd-udevd[403]: Using default interface naming scheme 'v255'. Nov 12 20:49:24.596330 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 20:49:24.605661 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 20:49:24.623353 dracut-pre-trigger[408]: rd.md=0: removing MD RAID activation Nov 12 20:49:24.659765 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 20:49:24.664624 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 20:49:24.734435 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 20:49:24.741833 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 20:49:24.759772 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 20:49:24.763257 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 20:49:24.766505 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 20:49:24.767601 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 20:49:24.772643 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 20:49:24.786141 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 20:49:24.800427 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Nov 12 20:49:24.857625 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Nov 12 20:49:24.857772 kernel: scsi host0: Virtio SCSI HBA Nov 12 20:49:24.857909 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 20:49:24.857923 kernel: GPT:9289727 != 125829119 Nov 12 20:49:24.857934 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 20:49:24.857946 kernel: GPT:9289727 != 125829119 Nov 12 20:49:24.857957 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 20:49:24.857973 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 20:49:24.857985 kernel: cryptd: max_cpu_qlen set to 1000 Nov 12 20:49:24.857997 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Nov 12 20:49:24.877739 kernel: virtio_blk virtio5: [vdb] 920 512-byte logical blocks (471 kB/460 KiB) Nov 12 20:49:24.877944 kernel: AVX2 version of gcm_enc/dec engaged. Nov 12 20:49:24.877968 kernel: AES CTR mode by8 optimization enabled Nov 12 20:49:24.909794 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 20:49:24.910652 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 20:49:24.912970 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 20:49:24.913772 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 20:49:24.915307 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:24.916026 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 20:49:24.928823 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 20:49:24.932433 kernel: libata version 3.00 loaded. Nov 12 20:49:24.942796 kernel: BTRFS: device fsid 9dfeafbb-8ab7-4be2-acae-f51db463fc77 devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (449) Nov 12 20:49:24.965602 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 12 20:49:25.012213 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (462) Nov 12 20:49:25.012258 kernel: ata_piix 0000:00:01.1: version 2.13 Nov 12 20:49:25.012593 kernel: ACPI: bus type USB registered Nov 12 20:49:25.012612 kernel: usbcore: registered new interface driver usbfs Nov 12 20:49:25.012629 kernel: usbcore: registered new interface driver hub Nov 12 20:49:25.012663 kernel: usbcore: registered new device driver usb Nov 12 20:49:25.012680 kernel: scsi host1: ata_piix Nov 12 20:49:25.012858 kernel: scsi host2: ata_piix Nov 12 20:49:25.013043 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 Nov 12 20:49:25.013061 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 Nov 12 20:49:25.016708 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 12 20:49:25.021005 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:25.034050 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 12 20:49:25.034701 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 12 20:49:25.041357 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 20:49:25.054658 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 20:49:25.058111 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 20:49:25.066850 disk-uuid[543]: Primary Header is updated. Nov 12 20:49:25.066850 disk-uuid[543]: Secondary Entries is updated. Nov 12 20:49:25.066850 disk-uuid[543]: Secondary Header is updated. Nov 12 20:49:25.079508 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 20:49:25.088870 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 20:49:25.100450 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 20:49:25.110438 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 20:49:25.200442 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Nov 12 20:49:25.206806 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Nov 12 20:49:25.207061 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Nov 12 20:49:25.207242 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Nov 12 20:49:25.207446 kernel: hub 1-0:1.0: USB hub found Nov 12 20:49:25.207677 kernel: hub 1-0:1.0: 2 ports detected Nov 12 20:49:26.089538 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 12 20:49:26.091071 disk-uuid[544]: The operation has completed successfully. Nov 12 20:49:26.151638 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 20:49:26.151860 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 20:49:26.172735 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 20:49:26.178171 sh[566]: Success Nov 12 20:49:26.194472 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Nov 12 20:49:26.259272 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 20:49:26.268576 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 20:49:26.274600 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 20:49:26.301451 kernel: BTRFS info (device dm-0): first mount of filesystem 9dfeafbb-8ab7-4be2-acae-f51db463fc77 Nov 12 20:49:26.301556 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 12 20:49:26.301572 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 20:49:26.302711 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 20:49:26.303519 kernel: BTRFS info (device dm-0): using free space tree Nov 12 20:49:26.314061 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 20:49:26.315939 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 20:49:26.321666 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 20:49:26.324594 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 20:49:26.339847 kernel: BTRFS info (device vda6): first mount of filesystem bdc43ff2-e8de-475f-88ba-e8c26a6bbaa6 Nov 12 20:49:26.339962 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 12 20:49:26.339985 kernel: BTRFS info (device vda6): using free space tree Nov 12 20:49:26.344443 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 20:49:26.357642 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 20:49:26.360653 kernel: BTRFS info (device vda6): last unmount of filesystem bdc43ff2-e8de-475f-88ba-e8c26a6bbaa6 Nov 12 20:49:26.368881 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 20:49:26.377736 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 20:49:26.494827 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 20:49:26.503693 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 20:49:26.512637 ignition[666]: Ignition 2.19.0 Nov 12 20:49:26.512650 ignition[666]: Stage: fetch-offline Nov 12 20:49:26.512708 ignition[666]: no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:26.512724 ignition[666]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:26.516699 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 20:49:26.512861 ignition[666]: parsed url from cmdline: "" Nov 12 20:49:26.512866 ignition[666]: no config URL provided Nov 12 20:49:26.512871 ignition[666]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 20:49:26.512880 ignition[666]: no config at "/usr/lib/ignition/user.ign" Nov 12 20:49:26.512889 ignition[666]: failed to fetch config: resource requires networking Nov 12 20:49:26.515280 ignition[666]: Ignition finished successfully Nov 12 20:49:26.537124 systemd-networkd[756]: lo: Link UP Nov 12 20:49:26.537140 systemd-networkd[756]: lo: Gained carrier Nov 12 20:49:26.539735 systemd-networkd[756]: Enumeration completed Nov 12 20:49:26.540126 systemd-networkd[756]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Nov 12 20:49:26.540130 systemd-networkd[756]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Nov 12 20:49:26.540929 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 20:49:26.542012 systemd[1]: Reached target network.target - Network. Nov 12 20:49:26.542153 systemd-networkd[756]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 20:49:26.542157 systemd-networkd[756]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 20:49:26.543077 systemd-networkd[756]: eth0: Link UP Nov 12 20:49:26.543081 systemd-networkd[756]: eth0: Gained carrier Nov 12 20:49:26.543090 systemd-networkd[756]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Nov 12 20:49:26.548860 systemd-networkd[756]: eth1: Link UP Nov 12 20:49:26.548873 systemd-networkd[756]: eth1: Gained carrier Nov 12 20:49:26.548888 systemd-networkd[756]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 20:49:26.549194 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 12 20:49:26.565481 systemd-networkd[756]: eth0: DHCPv4 address 164.92.111.12/19, gateway 164.92.96.1 acquired from 169.254.169.253 Nov 12 20:49:26.569508 systemd-networkd[756]: eth1: DHCPv4 address 10.124.0.22/20 acquired from 169.254.169.253 Nov 12 20:49:26.579451 ignition[759]: Ignition 2.19.0 Nov 12 20:49:26.580225 ignition[759]: Stage: fetch Nov 12 20:49:26.580802 ignition[759]: no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:26.581844 ignition[759]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:26.582592 ignition[759]: parsed url from cmdline: "" Nov 12 20:49:26.582606 ignition[759]: no config URL provided Nov 12 20:49:26.582613 ignition[759]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 20:49:26.582624 ignition[759]: no config at "/usr/lib/ignition/user.ign" Nov 12 20:49:26.582646 ignition[759]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Nov 12 20:49:26.597795 ignition[759]: GET result: OK Nov 12 20:49:26.597930 ignition[759]: parsing config with SHA512: 5e5cdb6e4ca768d2b7b037b0889eceb27e82b7eedb550741f2fc36873a226c027a4098dcaa31c07615857d1196738ef78b217c06b0d72c98cc18762e64134fc2 Nov 12 20:49:26.602127 unknown[759]: fetched base config from "system" Nov 12 20:49:26.602142 unknown[759]: fetched base config from "system" Nov 12 20:49:26.602584 ignition[759]: fetch: fetch complete Nov 12 20:49:26.602153 unknown[759]: fetched user config from "digitalocean" Nov 12 20:49:26.602593 ignition[759]: fetch: fetch passed Nov 12 20:49:26.602672 ignition[759]: Ignition finished successfully Nov 12 20:49:26.604929 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 12 20:49:26.611654 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 20:49:26.643357 ignition[766]: Ignition 2.19.0 Nov 12 20:49:26.643369 ignition[766]: Stage: kargs Nov 12 20:49:26.643593 ignition[766]: no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:26.643605 ignition[766]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:26.644301 ignition[766]: kargs: kargs passed Nov 12 20:49:26.645410 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 20:49:26.644348 ignition[766]: Ignition finished successfully Nov 12 20:49:26.650652 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 20:49:26.671802 ignition[772]: Ignition 2.19.0 Nov 12 20:49:26.671821 ignition[772]: Stage: disks Nov 12 20:49:26.672064 ignition[772]: no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:26.672075 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:26.674762 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 20:49:26.673200 ignition[772]: disks: disks passed Nov 12 20:49:26.673272 ignition[772]: Ignition finished successfully Nov 12 20:49:26.680604 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 20:49:26.681748 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 20:49:26.682498 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 20:49:26.683451 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 20:49:26.683982 systemd[1]: Reached target basic.target - Basic System. Nov 12 20:49:26.695734 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 20:49:26.713097 systemd-fsck[780]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 20:49:26.716163 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 20:49:26.720640 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 20:49:26.835439 kernel: EXT4-fs (vda9): mounted filesystem cc5635ac-cac6-420e-b789-89e3a937cfb2 r/w with ordered data mode. Quota mode: none. Nov 12 20:49:26.835758 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 20:49:26.836881 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 20:49:26.849630 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 20:49:26.852339 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 20:49:26.854645 systemd[1]: Starting flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent... Nov 12 20:49:26.861599 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 12 20:49:26.863925 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (788) Nov 12 20:49:26.865804 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 20:49:26.866998 kernel: BTRFS info (device vda6): first mount of filesystem bdc43ff2-e8de-475f-88ba-e8c26a6bbaa6 Nov 12 20:49:26.867047 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 12 20:49:26.867067 kernel: BTRFS info (device vda6): using free space tree Nov 12 20:49:26.865866 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 20:49:26.874485 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 20:49:26.875957 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 20:49:26.882875 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 20:49:26.889763 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 20:49:26.967679 coreos-metadata[790]: Nov 12 20:49:26.967 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Nov 12 20:49:26.977905 coreos-metadata[791]: Nov 12 20:49:26.977 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Nov 12 20:49:26.980052 coreos-metadata[790]: Nov 12 20:49:26.980 INFO Fetch successful Nov 12 20:49:26.982294 initrd-setup-root[818]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 20:49:26.987359 systemd[1]: flatcar-digitalocean-network.service: Deactivated successfully. Nov 12 20:49:26.988572 systemd[1]: Finished flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent. Nov 12 20:49:26.992523 initrd-setup-root[825]: cut: /sysroot/etc/group: No such file or directory Nov 12 20:49:26.994092 coreos-metadata[791]: Nov 12 20:49:26.993 INFO Fetch successful Nov 12 20:49:26.998164 coreos-metadata[791]: Nov 12 20:49:26.998 INFO wrote hostname ci-4081.2.0-b-5184cdca4b to /sysroot/etc/hostname Nov 12 20:49:27.001792 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 12 20:49:27.004618 initrd-setup-root[834]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 20:49:27.011425 initrd-setup-root[841]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 20:49:27.131035 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 20:49:27.137620 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 20:49:27.151644 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 20:49:27.160446 kernel: BTRFS info (device vda6): last unmount of filesystem bdc43ff2-e8de-475f-88ba-e8c26a6bbaa6 Nov 12 20:49:27.179259 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 20:49:27.201246 ignition[910]: INFO : Ignition 2.19.0 Nov 12 20:49:27.201246 ignition[910]: INFO : Stage: mount Nov 12 20:49:27.202554 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:27.202554 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:27.203492 ignition[910]: INFO : mount: mount passed Nov 12 20:49:27.203492 ignition[910]: INFO : Ignition finished successfully Nov 12 20:49:27.204156 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 20:49:27.208586 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 20:49:27.299539 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 20:49:27.303751 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 20:49:27.319820 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (921) Nov 12 20:49:27.319917 kernel: BTRFS info (device vda6): first mount of filesystem bdc43ff2-e8de-475f-88ba-e8c26a6bbaa6 Nov 12 20:49:27.321876 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 12 20:49:27.321980 kernel: BTRFS info (device vda6): using free space tree Nov 12 20:49:27.327802 kernel: BTRFS info (device vda6): auto enabling async discard Nov 12 20:49:27.329482 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 20:49:27.362539 ignition[938]: INFO : Ignition 2.19.0 Nov 12 20:49:27.362539 ignition[938]: INFO : Stage: files Nov 12 20:49:27.362539 ignition[938]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:27.362539 ignition[938]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:27.365179 ignition[938]: DEBUG : files: compiled without relabeling support, skipping Nov 12 20:49:27.365179 ignition[938]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 20:49:27.365179 ignition[938]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 20:49:27.368841 ignition[938]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 20:49:27.369512 ignition[938]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 20:49:27.369512 ignition[938]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 20:49:27.369448 unknown[938]: wrote ssh authorized keys file for user: core Nov 12 20:49:27.371844 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 12 20:49:27.371844 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 12 20:49:27.371844 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 20:49:27.371844 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 20:49:27.375030 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Nov 12 20:49:27.375030 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Nov 12 20:49:27.375030 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Nov 12 20:49:27.375030 ignition[938]: 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 Nov 12 20:49:27.702576 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 12 20:49:27.830562 systemd-networkd[756]: eth0: Gained IPv6LL Nov 12 20:49:28.012161 ignition[938]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Nov 12 20:49:28.013141 ignition[938]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 20:49:28.013141 ignition[938]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 20:49:28.013141 ignition[938]: INFO : files: files passed Nov 12 20:49:28.013141 ignition[938]: INFO : Ignition finished successfully Nov 12 20:49:28.014643 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 20:49:28.021696 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 20:49:28.024612 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 20:49:28.038921 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 20:49:28.039645 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 20:49:28.048507 initrd-setup-root-after-ignition[966]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 20:49:28.049731 initrd-setup-root-after-ignition[966]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 20:49:28.051433 initrd-setup-root-after-ignition[970]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 20:49:28.053931 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 20:49:28.055313 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 20:49:28.061676 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 20:49:28.107287 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 20:49:28.107445 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 20:49:28.109226 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 20:49:28.109707 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 20:49:28.110858 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 20:49:28.116644 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 20:49:28.133255 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 20:49:28.140689 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 20:49:28.153469 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 20:49:28.154825 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 20:49:28.155376 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 20:49:28.155906 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 20:49:28.156049 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 20:49:28.157234 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 20:49:28.158149 systemd[1]: Stopped target basic.target - Basic System. Nov 12 20:49:28.159213 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 20:49:28.159864 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 20:49:28.160673 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 20:49:28.161492 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 20:49:28.162316 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 20:49:28.163347 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 20:49:28.163899 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 20:49:28.164608 systemd[1]: Stopped target swap.target - Swaps. Nov 12 20:49:28.165287 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 20:49:28.165495 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 20:49:28.166359 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 20:49:28.167166 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 20:49:28.167877 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 20:49:28.167996 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 20:49:28.168711 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 20:49:28.168876 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 20:49:28.169790 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 20:49:28.169926 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 20:49:28.170805 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 20:49:28.170916 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 20:49:28.171558 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 12 20:49:28.171687 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 12 20:49:28.180719 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 20:49:28.182599 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 20:49:28.182776 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 20:49:28.185950 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 20:49:28.188530 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 20:49:28.189230 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 20:49:28.190555 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 20:49:28.190680 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 20:49:28.196428 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 20:49:28.199576 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 20:49:28.200921 ignition[990]: INFO : Ignition 2.19.0 Nov 12 20:49:28.200921 ignition[990]: INFO : Stage: umount Nov 12 20:49:28.200921 ignition[990]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 20:49:28.200921 ignition[990]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Nov 12 20:49:28.200921 ignition[990]: INFO : umount: umount passed Nov 12 20:49:28.200921 ignition[990]: INFO : Ignition finished successfully Nov 12 20:49:28.201901 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 20:49:28.202455 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 20:49:28.207694 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 20:49:28.207764 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 20:49:28.208223 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 20:49:28.208265 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 20:49:28.208648 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 12 20:49:28.208682 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 12 20:49:28.209032 systemd[1]: Stopped target network.target - Network. Nov 12 20:49:28.209368 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 20:49:28.209421 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 20:49:28.210395 systemd[1]: Stopped target paths.target - Path Units. Nov 12 20:49:28.211009 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 20:49:28.214503 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 20:49:28.215051 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 20:49:28.218523 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 20:49:28.218926 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 20:49:28.218998 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 20:49:28.219410 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 20:49:28.219448 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 20:49:28.219823 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 20:49:28.219876 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 20:49:28.220282 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 20:49:28.220332 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 20:49:28.220999 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 20:49:28.221760 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 20:49:28.231672 systemd-networkd[756]: eth1: DHCPv6 lease lost Nov 12 20:49:28.232985 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 20:49:28.233617 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 20:49:28.233722 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 20:49:28.236684 systemd-networkd[756]: eth0: DHCPv6 lease lost Nov 12 20:49:28.237220 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 20:49:28.238485 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 20:49:28.239232 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 20:49:28.239347 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 20:49:28.241859 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 20:49:28.241931 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 20:49:28.242963 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 20:49:28.243019 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 20:49:28.248619 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 20:49:28.249643 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 20:49:28.249746 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 20:49:28.250340 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 20:49:28.250392 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 20:49:28.250792 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 20:49:28.250842 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 20:49:28.251271 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 20:49:28.251319 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 20:49:28.252479 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 20:49:28.267479 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 20:49:28.268395 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 20:49:28.269754 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 20:49:28.270554 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 20:49:28.271685 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 20:49:28.271780 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 20:49:28.272673 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 20:49:28.272724 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 20:49:28.273608 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 20:49:28.273680 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 20:49:28.275051 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 20:49:28.275103 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 20:49:28.276030 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 20:49:28.276098 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 20:49:28.286080 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 20:49:28.286785 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 20:49:28.286875 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 20:49:28.287323 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 20:49:28.287365 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:28.293653 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 20:49:28.293781 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 20:49:28.295287 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 20:49:28.302690 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 20:49:28.312963 systemd[1]: Switching root. Nov 12 20:49:28.357881 systemd-journald[184]: Journal stopped Nov 12 20:49:29.687600 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). Nov 12 20:49:29.687741 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 20:49:29.687766 kernel: SELinux: policy capability open_perms=1 Nov 12 20:49:29.687784 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 20:49:29.687802 kernel: SELinux: policy capability always_check_network=0 Nov 12 20:49:29.687822 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 20:49:29.687858 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 20:49:29.687884 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 20:49:29.687901 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 20:49:29.687918 kernel: audit: type=1403 audit(1731444568.605:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 20:49:29.687946 systemd[1]: Successfully loaded SELinux policy in 53.416ms. Nov 12 20:49:29.687979 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.391ms. Nov 12 20:49:29.688007 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) Nov 12 20:49:29.688036 systemd[1]: Detected virtualization kvm. Nov 12 20:49:29.688097 systemd[1]: Detected architecture x86-64. Nov 12 20:49:29.688122 systemd[1]: Detected first boot. Nov 12 20:49:29.688143 systemd[1]: Hostname set to . Nov 12 20:49:29.688162 systemd[1]: Initializing machine ID from VM UUID. Nov 12 20:49:29.688180 zram_generator::config[1032]: No configuration found. Nov 12 20:49:29.688204 systemd[1]: Populated /etc with preset unit settings. Nov 12 20:49:29.688225 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 20:49:29.688245 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 20:49:29.688265 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 20:49:29.688296 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 20:49:29.688321 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 20:49:29.688345 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 20:49:29.688363 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 20:49:29.688382 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 20:49:29.688430 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 20:49:29.690560 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 20:49:29.690598 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 20:49:29.690617 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 20:49:29.690653 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 20:49:29.690672 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 20:49:29.690692 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 20:49:29.690711 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 20:49:29.690731 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 20:49:29.690755 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 12 20:49:29.690778 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 20:49:29.690800 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 20:49:29.690829 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 20:49:29.690848 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 20:49:29.690869 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 20:49:29.690888 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 20:49:29.690908 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 20:49:29.690929 systemd[1]: Reached target slices.target - Slice Units. Nov 12 20:49:29.690947 systemd[1]: Reached target swap.target - Swaps. Nov 12 20:49:29.690966 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 20:49:29.690991 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 20:49:29.691009 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 20:49:29.691030 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 20:49:29.691048 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 20:49:29.691067 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 20:49:29.691088 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 20:49:29.691105 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 20:49:29.691123 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 20:49:29.691143 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:29.691169 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 20:49:29.691187 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 20:49:29.691204 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 20:49:29.691224 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). Nov 12 20:49:29.691242 systemd[1]: Reached target machines.target - Containers. Nov 12 20:49:29.691261 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 20:49:29.691280 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 20:49:29.691333 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 20:49:29.691357 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 20:49:29.691375 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 20:49:29.691393 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 20:49:29.693549 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 20:49:29.693586 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 20:49:29.693605 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 20:49:29.693626 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 20:49:29.693646 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 20:49:29.693676 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 20:49:29.693696 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 20:49:29.693715 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 20:49:29.693737 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 20:49:29.693756 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 20:49:29.693778 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 20:49:29.693800 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 20:49:29.693823 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 20:49:29.693846 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 20:49:29.693874 systemd[1]: Stopped verity-setup.service. Nov 12 20:49:29.693897 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:29.693918 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 20:49:29.693936 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 20:49:29.693953 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 20:49:29.693974 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 20:49:29.693998 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 20:49:29.694018 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 20:49:29.694040 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 20:49:29.694061 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 20:49:29.694079 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 20:49:29.694099 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 20:49:29.694126 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 20:49:29.694148 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 20:49:29.694186 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 20:49:29.694207 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 20:49:29.694229 kernel: loop: module loaded Nov 12 20:49:29.694250 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 20:49:29.694269 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 20:49:29.694296 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 20:49:29.694316 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 20:49:29.694338 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 20:49:29.694361 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 20:49:29.694382 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 20:49:29.697519 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 20:49:29.697581 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 20:49:29.697606 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 20:49:29.697643 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 20:49:29.697666 kernel: fuse: init (API version 7.39) Nov 12 20:49:29.697690 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 20:49:29.697769 systemd-journald[1101]: Collecting audit messages is disabled. Nov 12 20:49:29.697819 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 20:49:29.697842 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 20:49:29.697863 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 20:49:29.697886 systemd-journald[1101]: Journal started Nov 12 20:49:29.697936 systemd-journald[1101]: Runtime Journal (/run/log/journal/1b167160ce294ce9a265822de3362ed4) is 4.9M, max 39.3M, 34.4M free. Nov 12 20:49:29.276299 systemd[1]: Queued start job for default target multi-user.target. Nov 12 20:49:29.301984 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 12 20:49:29.302666 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 20:49:29.707543 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 20:49:29.712448 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 20:49:29.722447 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 20:49:29.738448 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 20:49:29.741440 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 20:49:29.756750 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 20:49:29.757589 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 20:49:29.760436 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 20:49:29.762443 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 20:49:29.818699 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 20:49:29.826342 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 20:49:29.835946 kernel: loop0: detected capacity change from 0 to 205544 Nov 12 20:49:29.840255 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 20:49:29.843589 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 20:49:29.848414 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 20:49:29.862670 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 20:49:29.870753 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 20:49:29.873950 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 20:49:29.887215 kernel: ACPI: bus type drm_connector registered Nov 12 20:49:29.906540 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 20:49:29.913759 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 20:49:29.914015 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 20:49:29.919231 systemd-journald[1101]: Time spent on flushing to /var/log/journal/1b167160ce294ce9a265822de3362ed4 is 79.689ms for 976 entries. Nov 12 20:49:29.919231 systemd-journald[1101]: System Journal (/var/log/journal/1b167160ce294ce9a265822de3362ed4) is 8.0M, max 195.6M, 187.6M free. Nov 12 20:49:30.032346 systemd-journald[1101]: Received client request to flush runtime journal. Nov 12 20:49:30.035757 kernel: loop1: detected capacity change from 0 to 140768 Nov 12 20:49:30.035806 kernel: loop2: detected capacity change from 0 to 142488 Nov 12 20:49:29.948392 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 20:49:29.952688 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 20:49:30.042184 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 20:49:30.052760 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 20:49:30.063775 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 20:49:30.068629 kernel: loop3: detected capacity change from 0 to 8 Nov 12 20:49:30.129482 kernel: loop4: detected capacity change from 0 to 205544 Nov 12 20:49:30.134846 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 20:49:30.143720 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 20:49:30.196433 kernel: loop5: detected capacity change from 0 to 140768 Nov 12 20:49:30.200680 udevadm[1175]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 12 20:49:30.205732 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. Nov 12 20:49:30.205764 systemd-tmpfiles[1171]: ACLs are not supported, ignoring. Nov 12 20:49:30.227482 kernel: loop6: detected capacity change from 0 to 142488 Nov 12 20:49:30.238905 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 20:49:30.250468 kernel: loop7: detected capacity change from 0 to 8 Nov 12 20:49:30.251146 (sd-merge)[1173]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Nov 12 20:49:30.252484 (sd-merge)[1173]: Merged extensions into '/usr'. Nov 12 20:49:30.261759 systemd[1]: Reloading requested from client PID 1131 ('systemd-sysext') (unit systemd-sysext.service)... Nov 12 20:49:30.261786 systemd[1]: Reloading... Nov 12 20:49:30.384457 zram_generator::config[1203]: No configuration found. Nov 12 20:49:30.586714 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. Nov 12 20:49:30.642499 systemd[1]: Reloading finished in 379 ms. Nov 12 20:49:30.671140 ldconfig[1124]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 20:49:30.675821 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 20:49:30.682723 systemd[1]: Starting ensure-sysext.service... Nov 12 20:49:30.690711 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 20:49:30.693633 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 20:49:30.721614 systemd[1]: Reloading requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... Nov 12 20:49:30.721636 systemd[1]: Reloading... Nov 12 20:49:30.736503 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 20:49:30.736860 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 20:49:30.742692 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 20:49:30.743019 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Nov 12 20:49:30.743107 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Nov 12 20:49:30.749792 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 20:49:30.749806 systemd-tmpfiles[1246]: Skipping /boot Nov 12 20:49:30.766063 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 20:49:30.766083 systemd-tmpfiles[1246]: Skipping /boot Nov 12 20:49:30.868446 zram_generator::config[1277]: No configuration found. Nov 12 20:49:31.008227 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. Nov 12 20:49:31.065022 systemd[1]: Reloading finished in 342 ms. Nov 12 20:49:31.080493 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 20:49:31.104437 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Nov 12 20:49:31.107619 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 20:49:31.111620 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 20:49:31.115604 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 20:49:31.124645 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 20:49:31.130708 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.132478 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 20:49:31.142778 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 20:49:31.149766 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 20:49:31.155967 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 20:49:31.156598 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 20:49:31.156741 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.165735 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 20:49:31.168947 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.169161 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 20:49:31.169348 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 20:49:31.169490 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.173592 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.173836 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 20:49:31.179824 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 20:49:31.180652 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 20:49:31.180893 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.188740 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 20:49:31.195165 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 20:49:31.195926 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 20:49:31.199474 systemd[1]: Finished ensure-sysext.service. Nov 12 20:49:31.223681 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 12 20:49:31.241004 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 20:49:31.261065 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 20:49:31.261511 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 20:49:31.262799 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 20:49:31.263565 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 20:49:31.264812 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 20:49:31.265590 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 20:49:31.267682 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 20:49:31.267755 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 20:49:31.270628 augenrules[1351]: No rules Nov 12 20:49:31.274705 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Nov 12 20:49:31.279133 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 20:49:31.279854 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). Nov 12 20:49:31.289428 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 20:49:31.299514 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 20:49:31.308703 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 20:49:31.309712 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 20:49:31.341610 systemd-udevd[1359]: Using default interface naming scheme 'v255'. Nov 12 20:49:31.351910 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 20:49:31.383929 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 20:49:31.391726 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 20:49:31.397127 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 12 20:49:31.398653 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 20:49:31.431765 systemd-resolved[1322]: Positive Trust Anchors: Nov 12 20:49:31.432925 systemd-resolved[1322]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 20:49:31.433058 systemd-resolved[1322]: 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 Nov 12 20:49:31.439533 systemd-resolved[1322]: Using system hostname 'ci-4081.2.0-b-5184cdca4b'. Nov 12 20:49:31.441312 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 20:49:31.442595 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 20:49:31.485550 systemd-networkd[1370]: lo: Link UP Nov 12 20:49:31.485558 systemd-networkd[1370]: lo: Gained carrier Nov 12 20:49:31.486377 systemd-networkd[1370]: Enumeration completed Nov 12 20:49:31.487188 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 20:49:31.487939 systemd[1]: Reached target network.target - Network. Nov 12 20:49:31.498804 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 20:49:31.530425 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1372) Nov 12 20:49:31.550891 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 12 20:49:31.555432 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1372) Nov 12 20:49:31.563897 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Nov 12 20:49:31.565146 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.565313 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 20:49:31.577010 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 20:49:31.581004 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 20:49:31.588660 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 20:49:31.592137 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 20:49:31.592189 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). Nov 12 20:49:31.592207 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 12 20:49:31.607425 kernel: ISO 9660 Extensions: RRIP_1991A Nov 12 20:49:31.609193 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Nov 12 20:49:31.612294 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 20:49:31.613526 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 20:49:31.618476 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1373) Nov 12 20:49:31.636217 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 20:49:31.636448 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 20:49:31.637820 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 20:49:31.637996 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 20:49:31.641772 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 20:49:31.641837 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 20:49:31.648112 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 12 20:49:31.658756 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 20:49:31.659427 systemd-networkd[1370]: eth0: Configuring with /run/systemd/network/10-36:87:74:fc:77:c6.network. Nov 12 20:49:31.665887 systemd-networkd[1370]: eth0: Link UP Nov 12 20:49:31.665906 systemd-networkd[1370]: eth0: Gained carrier Nov 12 20:49:31.672656 systemd-networkd[1370]: eth1: Configuring with /run/systemd/network/10-9a:93:54:8a:09:7a.network. Nov 12 20:49:31.676839 systemd-networkd[1370]: eth1: Link UP Nov 12 20:49:31.676849 systemd-networkd[1370]: eth1: Gained carrier Nov 12 20:49:31.682295 systemd-timesyncd[1343]: Network configuration changed, trying to establish connection. Nov 12 20:49:31.697493 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Nov 12 20:49:31.697653 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 20:49:31.702471 kernel: ACPI: button: Power Button [PWRF] Nov 12 20:49:31.707450 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Nov 12 20:49:31.746997 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Nov 12 20:49:31.782735 kernel: mousedev: PS/2 mouse device common for all mice Nov 12 20:49:31.783575 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 20:49:31.794467 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Nov 12 20:49:31.794594 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Nov 12 20:49:31.799100 kernel: Console: switching to colour dummy device 80x25 Nov 12 20:49:31.799183 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Nov 12 20:49:31.799229 kernel: [drm] features: -context_init Nov 12 20:49:31.799245 kernel: [drm] number of scanouts: 1 Nov 12 20:49:31.799258 kernel: [drm] number of cap sets: 0 Nov 12 20:49:31.802453 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Nov 12 20:49:31.812429 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Nov 12 20:49:31.812524 kernel: Console: switching to colour frame buffer device 128x48 Nov 12 20:49:31.819492 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Nov 12 20:49:31.847088 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 20:49:31.847863 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:31.866060 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 20:49:31.979476 kernel: EDAC MC: Ver: 3.0.0 Nov 12 20:49:32.005906 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 20:49:32.008779 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 20:49:32.016772 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 20:49:32.040706 lvm[1427]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 20:49:32.073794 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 20:49:32.074386 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 20:49:32.075551 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 20:49:32.075774 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 20:49:32.075894 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 20:49:32.076201 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 20:49:32.076425 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 20:49:32.076506 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 20:49:32.076566 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 20:49:32.076600 systemd[1]: Reached target paths.target - Path Units. Nov 12 20:49:32.076650 systemd[1]: Reached target timers.target - Timer Units. Nov 12 20:49:32.078082 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 20:49:32.081870 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 20:49:32.088026 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 20:49:32.090509 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 20:49:32.091363 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 20:49:32.092063 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 20:49:32.095355 systemd[1]: Reached target basic.target - Basic System. Nov 12 20:49:32.096105 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 20:49:32.096149 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 20:49:32.100627 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 20:49:32.105340 lvm[1432]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 20:49:32.114792 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 12 20:49:32.124715 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 20:49:32.135680 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 20:49:32.145454 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 20:49:32.147337 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). Nov 12 20:49:32.153604 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 20:49:32.160822 jq[1438]: false Nov 12 20:49:32.170672 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 20:49:32.176478 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 20:49:32.187595 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 20:49:32.190996 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 20:49:32.191625 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 20:49:32.200567 coreos-metadata[1434]: Nov 12 20:49:32.200 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Nov 12 20:49:32.201623 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 20:49:32.205602 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 20:49:32.210550 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 20:49:32.212450 coreos-metadata[1434]: Nov 12 20:49:32.211 INFO Fetch successful Nov 12 20:49:32.222960 dbus-daemon[1435]: [system] SELinux support is enabled Nov 12 20:49:32.224719 jq[1445]: true Nov 12 20:49:32.233494 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 20:49:32.244951 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 20:49:32.245142 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 20:49:32.245484 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 20:49:32.245654 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 20:49:32.295919 (ntainerd)[1455]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 20:49:32.307563 jq[1454]: true Nov 12 20:49:32.314879 update_engine[1444]: I20241112 20:49:32.311925 1444 main.cc:92] Flatcar Update Engine starting Nov 12 20:49:32.321496 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 12 20:49:32.324502 extend-filesystems[1439]: Found loop4 Nov 12 20:49:32.324502 extend-filesystems[1439]: Found loop5 Nov 12 20:49:32.324502 extend-filesystems[1439]: Found loop6 Nov 12 20:49:32.324502 extend-filesystems[1439]: Found loop7 Nov 12 20:49:32.324502 extend-filesystems[1439]: Found vda Nov 12 20:49:32.324502 extend-filesystems[1439]: Found vda1 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda2 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda3 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found usr Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda4 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda6 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda7 Nov 12 20:49:32.348136 extend-filesystems[1439]: Found vda9 Nov 12 20:49:32.348136 extend-filesystems[1439]: Checking size of /dev/vda9 Nov 12 20:49:32.327229 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 20:49:32.386804 update_engine[1444]: I20241112 20:49:32.350533 1444 update_check_scheduler.cc:74] Next update check in 11m38s Nov 12 20:49:32.327391 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). Nov 12 20:49:32.327439 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 20:49:32.335124 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). Nov 12 20:49:32.335294 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Nov 12 20:49:32.335325 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 20:49:32.337739 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 20:49:32.338814 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 20:49:32.348326 systemd[1]: Started update-engine.service - Update Engine. Nov 12 20:49:32.367121 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 20:49:32.424434 extend-filesystems[1439]: Resized partition /dev/vda9 Nov 12 20:49:32.442473 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Nov 12 20:49:32.442683 extend-filesystems[1491]: resize2fs 1.47.1 (20-May-2024) Nov 12 20:49:32.453584 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1375) Nov 12 20:49:32.520471 bash[1492]: Updated "/home/core/.ssh/authorized_keys" Nov 12 20:49:32.522800 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 20:49:32.542760 systemd[1]: Starting sshkeys.service... Nov 12 20:49:32.556252 systemd-logind[1443]: New seat seat0. Nov 12 20:49:32.600309 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Nov 12 20:49:32.600474 systemd-logind[1443]: Watching system buttons on /dev/input/event1 (Power Button) Nov 12 20:49:32.600495 systemd-logind[1443]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 12 20:49:32.600952 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 20:49:32.611285 extend-filesystems[1491]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 12 20:49:32.611285 extend-filesystems[1491]: old_desc_blocks = 1, new_desc_blocks = 8 Nov 12 20:49:32.611285 extend-filesystems[1491]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Nov 12 20:49:32.615280 extend-filesystems[1439]: Resized filesystem in /dev/vda9 Nov 12 20:49:32.615280 extend-filesystems[1439]: Found vdb Nov 12 20:49:32.614668 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 20:49:32.615485 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 20:49:32.633531 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 12 20:49:32.650070 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 12 20:49:32.660042 sshd_keygen[1452]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 20:49:32.698042 locksmithd[1474]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 20:49:32.700329 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 20:49:32.718580 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 20:49:32.729625 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 20:49:32.729871 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 20:49:32.743772 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 20:49:32.746686 coreos-metadata[1501]: Nov 12 20:49:32.746 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Nov 12 20:49:32.756530 coreos-metadata[1501]: Nov 12 20:49:32.756 INFO Fetch successful Nov 12 20:49:32.769987 unknown[1501]: wrote ssh authorized keys file for user: core Nov 12 20:49:32.786365 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 20:49:32.801627 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 20:49:32.816851 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 12 20:49:32.824170 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 20:49:32.832684 update-ssh-keys[1525]: Updated "/home/core/.ssh/authorized_keys" Nov 12 20:49:32.834391 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 12 20:49:32.838646 systemd[1]: Finished sshkeys.service. Nov 12 20:49:32.870069 containerd[1455]: time="2024-11-12T20:49:32.869935216Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Nov 12 20:49:32.900420 containerd[1455]: time="2024-11-12T20:49:32.900082440Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.903165 containerd[1455]: time="2024-11-12T20:49:32.903067751Z" 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.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 20:49:32.903165 containerd[1455]: time="2024-11-12T20:49:32.903135094Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 20:49:32.903165 containerd[1455]: time="2024-11-12T20:49:32.903165817Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 20:49:32.903510 containerd[1455]: time="2024-11-12T20:49:32.903473841Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 20:49:32.903559 containerd[1455]: time="2024-11-12T20:49:32.903515640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.903616 containerd[1455]: time="2024-11-12T20:49:32.903597305Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 20:49:32.903653 containerd[1455]: time="2024-11-12T20:49:32.903616975Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.904343 containerd[1455]: time="2024-11-12T20:49:32.904299077Z" 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 Nov 12 20:49:32.904343 containerd[1455]: time="2024-11-12T20:49:32.904331429Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.904343 containerd[1455]: time="2024-11-12T20:49:32.904346898Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 20:49:32.904512 containerd[1455]: time="2024-11-12T20:49:32.904356852Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.904512 containerd[1455]: time="2024-11-12T20:49:32.904491543Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.904775 containerd[1455]: time="2024-11-12T20:49:32.904743980Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 20:49:32.905007 containerd[1455]: time="2024-11-12T20:49:32.904957612Z" 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 Nov 12 20:49:32.905007 containerd[1455]: time="2024-11-12T20:49:32.904983410Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 20:49:32.905146 containerd[1455]: time="2024-11-12T20:49:32.905096297Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 20:49:32.905178 containerd[1455]: time="2024-11-12T20:49:32.905153619Z" level=info msg="metadata content store policy set" policy=shared Nov 12 20:49:32.920627 containerd[1455]: time="2024-11-12T20:49:32.920536386Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 20:49:32.920817 containerd[1455]: time="2024-11-12T20:49:32.920694051Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 20:49:32.920817 containerd[1455]: time="2024-11-12T20:49:32.920730564Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 20:49:32.920817 containerd[1455]: time="2024-11-12T20:49:32.920778932Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 20:49:32.920817 containerd[1455]: time="2024-11-12T20:49:32.920807213Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 20:49:32.921110 containerd[1455]: time="2024-11-12T20:49:32.921019173Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 20:49:32.921472 containerd[1455]: time="2024-11-12T20:49:32.921433355Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 20:49:32.921693 containerd[1455]: time="2024-11-12T20:49:32.921660038Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 20:49:32.921733 containerd[1455]: time="2024-11-12T20:49:32.921696050Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 20:49:32.921766 containerd[1455]: time="2024-11-12T20:49:32.921737457Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 20:49:32.921798 containerd[1455]: time="2024-11-12T20:49:32.921771549Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921798 containerd[1455]: time="2024-11-12T20:49:32.921791132Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921862 containerd[1455]: time="2024-11-12T20:49:32.921808506Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921862 containerd[1455]: time="2024-11-12T20:49:32.921830347Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921918 containerd[1455]: time="2024-11-12T20:49:32.921848878Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921918 containerd[1455]: time="2024-11-12T20:49:32.921881093Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921918 containerd[1455]: time="2024-11-12T20:49:32.921898622Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.921918 containerd[1455]: time="2024-11-12T20:49:32.921910876Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 20:49:32.922005 containerd[1455]: time="2024-11-12T20:49:32.921931777Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922005 containerd[1455]: time="2024-11-12T20:49:32.921946199Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922005 containerd[1455]: time="2024-11-12T20:49:32.921959451Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922005 containerd[1455]: time="2024-11-12T20:49:32.921972210Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922005 containerd[1455]: time="2024-11-12T20:49:32.921983807Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922009998Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922024168Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922042616Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922086555Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922103848Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922114664Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922125968Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922138488Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922335954Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 20:49:32.922383 containerd[1455]: time="2024-11-12T20:49:32.922381695Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922421904Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922433743Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922495928Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922552220Z" 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 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922566877Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922579032Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922588433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922611861Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922628326Z" level=info msg="NRI interface is disabled by configuration." Nov 12 20:49:32.922674 containerd[1455]: time="2024-11-12T20:49:32.922640233Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 20:49:32.923060 containerd[1455]: time="2024-11-12T20:49:32.922966209Z" 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}" Nov 12 20:49:32.923060 containerd[1455]: time="2024-11-12T20:49:32.923052164Z" level=info msg="Connect containerd service" Nov 12 20:49:32.923422 containerd[1455]: time="2024-11-12T20:49:32.923102396Z" level=info msg="using legacy CRI server" Nov 12 20:49:32.923422 containerd[1455]: time="2024-11-12T20:49:32.923112222Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 20:49:32.923422 containerd[1455]: time="2024-11-12T20:49:32.923290121Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 20:49:32.924297 containerd[1455]: time="2024-11-12T20:49:32.924250542Z" 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" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924453289Z" level=info msg="Start subscribing containerd event" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924510845Z" level=info msg="Start recovering state" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924587703Z" level=info msg="Start event monitor" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924600529Z" level=info msg="Start snapshots syncer" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924610816Z" level=info msg="Start cni network conf syncer for default" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924619162Z" level=info msg="Start streaming server" Nov 12 20:49:32.924911 containerd[1455]: time="2024-11-12T20:49:32.924896113Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 20:49:32.925177 containerd[1455]: time="2024-11-12T20:49:32.924974365Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 20:49:32.925178 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 20:49:32.929376 containerd[1455]: time="2024-11-12T20:49:32.929321454Z" level=info msg="containerd successfully booted in 0.062532s" Nov 12 20:49:33.078661 systemd-networkd[1370]: eth1: Gained IPv6LL Nov 12 20:49:33.079186 systemd-timesyncd[1343]: Network configuration changed, trying to establish connection. Nov 12 20:49:33.081206 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 20:49:33.084202 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 20:49:33.100953 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 20:49:33.104969 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 20:49:33.157022 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 20:49:33.718794 systemd-networkd[1370]: eth0: Gained IPv6LL Nov 12 20:49:33.720381 systemd-timesyncd[1343]: Network configuration changed, trying to establish connection. Nov 12 20:49:34.212463 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 20:49:34.213811 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 20:49:34.217477 systemd[1]: Startup finished in 1.020s (kernel) + 4.901s (initrd) + 5.662s (userspace) = 11.585s. Nov 12 20:49:34.224458 (kubelet)[1550]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 12 20:49:35.006074 kubelet[1550]: E1112 20:49:35.005924 1550 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" Nov 12 20:49:35.009598 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 12 20:49:35.009821 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 12 20:49:35.010437 systemd[1]: kubelet.service: Consumed 1.372s CPU time. Nov 12 20:49:36.624794 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 20:49:36.640846 systemd[1]: Started sshd@0-164.92.111.12:22-139.178.68.195:42504.service - OpenSSH per-connection server daemon (139.178.68.195:42504). Nov 12 20:49:36.702833 sshd[1563]: Accepted publickey for core from 139.178.68.195 port 42504 ssh2: RSA SHA256:/Mu5B3+sQwSvJNgAFIVIybGipt6f4mtp7EAYN0WVQJs Nov 12 20:49:36.705900 sshd[1563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 20:49:36.719210 systemd-logind[1443]: New session 1 of user core. Nov 12 20:49:36.720058 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 20:49:36.728802 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 20:49:36.745611 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 20:49:36.751993 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 20:49:36.759546 (systemd)[1567]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 20:49:36.875180 systemd[1567]: Queued start job for default target default.target. Nov 12 20:49:36.887391 systemd[1567]: Created slice app.slice - User Application Slice. Nov 12 20:49:36.887465 systemd[1567]: Reached target paths.target - Paths. Nov 12 20:49:36.887488 systemd[1567]: Reached target timers.target - Timers. Nov 12 20:49:36.889781 systemd[1567]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 20:49:36.904412 systemd[1567]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 20:49:36.904559 systemd[1567]: Reached target sockets.target - Sockets. Nov 12 20:49:36.904575 systemd[1567]: Reached target basic.target - Basic System. Nov 12 20:49:36.904622 systemd[1567]: Reached target default.target - Main User Target. Nov 12 20:49:36.904663 systemd[1567]: Startup finished in 135ms. Nov 12 20:49:36.905087 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 20:49:36.913764 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 20:49:36.988746 systemd[1]: Started sshd@1-164.92.111.12:22-139.178.68.195:42518.service - OpenSSH per-connection server daemon (139.178.68.195:42518). Nov 12 20:49:37.033371 sshd[1578]: Accepted publickey for core from 139.178.68.195 port 42518 ssh2: RSA SHA256:/Mu5B3+sQwSvJNgAFIVIybGipt6f4mtp7EAYN0WVQJs Nov 12 20:49:37.035640 sshd[1578]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 20:49:37.041993 systemd-logind[1443]: New session 2 of user core. Nov 12 20:49:37.048676 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 20:49:37.112207 sshd[1578]: pam_unix(sshd:session): session closed for user core Nov 12 20:49:37.124709 systemd[1]: sshd@1-164.92.111.12:22-139.178.68.195:42518.service: Deactivated successfully. Nov 12 20:49:37.126707 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 20:49:37.129877 systemd-logind[1443]: Session 2 logged out. Waiting for processes to exit. Nov 12 20:49:37.134852 systemd[1]: Started sshd@2-164.92.111.12:22-139.178.68.195:42532.service - OpenSSH per-connection server daemon (139.178.68.195:42532). Nov 12 20:49:37.136896 systemd-logind[1443]: Removed session 2. Nov 12 20:49:37.176469 sshd[1585]: Accepted publickey for core from 139.178.68.195 port 42532 ssh2: RSA SHA256:/Mu5B3+sQwSvJNgAFIVIybGipt6f4mtp7EAYN0WVQJs Nov 12 20:49:37.178767 sshd[1585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 20:49:37.185713 systemd-logind[1443]: New session 3 of user core. Nov 12 20:49:37.192793 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 20:49:37.252360 sshd[1585]: pam_unix(sshd:session): session closed for user core Nov 12 20:49:37.261220 systemd[1]: sshd@2-164.92.111.12:22-139.178.68.195:42532.service: Deactivated successfully. Nov 12 20:49:37.263751 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 20:49:37.265732 systemd-logind[1443]: Session 3 logged out. Waiting for processes to exit. Nov 12 20:49:37.271924 systemd[1]: Started sshd@3-164.92.111.12:22-139.178.68.195:42548.service - OpenSSH per-connection server daemon (139.178.68.195:42548). Nov 12 20:49:37.273441 systemd-logind[1443]: Removed session 3. Nov 12 20:49:37.312469 sshd[1592]: Accepted publickey for core from 139.178.68.195 port 42548 ssh2: RSA SHA256:/Mu5B3+sQwSvJNgAFIVIybGipt6f4mtp7EAYN0WVQJs Nov 12 20:49:37.314589 sshd[1592]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 20:49:37.321037 systemd-logind[1443]: New session 4 of user core. Nov 12 20:49:37.332684 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 20:49:37.397695 sshd[1592]: pam_unix(sshd:session): session closed for user core Nov 12 20:49:37.413560 systemd[1]: sshd@3-164.92.111.12:22-139.178.68.195:42548.service: Deactivated successfully. Nov 12 20:49:37.415749 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 20:49:37.417583 systemd-logind[1443]: Session 4 logged out. Waiting for processes to exit. Nov 12 20:49:37.427944 systemd[1]: Started sshd@4-164.92.111.12:22-139.178.68.195:42552.service - OpenSSH per-connection server daemon (139.178.68.195:42552). Nov 12 20:49:37.430007 systemd-logind[1443]: Removed session 4. Nov 12 20:49:37.473526 sshd[1599]: Accepted publickey for core from 139.178.68.195 port 42552 ssh2: RSA SHA256:/Mu5B3+sQwSvJNgAFIVIybGipt6f4mtp7EAYN0WVQJs Nov 12 20:49:37.476204 sshd[1599]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 20:49:37.482045 systemd-logind[1443]: New session 5 of user core. Nov 12 20:49:37.488693 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 20:49:37.561269 sudo[1602]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 12 20:49:37.562372 sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 20:49:38.288076 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 20:49:38.288439 systemd[1]: kubelet.service: Consumed 1.372s CPU time. Nov 12 20:49:38.300972 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 20:49:38.342080 systemd[1]: Reloading requested from client PID 1633 ('systemctl') (unit session-5.scope)... Nov 12 20:49:38.342102 systemd[1]: Reloading... Nov 12 20:49:38.498435 zram_generator::config[1671]: No configuration found. Nov 12 20:49:38.632120 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. Nov 12 20:49:38.717697 systemd[1]: Reloading finished in 375 ms. Nov 12 20:49:38.778731 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 12 20:49:38.778816 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 12 20:49:38.779284 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 20:49:38.785825 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 20:49:38.928712 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 20:49:38.930154 (kubelet)[1726]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 12 20:49:38.982332 kubelet[1726]: 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. Nov 12 20:49:38.982728 kubelet[1726]: 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. Nov 12 20:49:38.982781 kubelet[1726]: 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. Nov 12 20:49:38.984093 kubelet[1726]: I1112 20:49:38.984025 1726 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" Nov 12 20:49:39.139642 kubelet[1726]: I1112 20:49:39.139587 1726 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Nov 12 20:49:39.139642 kubelet[1726]: I1112 20:49:39.139628 1726 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 12 20:49:39.139998 kubelet[1726]: I1112 20:49:39.139960 1726 server.go:929] "Client rotation is on, will bootstrap in background" Nov 12 20:49:39.166490 kubelet[1726]: I1112 20:49:39.166270 1726 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 12 20:49:39.200560 kubelet[1726]: E1112 20:49:39.200435 1726 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 12 20:49:39.200800 kubelet[1726]: I1112 20:49:39.200685 1726 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." Nov 12 20:49:39.210274 kubelet[1726]: I1112 20:49:39.209390 1726 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 12 20:49:39.210274 kubelet[1726]: I1112 20:49:39.209523 1726 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Nov 12 20:49:39.210274 kubelet[1726]: I1112 20:49:39.209669 1726 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 12 20:49:39.210274 kubelet[1726]: I1112 20:49:39.209695 1726 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"164.92.111.12","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 12 20:49:39.210578 kubelet[1726]: I1112 20:49:39.209866 1726 topology_manager.go:138] "Creating topology manager with none policy" Nov 12 20:49:39.210578 kubelet[1726]: I1112 20:49:39.209877 1726 container_manager_linux.go:300] "Creating device plugin manager" Nov 12 20:49:39.210578 kubelet[1726]: I1112 20:49:39.210002 1726 state_mem.go:36] "Initialized new in-memory state store" Nov 12 20:49:39.211678 kubelet[1726]: I1112 20:49:39.211651 1726 kubelet.go:408] "Attempting to sync node with API server" Nov 12 20:49:39.211767 kubelet[1726]: I1112 20:49:39.211759 1726 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 12 20:49:39.211833 kubelet[1726]: I1112 20:49:39.211826 1726 kubelet.go:314] "Adding apiserver pod source" Nov 12 20:49:39.211889 kubelet[1726]: I1112 20:49:39.211883 1726 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 12 20:49:39.212374 kubelet[1726]: E1112 20:49:39.212323 1726 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:39.213476 kubelet[1726]: E1112 20:49:39.212432 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:39.217928 kubelet[1726]: I1112 20:49:39.217752 1726 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Nov 12 20:49:39.220633 kubelet[1726]: I1112 20:49:39.220446 1726 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 12 20:49:39.222474 kubelet[1726]: W1112 20:49:39.222058 1726 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 12 20:49:39.223179 kubelet[1726]: I1112 20:49:39.222876 1726 server.go:1269] "Started kubelet" Nov 12 20:49:39.225425 kubelet[1726]: I1112 20:49:39.224113 1726 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Nov 12 20:49:39.225425 kubelet[1726]: I1112 20:49:39.225133 1726 server.go:460] "Adding debug handlers to kubelet server" Nov 12 20:49:39.225425 kubelet[1726]: I1112 20:49:39.225161 1726 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 12 20:49:39.226392 kubelet[1726]: I1112 20:49:39.226339 1726 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 12 20:49:39.226704 kubelet[1726]: I1112 20:49:39.226690 1726 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 12 20:49:39.234921 kubelet[1726]: I1112 20:49:39.234782 1726 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 12 20:49:39.236756 kubelet[1726]: I1112 20:49:39.236729 1726 volume_manager.go:289] "Starting Kubelet Volume Manager" Nov 12 20:49:39.237051 kubelet[1726]: I1112 20:49:39.237031 1726 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Nov 12 20:49:39.237185 kubelet[1726]: I1112 20:49:39.237164 1726 reconciler.go:26] "Reconciler: start to sync state" Nov 12 20:49:39.237964 kubelet[1726]: E1112 20:49:39.237917 1726 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"164.92.111.12\" not found" Nov 12 20:49:39.238572 kubelet[1726]: I1112 20:49:39.238545 1726 factory.go:221] Registration of the systemd container factory successfully Nov 12 20:49:39.238798 kubelet[1726]: I1112 20:49:39.238779 1726 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 Nov 12 20:49:39.241447 kubelet[1726]: I1112 20:49:39.241425 1726 factory.go:221] Registration of the containerd container factory successfully Nov 12 20:49:39.260912 kubelet[1726]: E1112 20:49:39.260853 1726 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 12 20:49:39.263236 kubelet[1726]: E1112 20:49:39.263180 1726 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"164.92.111.12\" not found" node="164.92.111.12" Nov 12 20:49:39.264820 kubelet[1726]: I1112 20:49:39.264784 1726 cpu_manager.go:214] "Starting CPU manager" policy="none" Nov 12 20:49:39.265093 kubelet[1726]: I1112 20:49:39.264957 1726 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Nov 12 20:49:39.265093 kubelet[1726]: I1112 20:49:39.264982 1726 state_mem.go:36] "Initialized new in-memory state store" Nov 12 20:49:39.271993 kubelet[1726]: I1112 20:49:39.271850 1726 policy_none.go:49] "None policy: Start" Nov 12 20:49:39.273555 kubelet[1726]: I1112 20:49:39.273050 1726 memory_manager.go:170] "Starting memorymanager" policy="None" Nov 12 20:49:39.273555 kubelet[1726]: I1112 20:49:39.273078 1726 state_mem.go:35] "Initializing new in-memory state store" Nov 12 20:49:39.288134 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 12 20:49:39.306800 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 12 20:49:39.311019 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 12 20:49:39.320436 kubelet[1726]: I1112 20:49:39.319853 1726 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 12 20:49:39.320436 kubelet[1726]: I1112 20:49:39.320077 1726 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 12 20:49:39.320436 kubelet[1726]: I1112 20:49:39.320092 1726 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 12 20:49:39.321767 kubelet[1726]: I1112 20:49:39.321065 1726 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 12 20:49:39.328468 kubelet[1726]: E1112 20:49:39.328439 1726 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"164.92.111.12\" not found" Nov 12 20:49:39.336200 kubelet[1726]: I1112 20:49:39.336143 1726 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 12 20:49:39.337704 kubelet[1726]: I1112 20:49:39.337673 1726 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 12 20:49:39.337704 kubelet[1726]: I1112 20:49:39.337703 1726 status_manager.go:217] "Starting to sync pod status with apiserver" Nov 12 20:49:39.338075 kubelet[1726]: I1112 20:49:39.337724 1726 kubelet.go:2321] "Starting kubelet main sync loop" Nov 12 20:49:39.338075 kubelet[1726]: E1112 20:49:39.337786 1726 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 12 20:49:39.421940 kubelet[1726]: I1112 20:49:39.421897 1726 kubelet_node_status.go:72] "Attempting to register node" node="164.92.111.12" Nov 12 20:49:39.436446 kubelet[1726]: I1112 20:49:39.436043 1726 kubelet_node_status.go:75] "Successfully registered node" node="164.92.111.12" Nov 12 20:49:39.457786 kubelet[1726]: I1112 20:49:39.456470 1726 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 12 20:49:39.458311 containerd[1455]: time="2024-11-12T20:49:39.457941948Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 12 20:49:39.459711 kubelet[1726]: I1112 20:49:39.458375 1726 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 12 20:49:39.504527 sudo[1602]: pam_unix(sudo:session): session closed for user root Nov 12 20:49:39.508276 sshd[1599]: pam_unix(sshd:session): session closed for user core Nov 12 20:49:39.512742 systemd[1]: sshd@4-164.92.111.12:22-139.178.68.195:42552.service: Deactivated successfully. Nov 12 20:49:39.515110 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 20:49:39.515957 systemd-logind[1443]: Session 5 logged out. Waiting for processes to exit. Nov 12 20:49:39.517589 systemd-logind[1443]: Removed session 5. Nov 12 20:49:40.141923 kubelet[1726]: I1112 20:49:40.141848 1726 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 12 20:49:40.143267 kubelet[1726]: W1112 20:49:40.142659 1726 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 12 20:49:40.143267 kubelet[1726]: W1112 20:49:40.142720 1726 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 Nov 12 20:49:40.143267 kubelet[1726]: W1112 20:49:40.142751 1726 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 Nov 12 20:49:40.213080 kubelet[1726]: E1112 20:49:40.212994 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:40.213080 kubelet[1726]: I1112 20:49:40.213042 1726 apiserver.go:52] "Watching apiserver" Nov 12 20:49:40.226617 systemd[1]: Created slice kubepods-besteffort-pod380fb1ec_ac8c_417e_b25a_5bb03a57c942.slice - libcontainer container kubepods-besteffort-pod380fb1ec_ac8c_417e_b25a_5bb03a57c942.slice. Nov 12 20:49:40.238373 kubelet[1726]: I1112 20:49:40.238325 1726 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Nov 12 20:49:40.242856 kubelet[1726]: I1112 20:49:40.241954 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/cbba257d-912e-4016-a2d5-2a34be673166-flannel-cfg\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.242856 kubelet[1726]: I1112 20:49:40.242018 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cbba257d-912e-4016-a2d5-2a34be673166-xtables-lock\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.242856 kubelet[1726]: I1112 20:49:40.242047 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/380fb1ec-ac8c-417e-b25a-5bb03a57c942-kube-proxy\") pod \"kube-proxy-crb2w\" (UID: \"380fb1ec-ac8c-417e-b25a-5bb03a57c942\") " pod="kube-system/kube-proxy-crb2w" Nov 12 20:49:40.242856 kubelet[1726]: I1112 20:49:40.242073 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/380fb1ec-ac8c-417e-b25a-5bb03a57c942-xtables-lock\") pod \"kube-proxy-crb2w\" (UID: \"380fb1ec-ac8c-417e-b25a-5bb03a57c942\") " pod="kube-system/kube-proxy-crb2w" Nov 12 20:49:40.242856 kubelet[1726]: I1112 20:49:40.242337 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/380fb1ec-ac8c-417e-b25a-5bb03a57c942-lib-modules\") pod \"kube-proxy-crb2w\" (UID: \"380fb1ec-ac8c-417e-b25a-5bb03a57c942\") " pod="kube-system/kube-proxy-crb2w" Nov 12 20:49:40.243260 kubelet[1726]: I1112 20:49:40.242361 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-56l97\" (UniqueName: \"kubernetes.io/projected/380fb1ec-ac8c-417e-b25a-5bb03a57c942-kube-api-access-56l97\") pod \"kube-proxy-crb2w\" (UID: \"380fb1ec-ac8c-417e-b25a-5bb03a57c942\") " pod="kube-system/kube-proxy-crb2w" Nov 12 20:49:40.243260 kubelet[1726]: I1112 20:49:40.242388 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/cbba257d-912e-4016-a2d5-2a34be673166-run\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.243260 kubelet[1726]: I1112 20:49:40.242434 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/cbba257d-912e-4016-a2d5-2a34be673166-cni-plugin\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.243260 kubelet[1726]: I1112 20:49:40.242460 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sn67m\" (UniqueName: \"kubernetes.io/projected/cbba257d-912e-4016-a2d5-2a34be673166-kube-api-access-sn67m\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.243260 kubelet[1726]: I1112 20:49:40.242483 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/cbba257d-912e-4016-a2d5-2a34be673166-cni\") pod \"kube-flannel-ds-rvld8\" (UID: \"cbba257d-912e-4016-a2d5-2a34be673166\") " pod="kube-flannel/kube-flannel-ds-rvld8" Nov 12 20:49:40.245498 systemd[1]: Created slice kubepods-burstable-podcbba257d_912e_4016_a2d5_2a34be673166.slice - libcontainer container kubepods-burstable-podcbba257d_912e_4016_a2d5_2a34be673166.slice. Nov 12 20:49:40.540635 kubelet[1726]: E1112 20:49:40.540577 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:40.541561 containerd[1455]: time="2024-11-12T20:49:40.541499901Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-crb2w,Uid:380fb1ec-ac8c-417e-b25a-5bb03a57c942,Namespace:kube-system,Attempt:0,}" Nov 12 20:49:40.555155 kubelet[1726]: E1112 20:49:40.554581 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:40.555776 containerd[1455]: time="2024-11-12T20:49:40.555697413Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-rvld8,Uid:cbba257d-912e-4016-a2d5-2a34be673166,Namespace:kube-flannel,Attempt:0,}" Nov 12 20:49:41.186012 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2428049286.mount: Deactivated successfully. Nov 12 20:49:41.199391 containerd[1455]: time="2024-11-12T20:49:41.197962374Z" 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\"}" Nov 12 20:49:41.199391 containerd[1455]: time="2024-11-12T20:49:41.198981272Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Nov 12 20:49:41.199770 containerd[1455]: time="2024-11-12T20:49:41.199643861Z" 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\"}" Nov 12 20:49:41.200418 containerd[1455]: time="2024-11-12T20:49:41.200361854Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 12 20:49:41.201417 containerd[1455]: time="2024-11-12T20:49:41.201367036Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 20:49:41.205263 containerd[1455]: time="2024-11-12T20:49:41.205220513Z" 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\"}" Nov 12 20:49:41.206495 containerd[1455]: time="2024-11-12T20:49:41.206281266Z" 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 664.658518ms" Nov 12 20:49:41.211181 containerd[1455]: time="2024-11-12T20:49:41.211133705Z" 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 654.62264ms" Nov 12 20:49:41.213438 kubelet[1726]: E1112 20:49:41.213374 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:41.391476 containerd[1455]: time="2024-11-12T20:49:41.388057386Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 20:49:41.391476 containerd[1455]: time="2024-11-12T20:49:41.389839904Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 20:49:41.391476 containerd[1455]: time="2024-11-12T20:49:41.389855963Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:41.391476 containerd[1455]: time="2024-11-12T20:49:41.389971551Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:41.397861 containerd[1455]: time="2024-11-12T20:49:41.397575389Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 20:49:41.397861 containerd[1455]: time="2024-11-12T20:49:41.397686376Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 20:49:41.397861 containerd[1455]: time="2024-11-12T20:49:41.397709736Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:41.398851 containerd[1455]: time="2024-11-12T20:49:41.398234880Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:41.502629 systemd[1]: run-containerd-runc-k8s.io-e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa-runc.YiAzga.mount: Deactivated successfully. Nov 12 20:49:41.514269 systemd[1]: Started cri-containerd-e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa.scope - libcontainer container e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa. Nov 12 20:49:41.522990 systemd[1]: Started cri-containerd-af2520c0e0edc08e9ddfe4e6bf4c54b6cce6415c3a55ee704eee2c1adc76f304.scope - libcontainer container af2520c0e0edc08e9ddfe4e6bf4c54b6cce6415c3a55ee704eee2c1adc76f304. Nov 12 20:49:41.580826 containerd[1455]: time="2024-11-12T20:49:41.580770325Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-crb2w,Uid:380fb1ec-ac8c-417e-b25a-5bb03a57c942,Namespace:kube-system,Attempt:0,} returns sandbox id \"af2520c0e0edc08e9ddfe4e6bf4c54b6cce6415c3a55ee704eee2c1adc76f304\"" Nov 12 20:49:41.583849 kubelet[1726]: E1112 20:49:41.582867 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:41.586796 containerd[1455]: time="2024-11-12T20:49:41.586758806Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.2\"" Nov 12 20:49:41.599928 containerd[1455]: time="2024-11-12T20:49:41.599850135Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-rvld8,Uid:cbba257d-912e-4016-a2d5-2a34be673166,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\"" Nov 12 20:49:41.601056 kubelet[1726]: E1112 20:49:41.601031 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:42.214788 kubelet[1726]: E1112 20:49:42.214729 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:42.692274 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount464988856.mount: Deactivated successfully. Nov 12 20:49:43.215526 kubelet[1726]: E1112 20:49:43.215455 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:43.251822 containerd[1455]: time="2024-11-12T20:49:43.250769162Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:43.251822 containerd[1455]: time="2024-11-12T20:49:43.251568753Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.2: active requests=0, bytes read=30226814" Nov 12 20:49:43.252438 containerd[1455]: time="2024-11-12T20:49:43.252391744Z" level=info msg="ImageCreate event name:\"sha256:505d571f5fd56726488d27af0d9a8e02c6db58f5d62ea51dd10d47de7a0c2d38\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:43.260131 containerd[1455]: time="2024-11-12T20:49:43.260066768Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:62128d752eb4a9162074697aba46adea4abb8aab2a53c992f20881365b61a4fe\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:43.261945 containerd[1455]: time="2024-11-12T20:49:43.261785510Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.2\" with image id \"sha256:505d571f5fd56726488d27af0d9a8e02c6db58f5d62ea51dd10d47de7a0c2d38\", repo tag \"registry.k8s.io/kube-proxy:v1.31.2\", repo digest \"registry.k8s.io/kube-proxy@sha256:62128d752eb4a9162074697aba46adea4abb8aab2a53c992f20881365b61a4fe\", size \"30225833\" in 1.674832489s" Nov 12 20:49:43.261945 containerd[1455]: time="2024-11-12T20:49:43.261920443Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.2\" returns image reference \"sha256:505d571f5fd56726488d27af0d9a8e02c6db58f5d62ea51dd10d47de7a0c2d38\"" Nov 12 20:49:43.263974 containerd[1455]: time="2024-11-12T20:49:43.263611117Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 12 20:49:43.265414 containerd[1455]: time="2024-11-12T20:49:43.265376206Z" level=info msg="CreateContainer within sandbox \"af2520c0e0edc08e9ddfe4e6bf4c54b6cce6415c3a55ee704eee2c1adc76f304\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 12 20:49:43.295846 containerd[1455]: time="2024-11-12T20:49:43.295758124Z" level=info msg="CreateContainer within sandbox \"af2520c0e0edc08e9ddfe4e6bf4c54b6cce6415c3a55ee704eee2c1adc76f304\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9fb7a2e49eeffcd0618ad9590bdc3079fdbc920616f613e0693d33c2ada93013\"" Nov 12 20:49:43.296733 containerd[1455]: time="2024-11-12T20:49:43.296682489Z" level=info msg="StartContainer for \"9fb7a2e49eeffcd0618ad9590bdc3079fdbc920616f613e0693d33c2ada93013\"" Nov 12 20:49:43.333676 systemd[1]: Started cri-containerd-9fb7a2e49eeffcd0618ad9590bdc3079fdbc920616f613e0693d33c2ada93013.scope - libcontainer container 9fb7a2e49eeffcd0618ad9590bdc3079fdbc920616f613e0693d33c2ada93013. Nov 12 20:49:43.378859 containerd[1455]: time="2024-11-12T20:49:43.378801141Z" level=info msg="StartContainer for \"9fb7a2e49eeffcd0618ad9590bdc3079fdbc920616f613e0693d33c2ada93013\" returns successfully" Nov 12 20:49:44.215832 kubelet[1726]: E1112 20:49:44.215780 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:44.362211 kubelet[1726]: E1112 20:49:44.362049 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:44.378982 kubelet[1726]: I1112 20:49:44.378893 1726 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-crb2w" podStartSLOduration=3.701442448 podStartE2EDuration="5.378875057s" podCreationTimestamp="2024-11-12 20:49:39 +0000 UTC" firstStartedPulling="2024-11-12 20:49:41.58599694 +0000 UTC m=+2.649698210" lastFinishedPulling="2024-11-12 20:49:43.263429546 +0000 UTC m=+4.327130819" observedRunningTime="2024-11-12 20:49:44.3788225 +0000 UTC m=+5.442523788" watchObservedRunningTime="2024-11-12 20:49:44.378875057 +0000 UTC m=+5.442576349" Nov 12 20:49:45.122169 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount811355828.mount: Deactivated successfully. Nov 12 20:49:45.171681 containerd[1455]: time="2024-11-12T20:49:45.171617270Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:45.173098 containerd[1455]: time="2024-11-12T20:49:45.172778124Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Nov 12 20:49:45.173845 containerd[1455]: time="2024-11-12T20:49:45.173784797Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:45.179486 containerd[1455]: time="2024-11-12T20:49:45.179343051Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:45.180269 containerd[1455]: time="2024-11-12T20:49:45.180118829Z" 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 1.91647466s" Nov 12 20:49:45.180269 containerd[1455]: time="2024-11-12T20:49:45.180156295Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Nov 12 20:49:45.182785 containerd[1455]: time="2024-11-12T20:49:45.182750212Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 12 20:49:45.204819 containerd[1455]: time="2024-11-12T20:49:45.204678965Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219\"" Nov 12 20:49:45.205706 containerd[1455]: time="2024-11-12T20:49:45.205674899Z" level=info msg="StartContainer for \"c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219\"" Nov 12 20:49:45.216601 kubelet[1726]: E1112 20:49:45.216546 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:45.238613 systemd[1]: Started cri-containerd-c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219.scope - libcontainer container c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219. Nov 12 20:49:45.270374 systemd[1]: cri-containerd-c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219.scope: Deactivated successfully. Nov 12 20:49:45.274141 containerd[1455]: time="2024-11-12T20:49:45.273440203Z" level=info msg="StartContainer for \"c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219\" returns successfully" Nov 12 20:49:45.346243 containerd[1455]: time="2024-11-12T20:49:45.346153400Z" level=info msg="shim disconnected" id=c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219 namespace=k8s.io Nov 12 20:49:45.346243 containerd[1455]: time="2024-11-12T20:49:45.346235888Z" level=warning msg="cleaning up after shim disconnected" id=c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219 namespace=k8s.io Nov 12 20:49:45.346243 containerd[1455]: time="2024-11-12T20:49:45.346248081Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 20:49:45.363798 containerd[1455]: time="2024-11-12T20:49:45.363741958Z" level=warning msg="cleanup warnings time=\"2024-11-12T20:49:45Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Nov 12 20:49:45.369678 kubelet[1726]: E1112 20:49:45.369428 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:45.369678 kubelet[1726]: E1112 20:49:45.369618 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:45.371432 containerd[1455]: time="2024-11-12T20:49:45.371133028Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 12 20:49:46.034381 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c73c69d8090c83d728dd58a6c60dbccfa427b085369c1471706aaee8e448e219-rootfs.mount: Deactivated successfully. Nov 12 20:49:46.217289 kubelet[1726]: E1112 20:49:46.217225 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:46.710756 systemd-resolved[1322]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3. Nov 12 20:49:47.218039 kubelet[1726]: E1112 20:49:47.217943 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:47.289920 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4000019231.mount: Deactivated successfully. Nov 12 20:49:48.154891 containerd[1455]: time="2024-11-12T20:49:48.153821417Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Nov 12 20:49:48.154891 containerd[1455]: time="2024-11-12T20:49:48.154822353Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:48.157261 containerd[1455]: time="2024-11-12T20:49:48.157214496Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:48.158585 containerd[1455]: time="2024-11-12T20:49:48.158554101Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 2.787379105s" Nov 12 20:49:48.158702 containerd[1455]: time="2024-11-12T20:49:48.158688100Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Nov 12 20:49:48.159128 containerd[1455]: time="2024-11-12T20:49:48.159095690Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:48.161681 containerd[1455]: time="2024-11-12T20:49:48.161643870Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 12 20:49:48.175748 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2986982750.mount: Deactivated successfully. Nov 12 20:49:48.187533 containerd[1455]: time="2024-11-12T20:49:48.187427539Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba\"" Nov 12 20:49:48.188455 containerd[1455]: time="2024-11-12T20:49:48.188394956Z" level=info msg="StartContainer for \"fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba\"" Nov 12 20:49:48.218787 kubelet[1726]: E1112 20:49:48.218721 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:48.234902 systemd[1]: Started cri-containerd-fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba.scope - libcontainer container fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba. Nov 12 20:49:48.269943 systemd[1]: cri-containerd-fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba.scope: Deactivated successfully. Nov 12 20:49:48.277111 containerd[1455]: time="2024-11-12T20:49:48.276790904Z" level=info msg="StartContainer for \"fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba\" returns successfully" Nov 12 20:49:48.301650 kubelet[1726]: I1112 20:49:48.301617 1726 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Nov 12 20:49:48.379547 kubelet[1726]: E1112 20:49:48.379509 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:48.405354 containerd[1455]: time="2024-11-12T20:49:48.405194967Z" level=info msg="shim disconnected" id=fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba namespace=k8s.io Nov 12 20:49:48.405354 containerd[1455]: time="2024-11-12T20:49:48.405260782Z" level=warning msg="cleaning up after shim disconnected" id=fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba namespace=k8s.io Nov 12 20:49:48.405354 containerd[1455]: time="2024-11-12T20:49:48.405270171Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 20:49:49.173386 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-fb286d748e71924472fea52b638b43e11510471351809418f3927423a10c5bba-rootfs.mount: Deactivated successfully. Nov 12 20:49:49.219294 kubelet[1726]: E1112 20:49:49.219225 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:49.383587 kubelet[1726]: E1112 20:49:49.383544 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:49.385834 containerd[1455]: time="2024-11-12T20:49:49.385797840Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 12 20:49:49.412811 containerd[1455]: time="2024-11-12T20:49:49.412713566Z" level=info msg="CreateContainer within sandbox \"e4ddf7edc02f081214aabcb766dbd8b1ad63ea89824dac3c36c7c1f883166afa\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"5129362429130485f1b11c658024f5757463db5e8fc76319dfe7ffa9de36c2f8\"" Nov 12 20:49:49.413554 containerd[1455]: time="2024-11-12T20:49:49.413519413Z" level=info msg="StartContainer for \"5129362429130485f1b11c658024f5757463db5e8fc76319dfe7ffa9de36c2f8\"" Nov 12 20:49:49.456724 systemd[1]: Started cri-containerd-5129362429130485f1b11c658024f5757463db5e8fc76319dfe7ffa9de36c2f8.scope - libcontainer container 5129362429130485f1b11c658024f5757463db5e8fc76319dfe7ffa9de36c2f8. Nov 12 20:49:49.491427 containerd[1455]: time="2024-11-12T20:49:49.491367681Z" level=info msg="StartContainer for \"5129362429130485f1b11c658024f5757463db5e8fc76319dfe7ffa9de36c2f8\" returns successfully" Nov 12 20:49:49.782733 systemd-resolved[1322]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Nov 12 20:49:50.219857 kubelet[1726]: E1112 20:49:50.219777 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:50.387619 kubelet[1726]: E1112 20:49:50.387536 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:50.417899 kubelet[1726]: I1112 20:49:50.417831 1726 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-rvld8" podStartSLOduration=4.860059098 podStartE2EDuration="11.417805211s" podCreationTimestamp="2024-11-12 20:49:39 +0000 UTC" firstStartedPulling="2024-11-12 20:49:41.60239193 +0000 UTC m=+2.666093216" lastFinishedPulling="2024-11-12 20:49:48.160138044 +0000 UTC m=+9.223839329" observedRunningTime="2024-11-12 20:49:50.417659782 +0000 UTC m=+11.481361074" watchObservedRunningTime="2024-11-12 20:49:50.417805211 +0000 UTC m=+11.481506501" Nov 12 20:49:50.541261 systemd-timesyncd[1343]: Network configuration changed, trying to establish connection. Nov 12 20:49:50.555976 systemd-networkd[1370]: flannel.1: Link UP Nov 12 20:49:50.555988 systemd-networkd[1370]: flannel.1: Gained carrier Nov 12 20:49:50.603286 systemd-timesyncd[1343]: Contacted time server 99.28.14.242:123 (2.flatcar.pool.ntp.org). Nov 12 20:49:50.603349 systemd-timesyncd[1343]: Initial clock synchronization to Tue 2024-11-12 20:49:50.880764 UTC. Nov 12 20:49:50.741626 systemd[1]: Created slice kubepods-besteffort-pod86e9f703_8168_4671_aa9b_d00ca1862b0c.slice - libcontainer container kubepods-besteffort-pod86e9f703_8168_4671_aa9b_d00ca1862b0c.slice. Nov 12 20:49:50.808710 kubelet[1726]: I1112 20:49:50.808542 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6qftb\" (UniqueName: \"kubernetes.io/projected/86e9f703-8168-4671-aa9b-d00ca1862b0c-kube-api-access-6qftb\") pod \"nginx-deployment-8587fbcb89-n9d2r\" (UID: \"86e9f703-8168-4671-aa9b-d00ca1862b0c\") " pod="default/nginx-deployment-8587fbcb89-n9d2r" Nov 12 20:49:51.046215 containerd[1455]: time="2024-11-12T20:49:51.046147215Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-n9d2r,Uid:86e9f703-8168-4671-aa9b-d00ca1862b0c,Namespace:default,Attempt:0,}" Nov 12 20:49:51.080676 systemd-networkd[1370]: cni0: Link UP Nov 12 20:49:51.089460 systemd-networkd[1370]: veth65a48d55: Link UP Nov 12 20:49:51.091412 kernel: cni0: port 1(veth65a48d55) entered blocking state Nov 12 20:49:51.091510 kernel: cni0: port 1(veth65a48d55) entered disabled state Nov 12 20:49:51.091542 kernel: veth65a48d55: entered allmulticast mode Nov 12 20:49:51.094053 kernel: veth65a48d55: entered promiscuous mode Nov 12 20:49:51.094147 kernel: cni0: port 1(veth65a48d55) entered blocking state Nov 12 20:49:51.094167 kernel: cni0: port 1(veth65a48d55) entered forwarding state Nov 12 20:49:51.096476 kernel: cni0: port 1(veth65a48d55) entered disabled state Nov 12 20:49:51.104463 kernel: cni0: port 1(veth65a48d55) entered blocking state Nov 12 20:49:51.104560 kernel: cni0: port 1(veth65a48d55) entered forwarding state Nov 12 20:49:51.104783 systemd-networkd[1370]: veth65a48d55: Gained carrier Nov 12 20:49:51.106096 systemd-networkd[1370]: cni0: Gained carrier Nov 12 20:49:51.113166 containerd[1455]: 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"} Nov 12 20:49:51.113166 containerd[1455]: delegateAdd: netconf sent to delegate plugin: Nov 12 20:49:51.139173 containerd[1455]: {"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="2024-11-12T20:49:51.139040603Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 20:49:51.139766 containerd[1455]: time="2024-11-12T20:49:51.139715876Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 20:49:51.140323 containerd[1455]: time="2024-11-12T20:49:51.140178748Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:51.140637 containerd[1455]: time="2024-11-12T20:49:51.140543354Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:49:51.169731 systemd[1]: Started cri-containerd-6764b09acf6fb6e992abbfe37445ed3061e5874bc32e494d021209b1cdfec7a6.scope - libcontainer container 6764b09acf6fb6e992abbfe37445ed3061e5874bc32e494d021209b1cdfec7a6. Nov 12 20:49:51.220058 kubelet[1726]: E1112 20:49:51.219991 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:51.232101 containerd[1455]: time="2024-11-12T20:49:51.232019337Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-n9d2r,Uid:86e9f703-8168-4671-aa9b-d00ca1862b0c,Namespace:default,Attempt:0,} returns sandbox id \"6764b09acf6fb6e992abbfe37445ed3061e5874bc32e494d021209b1cdfec7a6\"" Nov 12 20:49:51.234040 containerd[1455]: time="2024-11-12T20:49:51.234008954Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 20:49:51.391615 kubelet[1726]: E1112 20:49:51.391003 1726 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Nov 12 20:49:52.220879 kubelet[1726]: E1112 20:49:52.220810 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:52.279987 systemd-networkd[1370]: cni0: Gained IPv6LL Nov 12 20:49:52.534644 systemd-networkd[1370]: veth65a48d55: Gained IPv6LL Nov 12 20:49:52.536914 systemd-networkd[1370]: flannel.1: Gained IPv6LL Nov 12 20:49:52.854636 systemd-resolved[1322]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.3. Nov 12 20:49:53.221284 kubelet[1726]: E1112 20:49:53.221211 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:54.028809 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1194477019.mount: Deactivated successfully. Nov 12 20:49:54.222419 kubelet[1726]: E1112 20:49:54.222355 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:55.222828 kubelet[1726]: E1112 20:49:55.222787 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:55.430365 containerd[1455]: time="2024-11-12T20:49:55.430285599Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:55.432173 containerd[1455]: time="2024-11-12T20:49:55.432105846Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71017596" Nov 12 20:49:55.432884 containerd[1455]: time="2024-11-12T20:49:55.432844073Z" level=info msg="ImageCreate event name:\"sha256:122ce9f0cbb4dfe43ffdb473f28715920b333fdb1a24276feb9164a36dc9e817\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:55.436865 containerd[1455]: time="2024-11-12T20:49:55.436813545Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:49:55.438133 containerd[1455]: time="2024-11-12T20:49:55.438089456Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:122ce9f0cbb4dfe43ffdb473f28715920b333fdb1a24276feb9164a36dc9e817\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"71017474\" in 4.203845603s" Nov 12 20:49:55.438603 containerd[1455]: time="2024-11-12T20:49:55.438570413Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:122ce9f0cbb4dfe43ffdb473f28715920b333fdb1a24276feb9164a36dc9e817\"" Nov 12 20:49:55.441759 containerd[1455]: time="2024-11-12T20:49:55.441598707Z" level=info msg="CreateContainer within sandbox \"6764b09acf6fb6e992abbfe37445ed3061e5874bc32e494d021209b1cdfec7a6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 12 20:49:55.464789 containerd[1455]: time="2024-11-12T20:49:55.464703079Z" level=info msg="CreateContainer within sandbox \"6764b09acf6fb6e992abbfe37445ed3061e5874bc32e494d021209b1cdfec7a6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"2c15d701fbe1a3ceadf0b5ba49f702cf2931a42eb472ab471f58dd8913fb7c8a\"" Nov 12 20:49:55.466025 containerd[1455]: time="2024-11-12T20:49:55.465934490Z" level=info msg="StartContainer for \"2c15d701fbe1a3ceadf0b5ba49f702cf2931a42eb472ab471f58dd8913fb7c8a\"" Nov 12 20:49:55.511842 systemd[1]: Started cri-containerd-2c15d701fbe1a3ceadf0b5ba49f702cf2931a42eb472ab471f58dd8913fb7c8a.scope - libcontainer container 2c15d701fbe1a3ceadf0b5ba49f702cf2931a42eb472ab471f58dd8913fb7c8a. Nov 12 20:49:55.548453 containerd[1455]: time="2024-11-12T20:49:55.548187972Z" level=info msg="StartContainer for \"2c15d701fbe1a3ceadf0b5ba49f702cf2931a42eb472ab471f58dd8913fb7c8a\" returns successfully" Nov 12 20:49:56.224707 kubelet[1726]: E1112 20:49:56.224562 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:57.225882 kubelet[1726]: E1112 20:49:57.225791 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:58.226383 kubelet[1726]: E1112 20:49:58.226316 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:59.211961 kubelet[1726]: E1112 20:49:59.211911 1726 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:49:59.227303 kubelet[1726]: E1112 20:49:59.227200 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:00.228052 kubelet[1726]: E1112 20:50:00.227921 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:01.228533 kubelet[1726]: E1112 20:50:01.228444 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:02.229485 kubelet[1726]: E1112 20:50:02.229394 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:03.231265 kubelet[1726]: E1112 20:50:03.231157 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:04.232132 kubelet[1726]: E1112 20:50:04.232046 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:05.233287 kubelet[1726]: E1112 20:50:05.233054 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:05.360653 kubelet[1726]: I1112 20:50:05.360525 1726 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-n9d2r" podStartSLOduration=11.154247559 podStartE2EDuration="15.360503403s" podCreationTimestamp="2024-11-12 20:49:50 +0000 UTC" firstStartedPulling="2024-11-12 20:49:51.233646261 +0000 UTC m=+12.297347533" lastFinishedPulling="2024-11-12 20:49:55.439902085 +0000 UTC m=+16.503603377" observedRunningTime="2024-11-12 20:49:56.420239601 +0000 UTC m=+17.483940895" watchObservedRunningTime="2024-11-12 20:50:05.360503403 +0000 UTC m=+26.424204702" Nov 12 20:50:05.382577 systemd[1]: Created slice kubepods-besteffort-pod9743dae0_c264_4aa8_842f_57bef0856172.slice - libcontainer container kubepods-besteffort-pod9743dae0_c264_4aa8_842f_57bef0856172.slice. Nov 12 20:50:05.428204 kubelet[1726]: I1112 20:50:05.428108 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9743dae0-c264-4aa8-842f-57bef0856172-data\") pod \"nfs-server-provisioner-0\" (UID: \"9743dae0-c264-4aa8-842f-57bef0856172\") " pod="default/nfs-server-provisioner-0" Nov 12 20:50:05.428204 kubelet[1726]: I1112 20:50:05.428171 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2kkt4\" (UniqueName: \"kubernetes.io/projected/9743dae0-c264-4aa8-842f-57bef0856172-kube-api-access-2kkt4\") pod \"nfs-server-provisioner-0\" (UID: \"9743dae0-c264-4aa8-842f-57bef0856172\") " pod="default/nfs-server-provisioner-0" Nov 12 20:50:05.687446 containerd[1455]: time="2024-11-12T20:50:05.687337228Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9743dae0-c264-4aa8-842f-57bef0856172,Namespace:default,Attempt:0,}" Nov 12 20:50:05.755709 kernel: cni0: port 2(vethefc20a17) entered blocking state Nov 12 20:50:05.755863 kernel: cni0: port 2(vethefc20a17) entered disabled state Nov 12 20:50:05.754033 systemd-networkd[1370]: vethefc20a17: Link UP Nov 12 20:50:05.759093 kernel: vethefc20a17: entered allmulticast mode Nov 12 20:50:05.762783 kernel: vethefc20a17: entered promiscuous mode Nov 12 20:50:05.784458 kernel: cni0: port 2(vethefc20a17) entered blocking state Nov 12 20:50:05.784599 kernel: cni0: port 2(vethefc20a17) entered forwarding state Nov 12 20:50:05.783469 systemd-networkd[1370]: vethefc20a17: Gained carrier Nov 12 20:50:05.795481 containerd[1455]: 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"} Nov 12 20:50:05.795481 containerd[1455]: delegateAdd: netconf sent to delegate plugin: Nov 12 20:50:05.830038 containerd[1455]: {"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="2024-11-12T20:50:05.829680505Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 20:50:05.830038 containerd[1455]: time="2024-11-12T20:50:05.829785316Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 20:50:05.830038 containerd[1455]: time="2024-11-12T20:50:05.829803194Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:50:05.830582 containerd[1455]: time="2024-11-12T20:50:05.829959080Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:50:05.873824 systemd[1]: Started cri-containerd-4c434816a17a3782e5b44fc4f5f636fa601c00715b222706c966b6e555940a54.scope - libcontainer container 4c434816a17a3782e5b44fc4f5f636fa601c00715b222706c966b6e555940a54. Nov 12 20:50:05.946437 containerd[1455]: time="2024-11-12T20:50:05.946161896Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9743dae0-c264-4aa8-842f-57bef0856172,Namespace:default,Attempt:0,} returns sandbox id \"4c434816a17a3782e5b44fc4f5f636fa601c00715b222706c966b6e555940a54\"" Nov 12 20:50:05.951653 containerd[1455]: time="2024-11-12T20:50:05.951601881Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 12 20:50:06.234199 kubelet[1726]: E1112 20:50:06.233990 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:07.234579 kubelet[1726]: E1112 20:50:07.234523 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:07.702652 systemd-networkd[1370]: vethefc20a17: Gained IPv6LL Nov 12 20:50:08.236475 kubelet[1726]: E1112 20:50:08.236429 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:08.547672 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3152808904.mount: Deactivated successfully. Nov 12 20:50:09.237616 kubelet[1726]: E1112 20:50:09.237541 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:10.237958 kubelet[1726]: E1112 20:50:10.237912 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:10.942782 containerd[1455]: time="2024-11-12T20:50:10.942697579Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:50:10.945237 containerd[1455]: time="2024-11-12T20:50:10.944759480Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Nov 12 20:50:10.945237 containerd[1455]: time="2024-11-12T20:50:10.945113394Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:50:10.948634 containerd[1455]: time="2024-11-12T20:50:10.948579065Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:50:10.950607 containerd[1455]: time="2024-11-12T20:50:10.950012773Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.998358611s" Nov 12 20:50:10.950607 containerd[1455]: time="2024-11-12T20:50:10.950056440Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Nov 12 20:50:10.953090 containerd[1455]: time="2024-11-12T20:50:10.953033610Z" level=info msg="CreateContainer within sandbox \"4c434816a17a3782e5b44fc4f5f636fa601c00715b222706c966b6e555940a54\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 12 20:50:10.972559 containerd[1455]: time="2024-11-12T20:50:10.972513072Z" level=info msg="CreateContainer within sandbox \"4c434816a17a3782e5b44fc4f5f636fa601c00715b222706c966b6e555940a54\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"5c27ea68418c9fed81648066cc2a9194510e1b01a843fdf651046dcbb8653ed5\"" Nov 12 20:50:10.973515 containerd[1455]: time="2024-11-12T20:50:10.973378861Z" level=info msg="StartContainer for \"5c27ea68418c9fed81648066cc2a9194510e1b01a843fdf651046dcbb8653ed5\"" Nov 12 20:50:11.020761 systemd[1]: Started cri-containerd-5c27ea68418c9fed81648066cc2a9194510e1b01a843fdf651046dcbb8653ed5.scope - libcontainer container 5c27ea68418c9fed81648066cc2a9194510e1b01a843fdf651046dcbb8653ed5. Nov 12 20:50:11.065537 containerd[1455]: time="2024-11-12T20:50:11.064330756Z" level=info msg="StartContainer for \"5c27ea68418c9fed81648066cc2a9194510e1b01a843fdf651046dcbb8653ed5\" returns successfully" Nov 12 20:50:11.239231 kubelet[1726]: E1112 20:50:11.239021 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:11.471201 kubelet[1726]: I1112 20:50:11.471134 1726 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.470393018 podStartE2EDuration="6.471114335s" podCreationTimestamp="2024-11-12 20:50:05 +0000 UTC" firstStartedPulling="2024-11-12 20:50:05.950559623 +0000 UTC m=+27.014260906" lastFinishedPulling="2024-11-12 20:50:10.951280939 +0000 UTC m=+32.014982223" observedRunningTime="2024-11-12 20:50:11.470651833 +0000 UTC m=+32.534353126" watchObservedRunningTime="2024-11-12 20:50:11.471114335 +0000 UTC m=+32.534815624" Nov 12 20:50:12.240356 kubelet[1726]: E1112 20:50:12.240272 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:13.240864 kubelet[1726]: E1112 20:50:13.240797 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:14.242028 kubelet[1726]: E1112 20:50:14.241954 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:15.242786 kubelet[1726]: E1112 20:50:15.242714 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:16.242992 kubelet[1726]: E1112 20:50:16.242898 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:17.243235 kubelet[1726]: E1112 20:50:17.243171 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:17.639679 update_engine[1444]: I20241112 20:50:17.639449 1444 update_attempter.cc:509] Updating boot flags... Nov 12 20:50:17.680558 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2740) Nov 12 20:50:17.750569 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (2739) Nov 12 20:50:18.243690 kubelet[1726]: E1112 20:50:18.243385 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:19.212041 kubelet[1726]: E1112 20:50:19.211967 1726 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:19.244676 kubelet[1726]: E1112 20:50:19.244582 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:20.245270 kubelet[1726]: E1112 20:50:20.245069 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:20.877037 systemd[1]: Created slice kubepods-besteffort-pod1c84c28e_ff47_449a_bde2_71b1bdc2517d.slice - libcontainer container kubepods-besteffort-pod1c84c28e_ff47_449a_bde2_71b1bdc2517d.slice. Nov 12 20:50:20.936780 kubelet[1726]: I1112 20:50:20.936703 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vw5j5\" (UniqueName: \"kubernetes.io/projected/1c84c28e-ff47-449a-bde2-71b1bdc2517d-kube-api-access-vw5j5\") pod \"test-pod-1\" (UID: \"1c84c28e-ff47-449a-bde2-71b1bdc2517d\") " pod="default/test-pod-1" Nov 12 20:50:20.936780 kubelet[1726]: I1112 20:50:20.936751 1726 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4d0f1968-dc80-4f0c-89df-6803d93e34c9\" (UniqueName: \"kubernetes.io/nfs/1c84c28e-ff47-449a-bde2-71b1bdc2517d-pvc-4d0f1968-dc80-4f0c-89df-6803d93e34c9\") pod \"test-pod-1\" (UID: \"1c84c28e-ff47-449a-bde2-71b1bdc2517d\") " pod="default/test-pod-1" Nov 12 20:50:21.075586 kernel: FS-Cache: Loaded Nov 12 20:50:21.158834 kernel: RPC: Registered named UNIX socket transport module. Nov 12 20:50:21.158996 kernel: RPC: Registered udp transport module. Nov 12 20:50:21.159060 kernel: RPC: Registered tcp transport module. Nov 12 20:50:21.159106 kernel: RPC: Registered tcp-with-tls transport module. Nov 12 20:50:21.159701 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 12 20:50:21.245491 kubelet[1726]: E1112 20:50:21.245308 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:21.501614 kernel: NFS: Registering the id_resolver key type Nov 12 20:50:21.501770 kernel: Key type id_resolver registered Nov 12 20:50:21.505598 kernel: Key type id_legacy registered Nov 12 20:50:21.544309 nfsidmap[2786]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-b-5184cdca4b' Nov 12 20:50:21.551221 nfsidmap[2787]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '2.0-b-5184cdca4b' Nov 12 20:50:21.783277 containerd[1455]: time="2024-11-12T20:50:21.782803970Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1c84c28e-ff47-449a-bde2-71b1bdc2517d,Namespace:default,Attempt:0,}" Nov 12 20:50:21.827001 systemd-networkd[1370]: veth9cc6e2a6: Link UP Nov 12 20:50:21.830661 kernel: cni0: port 3(veth9cc6e2a6) entered blocking state Nov 12 20:50:21.830795 kernel: cni0: port 3(veth9cc6e2a6) entered disabled state Nov 12 20:50:21.832464 kernel: veth9cc6e2a6: entered allmulticast mode Nov 12 20:50:21.833927 kernel: veth9cc6e2a6: entered promiscuous mode Nov 12 20:50:21.847645 kernel: cni0: port 3(veth9cc6e2a6) entered blocking state Nov 12 20:50:21.847865 kernel: cni0: port 3(veth9cc6e2a6) entered forwarding state Nov 12 20:50:21.848311 systemd-networkd[1370]: veth9cc6e2a6: Gained carrier Nov 12 20:50:21.853301 containerd[1455]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009c8e8), "name":"cbr0", "type":"bridge"} Nov 12 20:50:21.853301 containerd[1455]: delegateAdd: netconf sent to delegate plugin: Nov 12 20:50:21.882425 containerd[1455]: {"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="2024-11-12T20:50:21.882107377Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 20:50:21.882425 containerd[1455]: time="2024-11-12T20:50:21.882193540Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 20:50:21.882425 containerd[1455]: time="2024-11-12T20:50:21.882208134Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:50:21.883957 containerd[1455]: time="2024-11-12T20:50:21.883801759Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 20:50:21.911673 systemd[1]: Started cri-containerd-2de717b1184c0a20219699869b62afdb92d7547d2fc28ed13ef2264c791f6b6a.scope - libcontainer container 2de717b1184c0a20219699869b62afdb92d7547d2fc28ed13ef2264c791f6b6a. Nov 12 20:50:21.963559 containerd[1455]: time="2024-11-12T20:50:21.963480025Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1c84c28e-ff47-449a-bde2-71b1bdc2517d,Namespace:default,Attempt:0,} returns sandbox id \"2de717b1184c0a20219699869b62afdb92d7547d2fc28ed13ef2264c791f6b6a\"" Nov 12 20:50:21.966016 containerd[1455]: time="2024-11-12T20:50:21.965979559Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 20:50:22.245933 kubelet[1726]: E1112 20:50:22.245862 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:22.333122 containerd[1455]: time="2024-11-12T20:50:22.332891742Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 20:50:22.333817 containerd[1455]: time="2024-11-12T20:50:22.333759500Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 12 20:50:22.337939 containerd[1455]: time="2024-11-12T20:50:22.337844947Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:122ce9f0cbb4dfe43ffdb473f28715920b333fdb1a24276feb9164a36dc9e817\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"71017474\" in 371.807186ms" Nov 12 20:50:22.337939 containerd[1455]: time="2024-11-12T20:50:22.337915019Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:122ce9f0cbb4dfe43ffdb473f28715920b333fdb1a24276feb9164a36dc9e817\"" Nov 12 20:50:22.341001 containerd[1455]: time="2024-11-12T20:50:22.340723195Z" level=info msg="CreateContainer within sandbox \"2de717b1184c0a20219699869b62afdb92d7547d2fc28ed13ef2264c791f6b6a\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 12 20:50:22.367230 containerd[1455]: time="2024-11-12T20:50:22.367111248Z" level=info msg="CreateContainer within sandbox \"2de717b1184c0a20219699869b62afdb92d7547d2fc28ed13ef2264c791f6b6a\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f\"" Nov 12 20:50:22.368980 containerd[1455]: time="2024-11-12T20:50:22.368584621Z" level=info msg="StartContainer for \"9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f\"" Nov 12 20:50:22.427643 systemd[1]: Started cri-containerd-9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f.scope - libcontainer container 9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f. Nov 12 20:50:22.465674 containerd[1455]: time="2024-11-12T20:50:22.465475428Z" level=info msg="StartContainer for \"9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f\" returns successfully" Nov 12 20:50:22.494032 kubelet[1726]: I1112 20:50:22.493908 1726 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.120277068 podStartE2EDuration="17.493879198s" podCreationTimestamp="2024-11-12 20:50:05 +0000 UTC" firstStartedPulling="2024-11-12 20:50:21.965346192 +0000 UTC m=+43.029047464" lastFinishedPulling="2024-11-12 20:50:22.338948316 +0000 UTC m=+43.402649594" observedRunningTime="2024-11-12 20:50:22.49360574 +0000 UTC m=+43.557307032" watchObservedRunningTime="2024-11-12 20:50:22.493879198 +0000 UTC m=+43.557580549" Nov 12 20:50:22.934734 systemd-networkd[1370]: veth9cc6e2a6: Gained IPv6LL Nov 12 20:50:23.052978 systemd[1]: run-containerd-runc-k8s.io-9f2552ca9062ce77ff83646c7b6c1b5ec7145f876209fabb8eaebb97e385a81f-runc.xXizwE.mount: Deactivated successfully. Nov 12 20:50:23.247395 kubelet[1726]: E1112 20:50:23.247007 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:24.248311 kubelet[1726]: E1112 20:50:24.248232 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:25.248735 kubelet[1726]: E1112 20:50:25.248641 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:26.249548 kubelet[1726]: E1112 20:50:26.249498 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:27.250909 kubelet[1726]: E1112 20:50:27.250841 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:28.251436 kubelet[1726]: E1112 20:50:28.251325 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 20:50:29.252091 kubelet[1726]: E1112 20:50:29.252020 1726 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"