Oct 9 01:04:43.998566 kernel: Linux version 6.6.54-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 Oct 8 23:33:43 -00 2024 Oct 9 01:04:43.998597 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=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:04:43.998612 kernel: BIOS-provided physical RAM map: Oct 9 01:04:43.998620 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Oct 9 01:04:43.998627 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Oct 9 01:04:43.998634 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Oct 9 01:04:43.998642 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffd7fff] usable Oct 9 01:04:43.998648 kernel: BIOS-e820: [mem 0x000000007ffd8000-0x000000007fffffff] reserved Oct 9 01:04:43.998655 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Oct 9 01:04:43.998664 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Oct 9 01:04:43.998671 kernel: NX (Execute Disable) protection: active Oct 9 01:04:43.998677 kernel: APIC: Static calls initialized Oct 9 01:04:43.998684 kernel: SMBIOS 2.8 present. Oct 9 01:04:43.998690 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Oct 9 01:04:43.998698 kernel: Hypervisor detected: KVM Oct 9 01:04:43.998708 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 9 01:04:43.998737 kernel: kvm-clock: using sched offset of 3541954036 cycles Oct 9 01:04:43.998746 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 9 01:04:43.998753 kernel: tsc: Detected 1995.312 MHz processor Oct 9 01:04:43.998760 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 9 01:04:43.998767 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 9 01:04:43.998774 kernel: last_pfn = 0x7ffd8 max_arch_pfn = 0x400000000 Oct 9 01:04:43.998782 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Oct 9 01:04:43.998789 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 9 01:04:43.998799 kernel: ACPI: Early table checksum verification disabled Oct 9 01:04:43.998806 kernel: ACPI: RSDP 0x00000000000F5A50 000014 (v00 BOCHS ) Oct 9 01:04:43.998813 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998820 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998827 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998834 kernel: ACPI: FACS 0x000000007FFE0000 000040 Oct 9 01:04:43.998841 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998848 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998855 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998864 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:04:43.998871 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Oct 9 01:04:43.998878 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Oct 9 01:04:43.998885 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Oct 9 01:04:43.998892 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Oct 9 01:04:43.998899 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Oct 9 01:04:43.998906 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Oct 9 01:04:43.998920 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Oct 9 01:04:43.998929 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 9 01:04:43.998939 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 9 01:04:43.998946 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Oct 9 01:04:43.998953 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Oct 9 01:04:43.998961 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffd7fff] -> [mem 0x00000000-0x7ffd7fff] Oct 9 01:04:43.998968 kernel: NODE_DATA(0) allocated [mem 0x7ffd2000-0x7ffd7fff] Oct 9 01:04:43.998979 kernel: Zone ranges: Oct 9 01:04:43.998986 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 9 01:04:43.998993 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffd7fff] Oct 9 01:04:43.999000 kernel: Normal empty Oct 9 01:04:43.999008 kernel: Movable zone start for each node Oct 9 01:04:43.999015 kernel: Early memory node ranges Oct 9 01:04:43.999022 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Oct 9 01:04:43.999030 kernel: node 0: [mem 0x0000000000100000-0x000000007ffd7fff] Oct 9 01:04:43.999682 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffd7fff] Oct 9 01:04:43.999702 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 9 01:04:43.999710 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Oct 9 01:04:43.999717 kernel: On node 0, zone DMA32: 40 pages in unavailable ranges Oct 9 01:04:43.999725 kernel: ACPI: PM-Timer IO Port: 0x608 Oct 9 01:04:43.999733 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 9 01:04:43.999741 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Oct 9 01:04:43.999749 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 9 01:04:43.999756 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 9 01:04:43.999763 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 9 01:04:43.999773 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 9 01:04:43.999781 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 9 01:04:43.999789 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 9 01:04:43.999796 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Oct 9 01:04:43.999804 kernel: TSC deadline timer available Oct 9 01:04:43.999811 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Oct 9 01:04:43.999819 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 9 01:04:43.999826 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Oct 9 01:04:43.999834 kernel: Booting paravirtualized kernel on KVM Oct 9 01:04:43.999845 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 9 01:04:43.999853 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Oct 9 01:04:43.999860 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Oct 9 01:04:43.999868 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Oct 9 01:04:43.999876 kernel: pcpu-alloc: [0] 0 1 Oct 9 01:04:43.999883 kernel: kvm-guest: PV spinlocks disabled, no host support Oct 9 01:04:43.999893 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=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:04:43.999901 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 9 01:04:43.999911 kernel: random: crng init done Oct 9 01:04:43.999919 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 9 01:04:43.999926 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 9 01:04:43.999934 kernel: Fallback order for Node 0: 0 Oct 9 01:04:43.999941 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515800 Oct 9 01:04:43.999949 kernel: Policy zone: DMA32 Oct 9 01:04:43.999956 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 9 01:04:43.999964 kernel: Memory: 1971192K/2096600K available (12288K kernel code, 2305K rwdata, 22728K rodata, 42872K init, 2316K bss, 125148K reserved, 0K cma-reserved) Oct 9 01:04:43.999972 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 9 01:04:43.999982 kernel: Kernel/User page tables isolation: enabled Oct 9 01:04:43.999989 kernel: ftrace: allocating 37786 entries in 148 pages Oct 9 01:04:43.999997 kernel: ftrace: allocated 148 pages with 3 groups Oct 9 01:04:44.000004 kernel: Dynamic Preempt: voluntary Oct 9 01:04:44.000012 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 9 01:04:44.000021 kernel: rcu: RCU event tracing is enabled. Oct 9 01:04:44.000028 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 9 01:04:44.000069 kernel: Trampoline variant of Tasks RCU enabled. Oct 9 01:04:44.000081 kernel: Rude variant of Tasks RCU enabled. Oct 9 01:04:44.000098 kernel: Tracing variant of Tasks RCU enabled. Oct 9 01:04:44.000111 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 9 01:04:44.000122 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 9 01:04:44.000132 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Oct 9 01:04:44.000143 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 9 01:04:44.000155 kernel: Console: colour VGA+ 80x25 Oct 9 01:04:44.000165 kernel: printk: console [tty0] enabled Oct 9 01:04:44.000176 kernel: printk: console [ttyS0] enabled Oct 9 01:04:44.000187 kernel: ACPI: Core revision 20230628 Oct 9 01:04:44.000200 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Oct 9 01:04:44.000215 kernel: APIC: Switch to symmetric I/O mode setup Oct 9 01:04:44.000227 kernel: x2apic enabled Oct 9 01:04:44.000238 kernel: APIC: Switched APIC routing to: physical x2apic Oct 9 01:04:44.000246 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 9 01:04:44.000254 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3985c314e25, max_idle_ns: 881590612270 ns Oct 9 01:04:44.000261 kernel: Calibrating delay loop (skipped) preset value.. 3990.62 BogoMIPS (lpj=1995312) Oct 9 01:04:44.000268 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Oct 9 01:04:44.000276 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Oct 9 01:04:44.000295 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 9 01:04:44.000304 kernel: Spectre V2 : Mitigation: Retpolines Oct 9 01:04:44.000311 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Oct 9 01:04:44.000339 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Oct 9 01:04:44.000347 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Oct 9 01:04:44.000355 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Oct 9 01:04:44.000363 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Oct 9 01:04:44.000371 kernel: MDS: Mitigation: Clear CPU buffers Oct 9 01:04:44.000380 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 9 01:04:44.000391 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 9 01:04:44.000399 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 9 01:04:44.000407 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 9 01:04:44.000415 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 9 01:04:44.000423 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 9 01:04:44.000432 kernel: Freeing SMP alternatives memory: 32K Oct 9 01:04:44.000440 kernel: pid_max: default: 32768 minimum: 301 Oct 9 01:04:44.000449 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 9 01:04:44.000459 kernel: landlock: Up and running. Oct 9 01:04:44.000467 kernel: SELinux: Initializing. Oct 9 01:04:44.000475 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:04:44.000483 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:04:44.000492 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Oct 9 01:04:44.000500 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:04:44.000508 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:04:44.000516 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:04:44.000524 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 9 01:04:44.000534 kernel: signal: max sigframe size: 1776 Oct 9 01:04:44.000543 kernel: rcu: Hierarchical SRCU implementation. Oct 9 01:04:44.000551 kernel: rcu: Max phase no-delay instances is 400. Oct 9 01:04:44.000559 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 9 01:04:44.000567 kernel: smp: Bringing up secondary CPUs ... Oct 9 01:04:44.000575 kernel: smpboot: x86: Booting SMP configuration: Oct 9 01:04:44.000583 kernel: .... node #0, CPUs: #1 Oct 9 01:04:44.000592 kernel: smp: Brought up 1 node, 2 CPUs Oct 9 01:04:44.000600 kernel: smpboot: Max logical packages: 1 Oct 9 01:04:44.000611 kernel: smpboot: Total of 2 processors activated (7981.24 BogoMIPS) Oct 9 01:04:44.000619 kernel: devtmpfs: initialized Oct 9 01:04:44.000628 kernel: x86/mm: Memory block size: 128MB Oct 9 01:04:44.000636 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 9 01:04:44.000644 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 9 01:04:44.000652 kernel: pinctrl core: initialized pinctrl subsystem Oct 9 01:04:44.000660 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 9 01:04:44.000668 kernel: audit: initializing netlink subsys (disabled) Oct 9 01:04:44.000676 kernel: audit: type=2000 audit(1728435882.871:1): state=initialized audit_enabled=0 res=1 Oct 9 01:04:44.000687 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 9 01:04:44.000695 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 9 01:04:44.000702 kernel: cpuidle: using governor menu Oct 9 01:04:44.000710 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 9 01:04:44.000718 kernel: dca service started, version 1.12.1 Oct 9 01:04:44.000727 kernel: PCI: Using configuration type 1 for base access Oct 9 01:04:44.000735 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 9 01:04:44.000743 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 9 01:04:44.000751 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 9 01:04:44.000763 kernel: ACPI: Added _OSI(Module Device) Oct 9 01:04:44.000771 kernel: ACPI: Added _OSI(Processor Device) Oct 9 01:04:44.000778 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 9 01:04:44.000786 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 9 01:04:44.000795 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 9 01:04:44.000803 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 9 01:04:44.000811 kernel: ACPI: Interpreter enabled Oct 9 01:04:44.000819 kernel: ACPI: PM: (supports S0 S5) Oct 9 01:04:44.000827 kernel: ACPI: Using IOAPIC for interrupt routing Oct 9 01:04:44.000838 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 9 01:04:44.000846 kernel: PCI: Using E820 reservations for host bridge windows Oct 9 01:04:44.000854 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Oct 9 01:04:44.000862 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 9 01:04:44.001159 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Oct 9 01:04:44.001275 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Oct 9 01:04:44.001371 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Oct 9 01:04:44.001387 kernel: acpiphp: Slot [3] registered Oct 9 01:04:44.001395 kernel: acpiphp: Slot [4] registered Oct 9 01:04:44.001403 kernel: acpiphp: Slot [5] registered Oct 9 01:04:44.001411 kernel: acpiphp: Slot [6] registered Oct 9 01:04:44.001420 kernel: acpiphp: Slot [7] registered Oct 9 01:04:44.001428 kernel: acpiphp: Slot [8] registered Oct 9 01:04:44.001436 kernel: acpiphp: Slot [9] registered Oct 9 01:04:44.001444 kernel: acpiphp: Slot [10] registered Oct 9 01:04:44.001452 kernel: acpiphp: Slot [11] registered Oct 9 01:04:44.001463 kernel: acpiphp: Slot [12] registered Oct 9 01:04:44.001471 kernel: acpiphp: Slot [13] registered Oct 9 01:04:44.001478 kernel: acpiphp: Slot [14] registered Oct 9 01:04:44.001487 kernel: acpiphp: Slot [15] registered Oct 9 01:04:44.001494 kernel: acpiphp: Slot [16] registered Oct 9 01:04:44.001502 kernel: acpiphp: Slot [17] registered Oct 9 01:04:44.001510 kernel: acpiphp: Slot [18] registered Oct 9 01:04:44.001518 kernel: acpiphp: Slot [19] registered Oct 9 01:04:44.001526 kernel: acpiphp: Slot [20] registered Oct 9 01:04:44.001534 kernel: acpiphp: Slot [21] registered Oct 9 01:04:44.001545 kernel: acpiphp: Slot [22] registered Oct 9 01:04:44.001553 kernel: acpiphp: Slot [23] registered Oct 9 01:04:44.001561 kernel: acpiphp: Slot [24] registered Oct 9 01:04:44.001569 kernel: acpiphp: Slot [25] registered Oct 9 01:04:44.001577 kernel: acpiphp: Slot [26] registered Oct 9 01:04:44.001585 kernel: acpiphp: Slot [27] registered Oct 9 01:04:44.001593 kernel: acpiphp: Slot [28] registered Oct 9 01:04:44.001601 kernel: acpiphp: Slot [29] registered Oct 9 01:04:44.001609 kernel: acpiphp: Slot [30] registered Oct 9 01:04:44.001620 kernel: acpiphp: Slot [31] registered Oct 9 01:04:44.001628 kernel: PCI host bridge to bus 0000:00 Oct 9 01:04:44.001747 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 9 01:04:44.001870 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 9 01:04:44.001960 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 9 01:04:44.002080 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Oct 9 01:04:44.002167 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Oct 9 01:04:44.002293 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 9 01:04:44.002426 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Oct 9 01:04:44.002562 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Oct 9 01:04:44.002675 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Oct 9 01:04:44.002782 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] Oct 9 01:04:44.002894 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Oct 9 01:04:44.002991 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Oct 9 01:04:44.003133 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Oct 9 01:04:44.003227 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Oct 9 01:04:44.003332 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Oct 9 01:04:44.003425 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] Oct 9 01:04:44.003527 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Oct 9 01:04:44.003622 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Oct 9 01:04:44.003755 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Oct 9 01:04:44.003876 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Oct 9 01:04:44.003975 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Oct 9 01:04:44.004101 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Oct 9 01:04:44.004196 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] Oct 9 01:04:44.004290 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Oct 9 01:04:44.004383 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 9 01:04:44.004545 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Oct 9 01:04:44.004645 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] Oct 9 01:04:44.004740 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] Oct 9 01:04:44.004834 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Oct 9 01:04:44.004939 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Oct 9 01:04:44.005033 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] Oct 9 01:04:44.008324 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] Oct 9 01:04:44.008431 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Oct 9 01:04:44.008542 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Oct 9 01:04:44.008638 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] Oct 9 01:04:44.008733 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] Oct 9 01:04:44.008831 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Oct 9 01:04:44.008967 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 Oct 9 01:04:44.010193 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] Oct 9 01:04:44.010389 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] Oct 9 01:04:44.010488 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Oct 9 01:04:44.010610 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 Oct 9 01:04:44.010708 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] Oct 9 01:04:44.010805 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] Oct 9 01:04:44.010913 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] Oct 9 01:04:44.011023 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 Oct 9 01:04:44.012179 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] Oct 9 01:04:44.012387 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] Oct 9 01:04:44.012414 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 9 01:04:44.012430 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 9 01:04:44.012444 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 9 01:04:44.012453 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 9 01:04:44.012468 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Oct 9 01:04:44.012477 kernel: iommu: Default domain type: Translated Oct 9 01:04:44.012485 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 9 01:04:44.012494 kernel: PCI: Using ACPI for IRQ routing Oct 9 01:04:44.012502 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 9 01:04:44.012511 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Oct 9 01:04:44.012519 kernel: e820: reserve RAM buffer [mem 0x7ffd8000-0x7fffffff] Oct 9 01:04:44.012637 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Oct 9 01:04:44.012734 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Oct 9 01:04:44.012857 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 9 01:04:44.012869 kernel: vgaarb: loaded Oct 9 01:04:44.012878 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Oct 9 01:04:44.012887 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Oct 9 01:04:44.012895 kernel: clocksource: Switched to clocksource kvm-clock Oct 9 01:04:44.012904 kernel: VFS: Disk quotas dquot_6.6.0 Oct 9 01:04:44.012913 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 9 01:04:44.012921 kernel: pnp: PnP ACPI init Oct 9 01:04:44.012930 kernel: pnp: PnP ACPI: found 4 devices Oct 9 01:04:44.012942 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 9 01:04:44.012950 kernel: NET: Registered PF_INET protocol family Oct 9 01:04:44.012959 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 9 01:04:44.012967 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Oct 9 01:04:44.012976 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 9 01:04:44.012985 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 9 01:04:44.012993 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Oct 9 01:04:44.013002 kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 9 01:04:44.013010 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:04:44.013022 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:04:44.013030 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 9 01:04:44.015081 kernel: NET: Registered PF_XDP protocol family Oct 9 01:04:44.015234 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 9 01:04:44.015323 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 9 01:04:44.015407 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 9 01:04:44.015492 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Oct 9 01:04:44.015575 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Oct 9 01:04:44.015694 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Oct 9 01:04:44.015820 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Oct 9 01:04:44.015834 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Oct 9 01:04:44.015935 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 33617 usecs Oct 9 01:04:44.015946 kernel: PCI: CLS 0 bytes, default 64 Oct 9 01:04:44.015955 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 9 01:04:44.016013 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3985c314e25, max_idle_ns: 881590612270 ns Oct 9 01:04:44.016021 kernel: Initialise system trusted keyrings Oct 9 01:04:44.016054 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Oct 9 01:04:44.016068 kernel: Key type asymmetric registered Oct 9 01:04:44.016081 kernel: Asymmetric key parser 'x509' registered Oct 9 01:04:44.016094 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 9 01:04:44.016109 kernel: io scheduler mq-deadline registered Oct 9 01:04:44.016123 kernel: io scheduler kyber registered Oct 9 01:04:44.016138 kernel: io scheduler bfq registered Oct 9 01:04:44.016164 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 9 01:04:44.016173 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Oct 9 01:04:44.016182 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Oct 9 01:04:44.016194 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Oct 9 01:04:44.016203 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 9 01:04:44.016211 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 9 01:04:44.016220 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 9 01:04:44.016228 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 9 01:04:44.016237 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 9 01:04:44.016246 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 9 01:04:44.016370 kernel: rtc_cmos 00:03: RTC can wake from S4 Oct 9 01:04:44.016467 kernel: rtc_cmos 00:03: registered as rtc0 Oct 9 01:04:44.016555 kernel: rtc_cmos 00:03: setting system clock to 2024-10-09T01:04:43 UTC (1728435883) Oct 9 01:04:44.016641 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Oct 9 01:04:44.016652 kernel: intel_pstate: CPU model not supported Oct 9 01:04:44.016660 kernel: NET: Registered PF_INET6 protocol family Oct 9 01:04:44.016669 kernel: Segment Routing with IPv6 Oct 9 01:04:44.016677 kernel: In-situ OAM (IOAM) with IPv6 Oct 9 01:04:44.016686 kernel: NET: Registered PF_PACKET protocol family Oct 9 01:04:44.016703 kernel: Key type dns_resolver registered Oct 9 01:04:44.016717 kernel: IPI shorthand broadcast: enabled Oct 9 01:04:44.016726 kernel: sched_clock: Marking stable (1194028162, 137614114)->(1371856162, -40213886) Oct 9 01:04:44.016734 kernel: registered taskstats version 1 Oct 9 01:04:44.016742 kernel: Loading compiled-in X.509 certificates Oct 9 01:04:44.016755 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.54-flatcar: 03ae66f5ce294ce3ab718ee0d7c4a4a6e8c5aae6' Oct 9 01:04:44.016768 kernel: Key type .fscrypt registered Oct 9 01:04:44.016776 kernel: Key type fscrypt-provisioning registered Oct 9 01:04:44.016784 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 9 01:04:44.016796 kernel: ima: Allocated hash algorithm: sha1 Oct 9 01:04:44.016804 kernel: ima: No architecture policies found Oct 9 01:04:44.016813 kernel: clk: Disabling unused clocks Oct 9 01:04:44.016821 kernel: Freeing unused kernel image (initmem) memory: 42872K Oct 9 01:04:44.016830 kernel: Write protecting the kernel read-only data: 36864k Oct 9 01:04:44.016861 kernel: Freeing unused kernel image (rodata/data gap) memory: 1848K Oct 9 01:04:44.016872 kernel: Run /init as init process Oct 9 01:04:44.016881 kernel: with arguments: Oct 9 01:04:44.016895 kernel: /init Oct 9 01:04:44.016915 kernel: with environment: Oct 9 01:04:44.016931 kernel: HOME=/ Oct 9 01:04:44.016946 kernel: TERM=linux Oct 9 01:04:44.016973 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 9 01:04:44.016990 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) Oct 9 01:04:44.017003 systemd[1]: Detected virtualization kvm. Oct 9 01:04:44.017017 systemd[1]: Detected architecture x86-64. Oct 9 01:04:44.017026 systemd[1]: Running in initrd. Oct 9 01:04:44.018761 systemd[1]: No hostname configured, using default hostname. Oct 9 01:04:44.018776 systemd[1]: Hostname set to . Oct 9 01:04:44.018786 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:04:44.018795 systemd[1]: Queued start job for default target initrd.target. Oct 9 01:04:44.018805 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:04:44.018814 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:04:44.018825 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 9 01:04:44.018834 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:04:44.018849 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 9 01:04:44.018859 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 9 01:04:44.018869 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 9 01:04:44.018879 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 9 01:04:44.018892 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:04:44.018902 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:04:44.018923 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:04:44.018943 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:04:44.018969 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:04:44.018983 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:04:44.018993 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:04:44.019002 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:04:44.019014 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 9 01:04:44.019023 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 9 01:04:44.019032 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:04:44.019124 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:04:44.019134 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:04:44.019142 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:04:44.019151 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 9 01:04:44.019160 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:04:44.019172 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 9 01:04:44.019181 systemd[1]: Starting systemd-fsck-usr.service... Oct 9 01:04:44.019190 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:04:44.019199 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:04:44.019209 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:44.019218 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 9 01:04:44.019260 systemd-journald[183]: Collecting audit messages is disabled. Oct 9 01:04:44.019291 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:04:44.019305 systemd[1]: Finished systemd-fsck-usr.service. Oct 9 01:04:44.019317 systemd-journald[183]: Journal started Oct 9 01:04:44.019344 systemd-journald[183]: Runtime Journal (/run/log/journal/48f90e3de5e44ecabcf4516b4f96e676) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:04:44.022549 systemd-modules-load[184]: Inserted module 'overlay' Oct 9 01:04:44.029077 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:04:44.057084 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 9 01:04:44.059531 systemd-modules-load[184]: Inserted module 'br_netfilter' Oct 9 01:04:44.072718 kernel: Bridge firewalling registered Oct 9 01:04:44.072752 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:04:44.078951 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:04:44.085153 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:44.086430 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:04:44.102606 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:04:44.106294 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:04:44.109723 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:04:44.116548 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:04:44.134825 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:04:44.139468 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:04:44.146424 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 9 01:04:44.147449 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:04:44.151545 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:04:44.161334 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:04:44.164609 dracut-cmdline[216]: dracut-dracut-053 Oct 9 01:04:44.170584 dracut-cmdline[216]: 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=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:04:44.198374 systemd-resolved[221]: Positive Trust Anchors: Oct 9 01:04:44.198392 systemd-resolved[221]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:04:44.198426 systemd-resolved[221]: 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 Oct 9 01:04:44.201377 systemd-resolved[221]: Defaulting to hostname 'linux'. Oct 9 01:04:44.202790 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:04:44.206576 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:04:44.280100 kernel: SCSI subsystem initialized Oct 9 01:04:44.291102 kernel: Loading iSCSI transport class v2.0-870. Oct 9 01:04:44.305086 kernel: iscsi: registered transport (tcp) Oct 9 01:04:44.331221 kernel: iscsi: registered transport (qla4xxx) Oct 9 01:04:44.331411 kernel: QLogic iSCSI HBA Driver Oct 9 01:04:44.388799 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 9 01:04:44.407455 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 9 01:04:44.439506 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 9 01:04:44.439608 kernel: device-mapper: uevent: version 1.0.3 Oct 9 01:04:44.439623 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 9 01:04:44.511152 kernel: raid6: avx2x4 gen() 17831 MB/s Oct 9 01:04:44.511277 kernel: raid6: avx2x2 gen() 25359 MB/s Oct 9 01:04:44.525271 kernel: raid6: avx2x1 gen() 15735 MB/s Oct 9 01:04:44.525387 kernel: raid6: using algorithm avx2x2 gen() 25359 MB/s Oct 9 01:04:44.544303 kernel: raid6: .... xor() 14599 MB/s, rmw enabled Oct 9 01:04:44.544457 kernel: raid6: using avx2x2 recovery algorithm Oct 9 01:04:44.577102 kernel: xor: automatically using best checksumming function avx Oct 9 01:04:44.784107 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 9 01:04:44.799782 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:04:44.807406 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:04:44.832448 systemd-udevd[403]: Using default interface naming scheme 'v255'. Oct 9 01:04:44.837522 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:04:44.847326 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 9 01:04:44.872139 dracut-pre-trigger[414]: rd.md=0: removing MD RAID activation Oct 9 01:04:44.912935 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:04:44.919510 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:04:44.994508 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:04:45.000334 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 9 01:04:45.026446 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 9 01:04:45.028729 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:04:45.031853 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:04:45.033980 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:04:45.039282 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 9 01:04:45.071834 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:04:45.082123 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Oct 9 01:04:45.108311 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Oct 9 01:04:45.130346 kernel: ACPI: bus type USB registered Oct 9 01:04:45.132401 kernel: usbcore: registered new interface driver usbfs Oct 9 01:04:45.132461 kernel: usbcore: registered new interface driver hub Oct 9 01:04:45.134075 kernel: libata version 3.00 loaded. Oct 9 01:04:45.137521 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 9 01:04:45.137591 kernel: GPT:9289727 != 125829119 Oct 9 01:04:45.137625 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 9 01:04:45.138693 kernel: GPT:9289727 != 125829119 Oct 9 01:04:45.139428 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 9 01:04:45.140429 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:04:45.144328 kernel: ata_piix 0000:00:01.1: version 2.13 Oct 9 01:04:45.144605 kernel: usbcore: registered new device driver usb Oct 9 01:04:45.145232 kernel: scsi host0: ata_piix Oct 9 01:04:45.151067 kernel: scsi host1: ata_piix Oct 9 01:04:45.153074 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Oct 9 01:04:45.155404 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 Oct 9 01:04:45.155469 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 Oct 9 01:04:45.158089 kernel: cryptd: max_cpu_qlen set to 1000 Oct 9 01:04:45.162064 kernel: virtio_blk virtio5: [vdb] 920 512-byte logical blocks (471 kB/460 KiB) Oct 9 01:04:45.162431 kernel: scsi host2: Virtio SCSI HBA Oct 9 01:04:45.188197 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:04:45.188338 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:04:45.189701 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:04:45.190724 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:04:45.190969 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:45.192713 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:45.203501 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:45.261321 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:45.268393 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:04:45.290537 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:04:45.344843 kernel: AVX2 version of gcm_enc/dec engaged. Oct 9 01:04:45.354064 kernel: BTRFS: device fsid 6ed52ce5-b2f8-4d16-8889-677a209bc377 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (462) Oct 9 01:04:45.358077 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (450) Oct 9 01:04:45.363176 kernel: AES CTR mode by8 optimization enabled Oct 9 01:04:45.366337 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 9 01:04:45.392573 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:04:45.402758 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 9 01:04:45.403394 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 9 01:04:45.410298 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 9 01:04:45.416918 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Oct 9 01:04:45.417168 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Oct 9 01:04:45.417290 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Oct 9 01:04:45.417402 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Oct 9 01:04:45.417509 kernel: hub 1-0:1.0: USB hub found Oct 9 01:04:45.417719 kernel: hub 1-0:1.0: 2 ports detected Oct 9 01:04:45.423921 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 9 01:04:45.439010 disk-uuid[552]: Primary Header is updated. Oct 9 01:04:45.439010 disk-uuid[552]: Secondary Entries is updated. Oct 9 01:04:45.439010 disk-uuid[552]: Secondary Header is updated. Oct 9 01:04:45.446099 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:04:45.451102 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:04:46.456074 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:04:46.457539 disk-uuid[553]: The operation has completed successfully. Oct 9 01:04:46.501627 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 9 01:04:46.503092 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 9 01:04:46.514601 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 9 01:04:46.520552 sh[564]: Success Oct 9 01:04:46.538366 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 9 01:04:46.622110 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 9 01:04:46.625205 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 9 01:04:46.626302 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 9 01:04:46.667515 kernel: BTRFS info (device dm-0): first mount of filesystem 6ed52ce5-b2f8-4d16-8889-677a209bc377 Oct 9 01:04:46.667644 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:04:46.667659 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 9 01:04:46.667671 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 9 01:04:46.667683 kernel: BTRFS info (device dm-0): using free space tree Oct 9 01:04:46.677946 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 9 01:04:46.680426 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 9 01:04:46.689327 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 9 01:04:46.693461 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 9 01:04:46.708149 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:04:46.708229 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:04:46.708242 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:04:46.712123 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:04:46.728642 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:04:46.728344 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 9 01:04:46.737409 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 9 01:04:46.747384 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 9 01:04:46.855872 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:04:46.864356 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:04:46.900203 systemd-networkd[749]: lo: Link UP Oct 9 01:04:46.900214 systemd-networkd[749]: lo: Gained carrier Oct 9 01:04:46.900752 ignition[666]: Ignition 2.19.0 Oct 9 01:04:46.900760 ignition[666]: Stage: fetch-offline Oct 9 01:04:46.900808 ignition[666]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:46.904340 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:04:46.900817 ignition[666]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:46.900935 ignition[666]: parsed url from cmdline: "" Oct 9 01:04:46.900940 ignition[666]: no config URL provided Oct 9 01:04:46.900945 ignition[666]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:04:46.907149 systemd-networkd[749]: Enumeration completed Oct 9 01:04:46.900953 ignition[666]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:04:46.907878 systemd-networkd[749]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Oct 9 01:04:46.900959 ignition[666]: failed to fetch config: resource requires networking Oct 9 01:04:46.907883 systemd-networkd[749]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Oct 9 01:04:46.901178 ignition[666]: Ignition finished successfully Oct 9 01:04:46.908520 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:04:46.909960 systemd[1]: Reached target network.target - Network. Oct 9 01:04:46.911489 systemd-networkd[749]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:04:46.911494 systemd-networkd[749]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 01:04:46.912360 systemd-networkd[749]: eth0: Link UP Oct 9 01:04:46.912366 systemd-networkd[749]: eth0: Gained carrier Oct 9 01:04:46.912380 systemd-networkd[749]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Oct 9 01:04:46.915722 systemd-networkd[749]: eth1: Link UP Oct 9 01:04:46.915728 systemd-networkd[749]: eth1: Gained carrier Oct 9 01:04:46.915746 systemd-networkd[749]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:04:46.923460 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 9 01:04:46.929135 systemd-networkd[749]: eth1: DHCPv4 address 10.124.0.9/20 acquired from 169.254.169.253 Oct 9 01:04:46.932184 systemd-networkd[749]: eth0: DHCPv4 address 137.184.14.81/20, gateway 137.184.0.1 acquired from 169.254.169.253 Oct 9 01:04:46.951533 ignition[756]: Ignition 2.19.0 Oct 9 01:04:46.951552 ignition[756]: Stage: fetch Oct 9 01:04:46.951857 ignition[756]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:46.951875 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:46.952066 ignition[756]: parsed url from cmdline: "" Oct 9 01:04:46.952073 ignition[756]: no config URL provided Oct 9 01:04:46.952082 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:04:46.952096 ignition[756]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:04:46.952132 ignition[756]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Oct 9 01:04:46.967509 ignition[756]: GET result: OK Oct 9 01:04:46.967774 ignition[756]: parsing config with SHA512: 979f5026528e464f043a4b2176055ab7e8a548e65fa25c3cd70897b1a0de9c510606f49d484b95f228ed1bed600c751e940b548bc2d652e7fe24bc0bdc78a399 Oct 9 01:04:46.970981 unknown[756]: fetched base config from "system" Oct 9 01:04:46.970998 unknown[756]: fetched base config from "system" Oct 9 01:04:46.971295 ignition[756]: fetch: fetch complete Oct 9 01:04:46.971008 unknown[756]: fetched user config from "digitalocean" Oct 9 01:04:46.971301 ignition[756]: fetch: fetch passed Oct 9 01:04:46.973267 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 9 01:04:46.971358 ignition[756]: Ignition finished successfully Oct 9 01:04:46.980360 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 9 01:04:47.024882 ignition[763]: Ignition 2.19.0 Oct 9 01:04:47.024896 ignition[763]: Stage: kargs Oct 9 01:04:47.027926 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 9 01:04:47.025130 ignition[763]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:47.025141 ignition[763]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:47.025901 ignition[763]: kargs: kargs passed Oct 9 01:04:47.025974 ignition[763]: Ignition finished successfully Oct 9 01:04:47.043949 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 9 01:04:47.060998 ignition[769]: Ignition 2.19.0 Oct 9 01:04:47.061019 ignition[769]: Stage: disks Oct 9 01:04:47.061365 ignition[769]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:47.061382 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:47.062626 ignition[769]: disks: disks passed Oct 9 01:04:47.067175 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 9 01:04:47.062716 ignition[769]: Ignition finished successfully Oct 9 01:04:47.071345 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 9 01:04:47.072344 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 9 01:04:47.073418 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:04:47.073912 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:04:47.075202 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:04:47.085471 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 9 01:04:47.103779 systemd-fsck[777]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 9 01:04:47.107996 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 9 01:04:47.119384 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 9 01:04:47.240095 kernel: EXT4-fs (vda9): mounted filesystem ba2945c1-be14-41c0-8c54-84d676c7a16b r/w with ordered data mode. Quota mode: none. Oct 9 01:04:47.240455 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 9 01:04:47.241715 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 9 01:04:47.249236 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:04:47.261312 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 9 01:04:47.264283 systemd[1]: Starting flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent... Oct 9 01:04:47.267218 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 9 01:04:47.269918 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 9 01:04:47.271183 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:04:47.274590 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 9 01:04:47.277870 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (785) Oct 9 01:04:47.280401 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:04:47.280469 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:04:47.280490 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:04:47.301437 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 9 01:04:47.310739 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:04:47.310513 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:04:47.357021 initrd-setup-root[811]: cut: /sysroot/etc/passwd: No such file or directory Oct 9 01:04:47.382077 initrd-setup-root[822]: cut: /sysroot/etc/group: No such file or directory Oct 9 01:04:47.390513 coreos-metadata[788]: Oct 09 01:04:47.390 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Oct 9 01:04:47.393064 initrd-setup-root[829]: cut: /sysroot/etc/shadow: No such file or directory Oct 9 01:04:47.401376 initrd-setup-root[836]: cut: /sysroot/etc/gshadow: No such file or directory Oct 9 01:04:47.403845 coreos-metadata[788]: Oct 09 01:04:47.402 INFO Fetch successful Oct 9 01:04:47.405929 coreos-metadata[787]: Oct 09 01:04:47.405 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Oct 9 01:04:47.409095 coreos-metadata[788]: Oct 09 01:04:47.408 INFO wrote hostname ci-4116.0.0-f-ecc45184e6 to /sysroot/etc/hostname Oct 9 01:04:47.410981 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 9 01:04:47.416804 coreos-metadata[787]: Oct 09 01:04:47.415 INFO Fetch successful Oct 9 01:04:47.420978 systemd[1]: flatcar-digitalocean-network.service: Deactivated successfully. Oct 9 01:04:47.421186 systemd[1]: Finished flatcar-digitalocean-network.service - Flatcar DigitalOcean Network Agent. Oct 9 01:04:47.536670 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 9 01:04:47.543288 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 9 01:04:47.546160 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 9 01:04:47.560085 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:04:47.591842 ignition[906]: INFO : Ignition 2.19.0 Oct 9 01:04:47.591842 ignition[906]: INFO : Stage: mount Oct 9 01:04:47.593481 ignition[906]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:47.593481 ignition[906]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:47.593481 ignition[906]: INFO : mount: mount passed Oct 9 01:04:47.593481 ignition[906]: INFO : Ignition finished successfully Oct 9 01:04:47.595123 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 9 01:04:47.596917 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 9 01:04:47.608257 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 9 01:04:47.661803 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 9 01:04:47.668437 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:04:47.683089 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (917) Oct 9 01:04:47.687257 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:04:47.687385 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:04:47.690017 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:04:47.695112 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:04:47.699071 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:04:47.732395 ignition[934]: INFO : Ignition 2.19.0 Oct 9 01:04:47.732395 ignition[934]: INFO : Stage: files Oct 9 01:04:47.734150 ignition[934]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:47.734150 ignition[934]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:47.734150 ignition[934]: DEBUG : files: compiled without relabeling support, skipping Oct 9 01:04:47.736715 ignition[934]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 9 01:04:47.736715 ignition[934]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 9 01:04:47.738734 ignition[934]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 9 01:04:47.739858 ignition[934]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 9 01:04:47.739858 ignition[934]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 9 01:04:47.739222 unknown[934]: wrote ssh authorized keys file for user: core Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Oct 9 01:04:47.742760 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 Oct 9 01:04:48.039231 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 9 01:04:48.337171 ignition[934]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Oct 9 01:04:48.338703 ignition[934]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:04:48.338703 ignition[934]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:04:48.338703 ignition[934]: INFO : files: files passed Oct 9 01:04:48.338703 ignition[934]: INFO : Ignition finished successfully Oct 9 01:04:48.339271 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 9 01:04:48.353093 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 9 01:04:48.358723 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 9 01:04:48.360950 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 9 01:04:48.361097 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 9 01:04:48.383942 initrd-setup-root-after-ignition[963]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:04:48.383942 initrd-setup-root-after-ignition[963]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:04:48.386455 initrd-setup-root-after-ignition[967]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:04:48.388230 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:04:48.389470 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 9 01:04:48.402604 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 9 01:04:48.452594 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 9 01:04:48.452743 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 9 01:04:48.455451 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 9 01:04:48.456165 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 9 01:04:48.457469 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 9 01:04:48.464417 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 9 01:04:48.484033 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:04:48.496458 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 9 01:04:48.511264 systemd-networkd[749]: eth1: Gained IPv6LL Oct 9 01:04:48.514216 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:04:48.515218 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:04:48.516875 systemd[1]: Stopped target timers.target - Timer Units. Oct 9 01:04:48.518160 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 9 01:04:48.518518 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:04:48.519986 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 9 01:04:48.521002 systemd[1]: Stopped target basic.target - Basic System. Oct 9 01:04:48.522527 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 9 01:04:48.523729 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:04:48.525097 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 9 01:04:48.526429 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 9 01:04:48.527798 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:04:48.529174 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 9 01:04:48.530540 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 9 01:04:48.532030 systemd[1]: Stopped target swap.target - Swaps. Oct 9 01:04:48.533411 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 9 01:04:48.533632 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:04:48.535404 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:04:48.537021 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:04:48.538399 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 9 01:04:48.538742 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:04:48.539948 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 9 01:04:48.540184 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 9 01:04:48.541995 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 9 01:04:48.542284 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:04:48.544199 systemd[1]: ignition-files.service: Deactivated successfully. Oct 9 01:04:48.544382 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 9 01:04:48.545601 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 9 01:04:48.545808 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 9 01:04:48.553622 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 9 01:04:48.558584 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 9 01:04:48.559478 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 9 01:04:48.559817 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:04:48.562713 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 9 01:04:48.562887 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:04:48.572170 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 9 01:04:48.572293 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 9 01:04:48.585074 ignition[987]: INFO : Ignition 2.19.0 Oct 9 01:04:48.585074 ignition[987]: INFO : Stage: umount Oct 9 01:04:48.585074 ignition[987]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:04:48.585074 ignition[987]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Oct 9 01:04:48.590138 ignition[987]: INFO : umount: umount passed Oct 9 01:04:48.590138 ignition[987]: INFO : Ignition finished successfully Oct 9 01:04:48.589204 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 9 01:04:48.589378 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 9 01:04:48.591786 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 9 01:04:48.591868 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 9 01:04:48.592678 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 9 01:04:48.592749 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 9 01:04:48.595827 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 9 01:04:48.595912 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 9 01:04:48.597426 systemd[1]: Stopped target network.target - Network. Oct 9 01:04:48.598853 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 9 01:04:48.598955 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:04:48.600200 systemd[1]: Stopped target paths.target - Path Units. Oct 9 01:04:48.600956 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 9 01:04:48.634095 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:04:48.635115 systemd[1]: Stopped target slices.target - Slice Units. Oct 9 01:04:48.636292 systemd[1]: Stopped target sockets.target - Socket Units. Oct 9 01:04:48.636862 systemd[1]: iscsid.socket: Deactivated successfully. Oct 9 01:04:48.636965 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:04:48.638530 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 9 01:04:48.638608 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:04:48.639459 systemd-networkd[749]: eth0: Gained IPv6LL Oct 9 01:04:48.640405 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 9 01:04:48.640491 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 9 01:04:48.642462 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 9 01:04:48.642544 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 9 01:04:48.643996 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 9 01:04:48.645910 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 9 01:04:48.650102 systemd-networkd[749]: eth0: DHCPv6 lease lost Oct 9 01:04:48.671755 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 9 01:04:48.673188 systemd-networkd[749]: eth1: DHCPv6 lease lost Oct 9 01:04:48.675992 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 9 01:04:48.676172 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 9 01:04:48.680623 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 9 01:04:48.680824 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 9 01:04:48.681906 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 9 01:04:48.682059 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 9 01:04:48.689682 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 9 01:04:48.689774 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:04:48.691281 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 9 01:04:48.691362 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 9 01:04:48.700283 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 9 01:04:48.702139 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 9 01:04:48.702358 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:04:48.703111 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 9 01:04:48.703170 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:04:48.703747 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 9 01:04:48.703792 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 9 01:04:48.705063 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 9 01:04:48.705111 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:04:48.707073 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:04:48.724855 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 9 01:04:48.725098 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:04:48.726482 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 9 01:04:48.726545 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 9 01:04:48.727538 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 9 01:04:48.727580 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:04:48.729252 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 9 01:04:48.729333 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:04:48.731402 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 9 01:04:48.731466 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 9 01:04:48.732699 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:04:48.732749 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:04:48.740407 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 9 01:04:48.741146 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 9 01:04:48.741234 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:04:48.744823 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 9 01:04:48.744905 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:04:48.746512 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 9 01:04:48.746575 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:04:48.747128 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:04:48.747167 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:48.748065 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 9 01:04:48.748169 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 9 01:04:48.754399 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 9 01:04:48.754542 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 9 01:04:48.756470 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 9 01:04:48.771504 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 9 01:04:48.784341 systemd[1]: Switching root. Oct 9 01:04:48.875127 systemd-journald[183]: Received SIGTERM from PID 1 (systemd). Oct 9 01:04:48.875265 systemd-journald[183]: Journal stopped Oct 9 01:04:50.082467 kernel: SELinux: policy capability network_peer_controls=1 Oct 9 01:04:50.082552 kernel: SELinux: policy capability open_perms=1 Oct 9 01:04:50.082566 kernel: SELinux: policy capability extended_socket_class=1 Oct 9 01:04:50.082577 kernel: SELinux: policy capability always_check_network=0 Oct 9 01:04:50.082589 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 9 01:04:50.082601 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 9 01:04:50.082623 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 9 01:04:50.082636 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 9 01:04:50.082649 kernel: audit: type=1403 audit(1728435889.035:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 9 01:04:50.082663 systemd[1]: Successfully loaded SELinux policy in 45.278ms. Oct 9 01:04:50.082682 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 11.088ms. Oct 9 01:04:50.082697 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) Oct 9 01:04:50.082709 systemd[1]: Detected virtualization kvm. Oct 9 01:04:50.082721 systemd[1]: Detected architecture x86-64. Oct 9 01:04:50.082735 systemd[1]: Detected first boot. Oct 9 01:04:50.082829 systemd[1]: Hostname set to . Oct 9 01:04:50.082850 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:04:50.082864 zram_generator::config[1030]: No configuration found. Oct 9 01:04:50.082877 systemd[1]: Populated /etc with preset unit settings. Oct 9 01:04:50.082903 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 9 01:04:50.082916 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 9 01:04:50.082928 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 9 01:04:50.082945 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 9 01:04:50.082957 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 9 01:04:50.082970 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 9 01:04:50.082981 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 9 01:04:50.082993 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 9 01:04:50.083005 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 9 01:04:50.083017 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 9 01:04:50.083028 systemd[1]: Created slice user.slice - User and Session Slice. Oct 9 01:04:50.084714 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:04:50.084746 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:04:50.084759 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 9 01:04:50.084773 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 9 01:04:50.084785 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 9 01:04:50.084799 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:04:50.084875 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 9 01:04:50.084889 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:04:50.084903 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 9 01:04:50.084920 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 9 01:04:50.084933 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 9 01:04:50.084946 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 9 01:04:50.084958 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:04:50.084970 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:04:50.084982 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:04:50.084994 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:04:50.085009 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 9 01:04:50.085020 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 9 01:04:50.085032 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:04:50.085059 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:04:50.085073 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:04:50.085085 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 9 01:04:50.085096 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 9 01:04:50.085109 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 9 01:04:50.085120 systemd[1]: Mounting media.mount - External Media Directory... Oct 9 01:04:50.085136 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:50.085149 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 9 01:04:50.085161 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 9 01:04:50.085173 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 9 01:04:50.085186 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). Oct 9 01:04:50.085198 systemd[1]: Reached target machines.target - Containers. Oct 9 01:04:50.085210 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 9 01:04:50.085223 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:04:50.085238 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:04:50.085250 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 9 01:04:50.085263 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:04:50.085274 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:04:50.085294 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:04:50.085306 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 9 01:04:50.085323 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:04:50.085337 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 9 01:04:50.085349 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 9 01:04:50.085364 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 9 01:04:50.085376 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 9 01:04:50.085387 systemd[1]: Stopped systemd-fsck-usr.service. Oct 9 01:04:50.085399 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:04:50.085412 kernel: fuse: init (API version 7.39) Oct 9 01:04:50.085426 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:04:50.085438 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 9 01:04:50.085449 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 9 01:04:50.085464 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:04:50.085476 systemd[1]: verity-setup.service: Deactivated successfully. Oct 9 01:04:50.085488 systemd[1]: Stopped verity-setup.service. Oct 9 01:04:50.085500 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:50.085512 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 9 01:04:50.085524 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 9 01:04:50.085537 systemd[1]: Mounted media.mount - External Media Directory. Oct 9 01:04:50.085548 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 9 01:04:50.085565 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 9 01:04:50.085578 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 9 01:04:50.085589 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:04:50.085604 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 9 01:04:50.085633 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 9 01:04:50.085644 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:04:50.085657 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:04:50.085668 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:04:50.085681 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:04:50.085694 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 9 01:04:50.085706 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 9 01:04:50.085721 kernel: loop: module loaded Oct 9 01:04:50.085734 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:04:50.085746 kernel: ACPI: bus type drm_connector registered Oct 9 01:04:50.085759 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:04:50.085771 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:04:50.085783 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 9 01:04:50.085796 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:04:50.085807 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:04:50.085820 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 9 01:04:50.085834 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 9 01:04:50.085888 systemd-journald[1110]: Collecting audit messages is disabled. Oct 9 01:04:50.085917 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 9 01:04:50.085931 systemd-journald[1110]: Journal started Oct 9 01:04:50.085956 systemd-journald[1110]: Runtime Journal (/run/log/journal/48f90e3de5e44ecabcf4516b4f96e676) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:04:50.091112 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 9 01:04:49.683736 systemd[1]: Queued start job for default target multi-user.target. Oct 9 01:04:49.701728 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 9 01:04:49.702361 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 9 01:04:50.106085 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 9 01:04:50.106251 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 9 01:04:50.109188 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:04:50.112076 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 9 01:04:50.122101 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 9 01:04:50.130647 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 9 01:04:50.130752 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:04:50.143619 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 9 01:04:50.143733 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:04:50.154624 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 9 01:04:50.154715 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:04:50.161744 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:04:50.175931 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 9 01:04:50.179399 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:04:50.186479 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:04:50.197254 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:04:50.199468 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 9 01:04:50.200243 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 9 01:04:50.202566 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 9 01:04:50.225162 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 9 01:04:50.257516 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 9 01:04:50.274863 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 9 01:04:50.284437 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 9 01:04:50.291498 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 9 01:04:50.295798 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:04:50.296104 kernel: loop0: detected capacity change from 0 to 140992 Oct 9 01:04:50.310451 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 9 01:04:50.311423 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 9 01:04:50.348466 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 9 01:04:50.352227 systemd-journald[1110]: Time spent on flushing to /var/log/journal/48f90e3de5e44ecabcf4516b4f96e676 is 88.109ms for 982 entries. Oct 9 01:04:50.352227 systemd-journald[1110]: System Journal (/var/log/journal/48f90e3de5e44ecabcf4516b4f96e676) is 8.0M, max 195.6M, 187.6M free. Oct 9 01:04:50.481471 systemd-journald[1110]: Received client request to flush runtime journal. Oct 9 01:04:50.482715 kernel: loop1: detected capacity change from 0 to 205544 Oct 9 01:04:50.482740 kernel: loop2: detected capacity change from 0 to 138192 Oct 9 01:04:50.388858 udevadm[1161]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 9 01:04:50.400907 systemd-tmpfiles[1133]: ACLs are not supported, ignoring. Oct 9 01:04:50.400932 systemd-tmpfiles[1133]: ACLs are not supported, ignoring. Oct 9 01:04:50.425232 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:04:50.435428 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 9 01:04:50.486821 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 9 01:04:50.496426 kernel: loop3: detected capacity change from 0 to 8 Oct 9 01:04:50.528880 kernel: loop4: detected capacity change from 0 to 140992 Oct 9 01:04:50.540315 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 9 01:04:50.555111 kernel: loop5: detected capacity change from 0 to 205544 Oct 9 01:04:50.555347 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:04:50.571085 kernel: loop6: detected capacity change from 0 to 138192 Oct 9 01:04:50.597106 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Oct 9 01:04:50.597541 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Oct 9 01:04:50.598077 kernel: loop7: detected capacity change from 0 to 8 Oct 9 01:04:50.605798 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:04:50.618260 (sd-merge)[1176]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Oct 9 01:04:50.619507 (sd-merge)[1176]: Merged extensions into '/usr'. Oct 9 01:04:50.636762 systemd[1]: Reloading requested from client PID 1132 ('systemd-sysext') (unit systemd-sysext.service)... Oct 9 01:04:50.636788 systemd[1]: Reloading... Oct 9 01:04:50.818557 zram_generator::config[1206]: No configuration found. Oct 9 01:04:50.959883 ldconfig[1128]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 9 01:04:50.994626 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. Oct 9 01:04:51.045521 systemd[1]: Reloading finished in 408 ms. Oct 9 01:04:51.069089 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 9 01:04:51.074445 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 9 01:04:51.082409 systemd[1]: Starting ensure-sysext.service... Oct 9 01:04:51.090417 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:04:51.105200 systemd[1]: Reloading requested from client PID 1250 ('systemctl') (unit ensure-sysext.service)... Oct 9 01:04:51.105229 systemd[1]: Reloading... Oct 9 01:04:51.149824 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 9 01:04:51.150181 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 9 01:04:51.151189 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 9 01:04:51.151466 systemd-tmpfiles[1251]: ACLs are not supported, ignoring. Oct 9 01:04:51.151553 systemd-tmpfiles[1251]: ACLs are not supported, ignoring. Oct 9 01:04:51.156124 systemd-tmpfiles[1251]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:04:51.156139 systemd-tmpfiles[1251]: Skipping /boot Oct 9 01:04:51.174681 systemd-tmpfiles[1251]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:04:51.174697 systemd-tmpfiles[1251]: Skipping /boot Oct 9 01:04:51.232070 zram_generator::config[1284]: No configuration found. Oct 9 01:04:51.366591 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. Oct 9 01:04:51.416893 systemd[1]: Reloading finished in 311 ms. Oct 9 01:04:51.433741 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 9 01:04:51.440859 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:04:51.460325 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 01:04:51.465293 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 9 01:04:51.474408 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 9 01:04:51.482468 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:04:51.491425 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:04:51.497306 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 9 01:04:51.506692 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.507269 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:04:51.517508 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:04:51.520486 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:04:51.526547 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:04:51.527930 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:04:51.528263 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.533479 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 9 01:04:51.536762 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.537273 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:04:51.537469 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:04:51.537549 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.544177 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 9 01:04:51.549500 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.549705 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:04:51.559538 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:04:51.560814 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:04:51.565614 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 9 01:04:51.567823 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.579401 systemd[1]: Finished ensure-sysext.service. Oct 9 01:04:51.580685 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:04:51.582410 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:04:51.596643 systemd-udevd[1333]: Using default interface naming scheme 'v255'. Oct 9 01:04:51.600502 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 9 01:04:51.605487 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 9 01:04:51.630136 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 9 01:04:51.631942 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). Oct 9 01:04:51.645771 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:04:51.646026 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:04:51.649529 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 9 01:04:51.650398 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:04:51.652150 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:04:51.663336 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:04:51.666109 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 9 01:04:51.670507 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:04:51.670675 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:04:51.680508 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:04:51.680715 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:04:51.681586 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:04:51.701885 augenrules[1386]: No rules Oct 9 01:04:51.703988 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 01:04:51.706136 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 01:04:51.808648 systemd-resolved[1332]: Positive Trust Anchors: Oct 9 01:04:51.808669 systemd-resolved[1332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:04:51.808706 systemd-resolved[1332]: 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 Oct 9 01:04:51.813021 systemd-networkd[1369]: lo: Link UP Oct 9 01:04:51.813030 systemd-networkd[1369]: lo: Gained carrier Oct 9 01:04:51.813879 systemd-networkd[1369]: Enumeration completed Oct 9 01:04:51.814010 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:04:51.819346 systemd-resolved[1332]: Using system hostname 'ci-4116.0.0-f-ecc45184e6'. Oct 9 01:04:51.823330 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 9 01:04:51.828804 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:04:51.830298 systemd[1]: Reached target network.target - Network. Oct 9 01:04:51.831131 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:04:51.839279 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 9 01:04:51.840191 systemd[1]: Reached target time-set.target - System Time Set. Oct 9 01:04:51.860144 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1372) Oct 9 01:04:51.869757 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Oct 9 01:04:51.870696 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.870872 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:04:51.881074 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1372) Oct 9 01:04:51.878323 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:04:51.880513 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:04:51.883673 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:04:51.884409 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:04:51.884455 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). Oct 9 01:04:51.884474 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:04:51.884771 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 9 01:04:51.906074 kernel: ISO 9660 Extensions: RRIP_1991A Oct 9 01:04:51.908784 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Oct 9 01:04:51.931913 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:04:51.932670 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:04:51.940433 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:04:51.941146 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:04:51.943414 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:04:51.943637 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:04:51.947789 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:04:51.947911 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:04:51.951078 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1374) Oct 9 01:04:51.991083 systemd-networkd[1369]: eth0: Configuring with /run/systemd/network/10-fa:53:50:3d:f0:8c.network. Oct 9 01:04:51.992272 systemd-networkd[1369]: eth0: Link UP Oct 9 01:04:51.992378 systemd-networkd[1369]: eth0: Gained carrier Oct 9 01:04:51.996864 systemd-timesyncd[1351]: Network configuration changed, trying to establish connection. Oct 9 01:04:52.016075 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 9 01:04:52.015482 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:04:52.024646 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 9 01:04:52.039340 systemd-networkd[1369]: eth1: Configuring with /run/systemd/network/10-36:56:38:e9:ba:f2.network. Oct 9 01:04:52.042825 kernel: ACPI: button: Power Button [PWRF] Oct 9 01:04:52.042588 systemd-networkd[1369]: eth1: Link UP Oct 9 01:04:52.042595 systemd-networkd[1369]: eth1: Gained carrier Oct 9 01:04:52.060994 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 9 01:04:52.065069 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Oct 9 01:04:52.090093 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Oct 9 01:04:52.104253 kernel: mousedev: PS/2 mouse device common for all mice Oct 9 01:04:52.135076 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Oct 9 01:04:52.140029 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Oct 9 01:04:52.139982 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:52.151107 kernel: Console: switching to colour dummy device 80x25 Oct 9 01:04:52.158317 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Oct 9 01:04:52.158422 kernel: [drm] features: -context_init Oct 9 01:04:52.158791 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:04:52.159138 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:52.167922 kernel: [drm] number of scanouts: 1 Oct 9 01:04:52.168003 kernel: [drm] number of cap sets: 0 Oct 9 01:04:52.166762 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:52.171068 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Oct 9 01:04:52.177067 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Oct 9 01:04:52.178905 kernel: Console: switching to colour frame buffer device 128x48 Oct 9 01:04:52.187110 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Oct 9 01:04:52.216645 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:04:52.216887 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:52.239663 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:04:52.813990 systemd-resolved[1332]: Clock change detected. Flushing caches. Oct 9 01:04:52.814426 systemd-timesyncd[1351]: Contacted time server 69.89.207.199:123 (0.flatcar.pool.ntp.org). Oct 9 01:04:52.814542 systemd-timesyncd[1351]: Initial clock synchronization to Wed 2024-10-09 01:04:52.813876 UTC. Oct 9 01:04:52.929762 kernel: EDAC MC: Ver: 3.0.0 Oct 9 01:04:52.938015 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:04:52.955526 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 9 01:04:52.962312 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 9 01:04:52.982543 lvm[1438]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:04:53.013649 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 9 01:04:53.015855 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:04:53.017090 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:04:53.017330 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 9 01:04:53.017444 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 9 01:04:53.017899 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 9 01:04:53.018416 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 9 01:04:53.018553 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 9 01:04:53.018675 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 9 01:04:53.018714 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:04:53.018839 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:04:53.020604 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 9 01:04:53.022957 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 9 01:04:53.030252 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 9 01:04:53.033000 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 9 01:04:53.033994 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 9 01:04:53.036083 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:04:53.038393 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:04:53.040864 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:04:53.042323 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:04:53.047758 lvm[1442]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:04:53.048250 systemd[1]: Starting containerd.service - containerd container runtime... Oct 9 01:04:53.062042 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 9 01:04:53.066558 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 9 01:04:53.071638 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 9 01:04:53.077639 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 9 01:04:53.080835 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). Oct 9 01:04:53.092207 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 9 01:04:53.097140 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 9 01:04:53.110258 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 9 01:04:53.129728 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 9 01:04:53.132754 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 9 01:04:53.135202 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 9 01:04:53.145317 systemd[1]: Starting update-engine.service - Update Engine... Oct 9 01:04:53.148530 dbus-daemon[1445]: [system] SELinux support is enabled Oct 9 01:04:53.151242 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 9 01:04:53.154385 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 9 01:04:53.162150 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 9 01:04:53.164674 extend-filesystems[1449]: Found loop4 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found loop5 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found loop6 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found loop7 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda1 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda2 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda3 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found usr Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda4 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda6 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda7 Oct 9 01:04:53.181186 extend-filesystems[1449]: Found vda9 Oct 9 01:04:53.181186 extend-filesystems[1449]: Checking size of /dev/vda9 Oct 9 01:04:53.225475 jq[1446]: false Oct 9 01:04:53.174392 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 9 01:04:53.175102 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 9 01:04:53.186758 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 9 01:04:53.188405 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 9 01:04:53.204266 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). Oct 9 01:04:53.204332 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 9 01:04:53.219335 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). Oct 9 01:04:53.219455 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Oct 9 01:04:53.219492 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 9 01:04:53.237167 coreos-metadata[1444]: Oct 09 01:04:53.237 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Oct 9 01:04:53.244418 extend-filesystems[1449]: Resized partition /dev/vda9 Oct 9 01:04:53.258781 extend-filesystems[1476]: resize2fs 1.47.1 (20-May-2024) Oct 9 01:04:53.265081 jq[1458]: true Oct 9 01:04:53.276770 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Oct 9 01:04:53.246655 systemd[1]: motdgen.service: Deactivated successfully. Oct 9 01:04:53.277629 coreos-metadata[1444]: Oct 09 01:04:53.265 INFO Fetch successful Oct 9 01:04:53.246943 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 9 01:04:53.250837 (ntainerd)[1475]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 9 01:04:53.306397 update_engine[1457]: I20241009 01:04:53.305548 1457 main.cc:92] Flatcar Update Engine starting Oct 9 01:04:53.314413 jq[1478]: true Oct 9 01:04:53.322091 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1379) Oct 9 01:04:53.327398 update_engine[1457]: I20241009 01:04:53.327149 1457 update_check_scheduler.cc:74] Next update check in 5m17s Oct 9 01:04:53.336762 systemd[1]: Started update-engine.service - Update Engine. Oct 9 01:04:53.355689 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 9 01:04:53.408154 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 9 01:04:53.413379 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 9 01:04:53.452964 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Oct 9 01:04:53.524062 extend-filesystems[1476]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 9 01:04:53.524062 extend-filesystems[1476]: old_desc_blocks = 1, new_desc_blocks = 8 Oct 9 01:04:53.524062 extend-filesystems[1476]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Oct 9 01:04:53.550694 extend-filesystems[1449]: Resized filesystem in /dev/vda9 Oct 9 01:04:53.550694 extend-filesystems[1449]: Found vdb Oct 9 01:04:53.529196 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 9 01:04:53.530375 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 9 01:04:53.575637 systemd-logind[1454]: New seat seat0. Oct 9 01:04:53.580805 bash[1502]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:04:53.592091 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 9 01:04:53.615307 systemd-logind[1454]: Watching system buttons on /dev/input/event1 (Power Button) Oct 9 01:04:53.615333 systemd-logind[1454]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 9 01:04:53.628365 systemd[1]: Starting sshkeys.service... Oct 9 01:04:53.630046 systemd[1]: Started systemd-logind.service - User Login Management. Oct 9 01:04:53.676012 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 9 01:04:53.692075 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 9 01:04:53.730903 locksmithd[1485]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 9 01:04:53.750965 coreos-metadata[1515]: Oct 09 01:04:53.750 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Oct 9 01:04:53.755824 sshd_keygen[1477]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 9 01:04:53.761206 coreos-metadata[1515]: Oct 09 01:04:53.761 INFO Fetch successful Oct 9 01:04:53.778400 unknown[1515]: wrote ssh authorized keys file for user: core Oct 9 01:04:53.795135 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 9 01:04:53.803164 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 9 01:04:53.819174 containerd[1475]: time="2024-10-09T01:04:53.819077234Z" level=info msg="starting containerd" revision=b2ce781edcbd6cb758f172ecab61c79d607cc41d version=v1.7.22 Oct 9 01:04:53.820430 update-ssh-keys[1528]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:04:53.821734 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 9 01:04:53.831084 systemd[1]: Finished sshkeys.service. Oct 9 01:04:53.839374 systemd[1]: issuegen.service: Deactivated successfully. Oct 9 01:04:53.839834 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 9 01:04:53.856458 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 9 01:04:53.862950 containerd[1475]: time="2024-10-09T01:04:53.862258400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.864184 containerd[1475]: time="2024-10-09T01:04:53.864119636Z" 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.54-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:04:53.864184 containerd[1475]: time="2024-10-09T01:04:53.864163117Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 9 01:04:53.864184 containerd[1475]: time="2024-10-09T01:04:53.864182417Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864370659Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864391564Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864459399Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864474354Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864698926Z" 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 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864715378Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864729035Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864738406Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.864808289Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865112 containerd[1475]: time="2024-10-09T01:04:53.865045864Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:04:53.865463 containerd[1475]: time="2024-10-09T01:04:53.865160342Z" 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 Oct 9 01:04:53.865463 containerd[1475]: time="2024-10-09T01:04:53.865179831Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 9 01:04:53.865463 containerd[1475]: time="2024-10-09T01:04:53.865282798Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 9 01:04:53.865463 containerd[1475]: time="2024-10-09T01:04:53.865332939Z" level=info msg="metadata content store policy set" policy=shared Oct 9 01:04:53.876791 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880472098Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880550752Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880569213Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880605017Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880629217Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 9 01:04:53.881085 containerd[1475]: time="2024-10-09T01:04:53.880834932Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 9 01:04:53.881366 containerd[1475]: time="2024-10-09T01:04:53.881230194Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881422327Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881447976Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881463917Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881478246Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881491899Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881505034Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881520674Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881535635Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881574 containerd[1475]: time="2024-10-09T01:04:53.881570744Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881587867Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881599827Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881621071Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881635837Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881648410Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881662358Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881674124Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881687696Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881699583Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881712334Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881726064Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881741160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.881758 containerd[1475]: time="2024-10-09T01:04:53.881752033Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881763503Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881785127Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881805033Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881834354Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881856232Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.882377 containerd[1475]: time="2024-10-09T01:04:53.881872513Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 9 01:04:53.883395 containerd[1475]: time="2024-10-09T01:04:53.883294804Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883513662Z" 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 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883536969Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883557577Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883575667Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883602996Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883617692Z" level=info msg="NRI interface is disabled by configuration." Oct 9 01:04:53.884530 containerd[1475]: time="2024-10-09T01:04:53.883629079Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 9 01:04:53.884714 containerd[1475]: time="2024-10-09T01:04:53.884024564Z" 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}" Oct 9 01:04:53.884714 containerd[1475]: time="2024-10-09T01:04:53.884073766Z" level=info msg="Connect containerd service" Oct 9 01:04:53.884714 containerd[1475]: time="2024-10-09T01:04:53.884115481Z" level=info msg="using legacy CRI server" Oct 9 01:04:53.884714 containerd[1475]: time="2024-10-09T01:04:53.884123629Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 9 01:04:53.884714 containerd[1475]: time="2024-10-09T01:04:53.884224819Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 9 01:04:53.885412 containerd[1475]: time="2024-10-09T01:04:53.885383854Z" 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" Oct 9 01:04:53.885614 containerd[1475]: time="2024-10-09T01:04:53.885581513Z" level=info msg="Start subscribing containerd event" Oct 9 01:04:53.885678 containerd[1475]: time="2024-10-09T01:04:53.885668719Z" level=info msg="Start recovering state" Oct 9 01:04:53.885771 containerd[1475]: time="2024-10-09T01:04:53.885760419Z" level=info msg="Start event monitor" Oct 9 01:04:53.885817 containerd[1475]: time="2024-10-09T01:04:53.885808827Z" level=info msg="Start snapshots syncer" Oct 9 01:04:53.885855 containerd[1475]: time="2024-10-09T01:04:53.885846270Z" level=info msg="Start cni network conf syncer for default" Oct 9 01:04:53.885893 containerd[1475]: time="2024-10-09T01:04:53.885884985Z" level=info msg="Start streaming server" Oct 9 01:04:53.886292 containerd[1475]: time="2024-10-09T01:04:53.886273213Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 9 01:04:53.886642 containerd[1475]: time="2024-10-09T01:04:53.886625587Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 9 01:04:53.886776 containerd[1475]: time="2024-10-09T01:04:53.886764272Z" level=info msg="containerd successfully booted in 0.069791s" Oct 9 01:04:53.887515 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 9 01:04:53.900518 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 9 01:04:53.905116 systemd[1]: Reached target getty.target - Login Prompts. Oct 9 01:04:53.906597 systemd[1]: Started containerd.service - containerd container runtime. Oct 9 01:04:54.195196 systemd-networkd[1369]: eth0: Gained IPv6LL Oct 9 01:04:54.198765 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 9 01:04:54.202378 systemd[1]: Reached target network-online.target - Network is Online. Oct 9 01:04:54.210424 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 01:04:54.222256 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 9 01:04:54.249408 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 9 01:04:54.323578 systemd-networkd[1369]: eth1: Gained IPv6LL Oct 9 01:04:55.119914 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 9 01:04:55.131446 systemd[1]: Started sshd@0-137.184.14.81:22-139.178.68.195:47108.service - OpenSSH per-connection server daemon (139.178.68.195:47108). Oct 9 01:04:55.251786 sshd[1557]: Accepted publickey for core from 139.178.68.195 port 47108 ssh2: RSA SHA256:rAraWF6dAhtbVQzAuCRwvYKxEoENakeAe95MuXIlOkk Oct 9 01:04:55.257065 sshd[1557]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:04:55.281401 systemd-logind[1454]: New session 1 of user core. Oct 9 01:04:55.283745 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 9 01:04:55.315551 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 9 01:04:55.366114 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 9 01:04:55.381682 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 9 01:04:55.402011 (systemd)[1561]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 9 01:04:55.515238 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 01:04:55.523527 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 9 01:04:55.535582 (kubelet)[1572]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 9 01:04:55.562894 systemd[1561]: Queued start job for default target default.target. Oct 9 01:04:55.573851 systemd[1561]: Created slice app.slice - User Application Slice. Oct 9 01:04:55.573903 systemd[1561]: Reached target paths.target - Paths. Oct 9 01:04:55.573966 systemd[1561]: Reached target timers.target - Timers. Oct 9 01:04:55.577843 systemd[1561]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 9 01:04:55.615216 systemd[1561]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 9 01:04:55.615351 systemd[1561]: Reached target sockets.target - Sockets. Oct 9 01:04:55.615368 systemd[1561]: Reached target basic.target - Basic System. Oct 9 01:04:55.615418 systemd[1561]: Reached target default.target - Main User Target. Oct 9 01:04:55.615453 systemd[1561]: Startup finished in 187ms. Oct 9 01:04:55.615760 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 9 01:04:55.625173 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 9 01:04:55.629209 systemd[1]: Startup finished in 1.360s (kernel) + 5.293s (initrd) + 6.074s (userspace) = 12.728s. Oct 9 01:04:55.711323 systemd[1]: Started sshd@1-137.184.14.81:22-139.178.68.195:47118.service - OpenSSH per-connection server daemon (139.178.68.195:47118). Oct 9 01:04:55.781253 sshd[1586]: Accepted publickey for core from 139.178.68.195 port 47118 ssh2: RSA SHA256:rAraWF6dAhtbVQzAuCRwvYKxEoENakeAe95MuXIlOkk Oct 9 01:04:55.785159 sshd[1586]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:04:55.802403 systemd-logind[1454]: New session 2 of user core. Oct 9 01:04:55.809338 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 9 01:04:55.883872 sshd[1586]: pam_unix(sshd:session): session closed for user core Oct 9 01:04:55.896159 systemd[1]: sshd@1-137.184.14.81:22-139.178.68.195:47118.service: Deactivated successfully. Oct 9 01:04:55.900488 systemd[1]: session-2.scope: Deactivated successfully. Oct 9 01:04:55.905267 systemd-logind[1454]: Session 2 logged out. Waiting for processes to exit. Oct 9 01:04:55.919474 systemd[1]: Started sshd@2-137.184.14.81:22-139.178.68.195:47120.service - OpenSSH per-connection server daemon (139.178.68.195:47120). Oct 9 01:04:55.925036 systemd-logind[1454]: Removed session 2. Oct 9 01:04:55.971634 sshd[1593]: Accepted publickey for core from 139.178.68.195 port 47120 ssh2: RSA SHA256:rAraWF6dAhtbVQzAuCRwvYKxEoENakeAe95MuXIlOkk Oct 9 01:04:55.974611 sshd[1593]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:04:55.980664 systemd-logind[1454]: New session 3 of user core. Oct 9 01:04:55.987299 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 9 01:04:56.055724 sshd[1593]: pam_unix(sshd:session): session closed for user core Oct 9 01:04:56.064729 systemd[1]: sshd@2-137.184.14.81:22-139.178.68.195:47120.service: Deactivated successfully. Oct 9 01:04:56.068346 systemd[1]: session-3.scope: Deactivated successfully. Oct 9 01:04:56.070441 systemd-logind[1454]: Session 3 logged out. Waiting for processes to exit. Oct 9 01:04:56.084411 systemd[1]: Started sshd@3-137.184.14.81:22-139.178.68.195:47132.service - OpenSSH per-connection server daemon (139.178.68.195:47132). Oct 9 01:04:56.087916 systemd-logind[1454]: Removed session 3. Oct 9 01:04:56.135872 sshd[1600]: Accepted publickey for core from 139.178.68.195 port 47132 ssh2: RSA SHA256:rAraWF6dAhtbVQzAuCRwvYKxEoENakeAe95MuXIlOkk Oct 9 01:04:56.139008 sshd[1600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:04:56.146387 systemd-logind[1454]: New session 4 of user core. Oct 9 01:04:56.153426 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 9 01:04:56.231585 sshd[1600]: pam_unix(sshd:session): session closed for user core Oct 9 01:04:56.245076 systemd[1]: sshd@3-137.184.14.81:22-139.178.68.195:47132.service: Deactivated successfully. Oct 9 01:04:56.247897 systemd[1]: session-4.scope: Deactivated successfully. Oct 9 01:04:56.251000 systemd-logind[1454]: Session 4 logged out. Waiting for processes to exit. Oct 9 01:04:56.260832 systemd[1]: Started sshd@4-137.184.14.81:22-139.178.68.195:47138.service - OpenSSH per-connection server daemon (139.178.68.195:47138). Oct 9 01:04:56.265081 systemd-logind[1454]: Removed session 4. Oct 9 01:04:56.303879 sshd[1607]: Accepted publickey for core from 139.178.68.195 port 47138 ssh2: RSA SHA256:rAraWF6dAhtbVQzAuCRwvYKxEoENakeAe95MuXIlOkk Oct 9 01:04:56.307547 sshd[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:04:56.317129 systemd-logind[1454]: New session 5 of user core. Oct 9 01:04:56.323300 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 9 01:04:56.409215 sudo[1611]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 9 01:04:56.409665 sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:04:56.415835 kubelet[1572]: E1009 01:04:56.415782 1572 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" Oct 9 01:04:56.420741 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 9 01:04:56.421468 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 9 01:04:56.422061 systemd[1]: kubelet.service: Consumed 1.552s CPU time. Oct 9 01:04:57.230188 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 01:04:57.230369 systemd[1]: kubelet.service: Consumed 1.552s CPU time. Oct 9 01:04:57.241486 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 01:04:57.286434 systemd[1]: Reloading requested from client PID 1644 ('systemctl') (unit session-5.scope)... Oct 9 01:04:57.286645 systemd[1]: Reloading... Oct 9 01:04:57.451965 zram_generator::config[1682]: No configuration found. Oct 9 01:04:57.611592 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. Oct 9 01:04:57.734770 systemd[1]: Reloading finished in 447 ms. Oct 9 01:04:57.810829 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 9 01:04:57.811063 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 9 01:04:57.811462 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 01:04:57.818583 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 9 01:04:57.994478 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 9 01:04:57.997809 (kubelet)[1736]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 9 01:04:58.061972 kubelet[1736]: 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. Oct 9 01:04:58.061972 kubelet[1736]: 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. Oct 9 01:04:58.061972 kubelet[1736]: 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. Oct 9 01:04:58.061972 kubelet[1736]: I1009 01:04:58.061538 1736 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" Oct 9 01:04:58.597990 kubelet[1736]: I1009 01:04:58.597689 1736 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Oct 9 01:04:58.597990 kubelet[1736]: I1009 01:04:58.597748 1736 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 9 01:04:58.598411 kubelet[1736]: I1009 01:04:58.598377 1736 server.go:929] "Client rotation is on, will bootstrap in background" Oct 9 01:04:58.633971 kubelet[1736]: I1009 01:04:58.633693 1736 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 9 01:04:58.652253 kubelet[1736]: E1009 01:04:58.652083 1736 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 9 01:04:58.652253 kubelet[1736]: I1009 01:04:58.652160 1736 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." Oct 9 01:04:58.663081 kubelet[1736]: I1009 01:04:58.663025 1736 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 9 01:04:58.664961 kubelet[1736]: I1009 01:04:58.664882 1736 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Oct 9 01:04:58.665279 kubelet[1736]: I1009 01:04:58.665209 1736 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 9 01:04:58.665606 kubelet[1736]: I1009 01:04:58.665275 1736 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"137.184.14.81","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 9 01:04:58.665751 kubelet[1736]: I1009 01:04:58.665611 1736 topology_manager.go:138] "Creating topology manager with none policy" Oct 9 01:04:58.665751 kubelet[1736]: I1009 01:04:58.665631 1736 container_manager_linux.go:300] "Creating device plugin manager" Oct 9 01:04:58.665829 kubelet[1736]: I1009 01:04:58.665798 1736 state_mem.go:36] "Initialized new in-memory state store" Oct 9 01:04:58.669399 kubelet[1736]: I1009 01:04:58.669314 1736 kubelet.go:408] "Attempting to sync node with API server" Oct 9 01:04:58.669399 kubelet[1736]: I1009 01:04:58.669377 1736 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 9 01:04:58.669588 kubelet[1736]: I1009 01:04:58.669443 1736 kubelet.go:314] "Adding apiserver pod source" Oct 9 01:04:58.669588 kubelet[1736]: I1009 01:04:58.669468 1736 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 9 01:04:58.670887 kubelet[1736]: E1009 01:04:58.669775 1736 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:04:58.670887 kubelet[1736]: E1009 01:04:58.669872 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:04:58.676163 kubelet[1736]: I1009 01:04:58.676085 1736 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.22" apiVersion="v1" Oct 9 01:04:58.677817 kubelet[1736]: W1009 01:04:58.677769 1736 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "137.184.14.81" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Oct 9 01:04:58.678245 kubelet[1736]: E1009 01:04:58.678208 1736 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"137.184.14.81\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Oct 9 01:04:58.679053 kubelet[1736]: I1009 01:04:58.678871 1736 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Oct 9 01:04:58.679867 kubelet[1736]: W1009 01:04:58.679821 1736 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 9 01:04:58.681203 kubelet[1736]: I1009 01:04:58.680916 1736 server.go:1269] "Started kubelet" Oct 9 01:04:58.682457 kubelet[1736]: I1009 01:04:58.681803 1736 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Oct 9 01:04:58.683884 kubelet[1736]: I1009 01:04:58.683359 1736 server.go:460] "Adding debug handlers to kubelet server" Oct 9 01:04:58.686893 kubelet[1736]: I1009 01:04:58.686574 1736 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 9 01:04:58.691234 kubelet[1736]: I1009 01:04:58.691141 1736 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 9 01:04:58.691756 kubelet[1736]: I1009 01:04:58.691732 1736 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 9 01:04:58.696816 kubelet[1736]: I1009 01:04:58.696776 1736 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 9 01:04:58.704854 kubelet[1736]: I1009 01:04:58.704808 1736 volume_manager.go:289] "Starting Kubelet Volume Manager" Oct 9 01:04:58.707803 kubelet[1736]: I1009 01:04:58.705228 1736 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Oct 9 01:04:58.710047 kubelet[1736]: E1009 01:04:58.705452 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:58.710463 kubelet[1736]: I1009 01:04:58.709240 1736 factory.go:221] Registration of the systemd container factory successfully Oct 9 01:04:58.710463 kubelet[1736]: I1009 01:04:58.710399 1736 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 Oct 9 01:04:58.710886 kubelet[1736]: I1009 01:04:58.709428 1736 reconciler.go:26] "Reconciler: start to sync state" Oct 9 01:04:58.712539 kubelet[1736]: E1009 01:04:58.712302 1736 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 9 01:04:58.714717 kubelet[1736]: E1009 01:04:58.714679 1736 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"137.184.14.81\" not found" node="137.184.14.81" Oct 9 01:04:58.715097 kubelet[1736]: I1009 01:04:58.714902 1736 factory.go:221] Registration of the containerd container factory successfully Oct 9 01:04:58.746374 kubelet[1736]: I1009 01:04:58.745671 1736 cpu_manager.go:214] "Starting CPU manager" policy="none" Oct 9 01:04:58.746374 kubelet[1736]: I1009 01:04:58.745705 1736 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Oct 9 01:04:58.746374 kubelet[1736]: I1009 01:04:58.745745 1736 state_mem.go:36] "Initialized new in-memory state store" Oct 9 01:04:58.751460 kubelet[1736]: I1009 01:04:58.751426 1736 policy_none.go:49] "None policy: Start" Oct 9 01:04:58.753705 kubelet[1736]: I1009 01:04:58.753678 1736 memory_manager.go:170] "Starting memorymanager" policy="None" Oct 9 01:04:58.755043 kubelet[1736]: I1009 01:04:58.755007 1736 state_mem.go:35] "Initializing new in-memory state store" Oct 9 01:04:58.771760 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 9 01:04:58.794346 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 9 01:04:58.810005 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 9 01:04:58.814905 kubelet[1736]: E1009 01:04:58.814838 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:58.823418 kubelet[1736]: I1009 01:04:58.822908 1736 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Oct 9 01:04:58.825053 kubelet[1736]: I1009 01:04:58.824775 1736 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 9 01:04:58.825053 kubelet[1736]: I1009 01:04:58.824798 1736 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 9 01:04:58.825380 kubelet[1736]: I1009 01:04:58.825259 1736 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 9 01:04:58.831339 kubelet[1736]: E1009 01:04:58.831213 1736 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"137.184.14.81\" not found" Oct 9 01:04:58.832809 kubelet[1736]: I1009 01:04:58.832604 1736 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Oct 9 01:04:58.836538 kubelet[1736]: I1009 01:04:58.836487 1736 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Oct 9 01:04:58.836961 kubelet[1736]: I1009 01:04:58.836765 1736 status_manager.go:217] "Starting to sync pod status with apiserver" Oct 9 01:04:58.836961 kubelet[1736]: I1009 01:04:58.836803 1736 kubelet.go:2321] "Starting kubelet main sync loop" Oct 9 01:04:58.837089 kubelet[1736]: E1009 01:04:58.837063 1736 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Oct 9 01:04:58.927406 kubelet[1736]: I1009 01:04:58.927207 1736 kubelet_node_status.go:72] "Attempting to register node" node="137.184.14.81" Oct 9 01:04:58.942784 kubelet[1736]: I1009 01:04:58.942674 1736 kubelet_node_status.go:75] "Successfully registered node" node="137.184.14.81" Oct 9 01:04:58.943272 kubelet[1736]: E1009 01:04:58.943038 1736 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"137.184.14.81\": node \"137.184.14.81\" not found" Oct 9 01:04:58.952150 kubelet[1736]: I1009 01:04:58.952006 1736 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 9 01:04:58.952888 containerd[1475]: time="2024-10-09T01:04:58.952805144Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 9 01:04:58.954653 kubelet[1736]: I1009 01:04:58.953148 1736 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 9 01:04:58.967287 kubelet[1736]: E1009 01:04:58.967221 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.036819 sudo[1611]: pam_unix(sudo:session): session closed for user root Oct 9 01:04:59.042459 sshd[1607]: pam_unix(sshd:session): session closed for user core Oct 9 01:04:59.047876 systemd[1]: sshd@4-137.184.14.81:22-139.178.68.195:47138.service: Deactivated successfully. Oct 9 01:04:59.052200 systemd[1]: session-5.scope: Deactivated successfully. Oct 9 01:04:59.053833 systemd-logind[1454]: Session 5 logged out. Waiting for processes to exit. Oct 9 01:04:59.055986 systemd-logind[1454]: Removed session 5. Oct 9 01:04:59.070556 kubelet[1736]: E1009 01:04:59.070473 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.171598 kubelet[1736]: E1009 01:04:59.171515 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.272430 kubelet[1736]: E1009 01:04:59.272212 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.373445 kubelet[1736]: E1009 01:04:59.373320 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.474518 kubelet[1736]: E1009 01:04:59.474433 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.575769 kubelet[1736]: E1009 01:04:59.575532 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.601100 kubelet[1736]: I1009 01:04:59.600971 1736 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 9 01:04:59.601528 kubelet[1736]: W1009 01:04:59.601421 1736 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 Oct 9 01:04:59.601528 kubelet[1736]: W1009 01:04:59.601471 1736 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 Oct 9 01:04:59.601528 kubelet[1736]: W1009 01:04:59.601426 1736 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 Oct 9 01:04:59.670546 kubelet[1736]: E1009 01:04:59.670422 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:04:59.676326 kubelet[1736]: E1009 01:04:59.676191 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.776744 kubelet[1736]: E1009 01:04:59.776651 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.877858 kubelet[1736]: E1009 01:04:59.877652 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:04:59.978528 kubelet[1736]: E1009 01:04:59.978403 1736 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"137.184.14.81\" not found" Oct 9 01:05:00.671593 kubelet[1736]: E1009 01:05:00.671499 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:00.672364 kubelet[1736]: I1009 01:05:00.672145 1736 apiserver.go:52] "Watching apiserver" Oct 9 01:05:00.693633 systemd[1]: Created slice kubepods-besteffort-podeca8ec5b_2d7d_4e41_8741_99c6b1a93a6e.slice - libcontainer container kubepods-besteffort-podeca8ec5b_2d7d_4e41_8741_99c6b1a93a6e.slice. Oct 9 01:05:00.707901 systemd[1]: Created slice kubepods-burstable-podf67b78b4_a71d_4888_97f4_5d5ede7e1e6b.slice - libcontainer container kubepods-burstable-podf67b78b4_a71d_4888_97f4_5d5ede7e1e6b.slice. Oct 9 01:05:00.710982 kubelet[1736]: I1009 01:05:00.710902 1736 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Oct 9 01:05:00.728359 kubelet[1736]: I1009 01:05:00.728272 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-cni-plugin\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:00.728359 kubelet[1736]: I1009 01:05:00.728351 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-cni\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:00.728359 kubelet[1736]: I1009 01:05:00.728391 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fpnrf\" (UniqueName: \"kubernetes.io/projected/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-kube-api-access-fpnrf\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:00.728682 kubelet[1736]: I1009 01:05:00.728421 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e-kube-proxy\") pod \"kube-proxy-sk8w4\" (UID: \"eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e\") " pod="kube-system/kube-proxy-sk8w4" Oct 9 01:05:00.728682 kubelet[1736]: I1009 01:05:00.728447 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e-xtables-lock\") pod \"kube-proxy-sk8w4\" (UID: \"eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e\") " pod="kube-system/kube-proxy-sk8w4" Oct 9 01:05:00.728682 kubelet[1736]: I1009 01:05:00.728472 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e-lib-modules\") pod \"kube-proxy-sk8w4\" (UID: \"eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e\") " pod="kube-system/kube-proxy-sk8w4" Oct 9 01:05:00.728682 kubelet[1736]: I1009 01:05:00.728534 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-86mds\" (UniqueName: \"kubernetes.io/projected/eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e-kube-api-access-86mds\") pod \"kube-proxy-sk8w4\" (UID: \"eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e\") " pod="kube-system/kube-proxy-sk8w4" Oct 9 01:05:00.728682 kubelet[1736]: I1009 01:05:00.728593 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-run\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:00.728852 kubelet[1736]: I1009 01:05:00.728630 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-xtables-lock\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:00.728852 kubelet[1736]: I1009 01:05:00.728659 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f67b78b4-a71d-4888-97f4-5d5ede7e1e6b-flannel-cfg\") pod \"kube-flannel-ds-qdnwl\" (UID: \"f67b78b4-a71d-4888-97f4-5d5ede7e1e6b\") " pod="kube-flannel/kube-flannel-ds-qdnwl" Oct 9 01:05:01.003676 kubelet[1736]: E1009 01:05:01.003068 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:01.005795 containerd[1475]: time="2024-10-09T01:05:01.005624782Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-sk8w4,Uid:eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e,Namespace:kube-system,Attempt:0,}" Oct 9 01:05:01.020454 kubelet[1736]: E1009 01:05:01.019717 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:01.020918 containerd[1475]: time="2024-10-09T01:05:01.020877798Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qdnwl,Uid:f67b78b4-a71d-4888-97f4-5d5ede7e1e6b,Namespace:kube-flannel,Attempt:0,}" Oct 9 01:05:01.672301 kubelet[1736]: E1009 01:05:01.672130 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:01.755357 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4049823077.mount: Deactivated successfully. Oct 9 01:05:01.778677 containerd[1475]: time="2024-10-09T01:05:01.778545966Z" 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\"}" Oct 9 01:05:01.781553 containerd[1475]: time="2024-10-09T01:05:01.781468659Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Oct 9 01:05:01.786691 containerd[1475]: time="2024-10-09T01:05:01.783908966Z" 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\"}" Oct 9 01:05:01.789966 containerd[1475]: time="2024-10-09T01:05:01.789824806Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Oct 9 01:05:01.800389 containerd[1475]: time="2024-10-09T01:05:01.800299667Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 9 01:05:01.807680 containerd[1475]: time="2024-10-09T01:05:01.807540245Z" 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\"}" Oct 9 01:05:01.809556 containerd[1475]: time="2024-10-09T01:05:01.809105345Z" 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 803.28595ms" Oct 9 01:05:01.812203 containerd[1475]: time="2024-10-09T01:05:01.812109805Z" 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 790.992403ms" Oct 9 01:05:02.160859 containerd[1475]: time="2024-10-09T01:05:02.160237845Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:05:02.160859 containerd[1475]: time="2024-10-09T01:05:02.160374868Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:05:02.160859 containerd[1475]: time="2024-10-09T01:05:02.160396797Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:02.170963 containerd[1475]: time="2024-10-09T01:05:02.166612003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:02.170963 containerd[1475]: time="2024-10-09T01:05:02.165236256Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:05:02.170963 containerd[1475]: time="2024-10-09T01:05:02.165341435Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:05:02.170963 containerd[1475]: time="2024-10-09T01:05:02.165369078Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:02.170963 containerd[1475]: time="2024-10-09T01:05:02.165523944Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:02.424448 systemd[1]: Started cri-containerd-3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0.scope - libcontainer container 3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0. Oct 9 01:05:02.450481 systemd[1]: Started cri-containerd-86f6c3d448764faf56e3aeafb390285b41109ff43d3df378605dd5c08a92c52a.scope - libcontainer container 86f6c3d448764faf56e3aeafb390285b41109ff43d3df378605dd5c08a92c52a. Oct 9 01:05:02.612714 containerd[1475]: time="2024-10-09T01:05:02.612633043Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-sk8w4,Uid:eca8ec5b-2d7d-4e41-8741-99c6b1a93a6e,Namespace:kube-system,Attempt:0,} returns sandbox id \"86f6c3d448764faf56e3aeafb390285b41109ff43d3df378605dd5c08a92c52a\"" Oct 9 01:05:02.619510 kubelet[1736]: E1009 01:05:02.619123 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:02.620249 containerd[1475]: time="2024-10-09T01:05:02.620177381Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qdnwl,Uid:f67b78b4-a71d-4888-97f4-5d5ede7e1e6b,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\"" Oct 9 01:05:02.622689 kubelet[1736]: E1009 01:05:02.622513 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:02.623563 containerd[1475]: time="2024-10-09T01:05:02.623485606Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.0\"" Oct 9 01:05:02.672823 kubelet[1736]: E1009 01:05:02.672659 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:03.673225 kubelet[1736]: E1009 01:05:03.673148 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:04.262804 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1865823241.mount: Deactivated successfully. Oct 9 01:05:04.673636 kubelet[1736]: E1009 01:05:04.673413 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:05.052424 containerd[1475]: time="2024-10-09T01:05:05.051169570Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:05.052424 containerd[1475]: time="2024-10-09T01:05:05.052269038Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.0: active requests=0, bytes read=30208881" Oct 9 01:05:05.055204 containerd[1475]: time="2024-10-09T01:05:05.055038172Z" level=info msg="ImageCreate event name:\"sha256:ad83b2ca7b09e6162f96f933eecded731cbebf049c78f941fd0ce560a86b6494\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:05.058578 containerd[1475]: time="2024-10-09T01:05:05.058453838Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:c727efb1c6f15a68060bf7f207f5c7a765355b7e3340c513e582ec819c5cd2fe\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:05.061064 containerd[1475]: time="2024-10-09T01:05:05.060895159Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.0\" with image id \"sha256:ad83b2ca7b09e6162f96f933eecded731cbebf049c78f941fd0ce560a86b6494\", repo tag \"registry.k8s.io/kube-proxy:v1.31.0\", repo digest \"registry.k8s.io/kube-proxy@sha256:c727efb1c6f15a68060bf7f207f5c7a765355b7e3340c513e582ec819c5cd2fe\", size \"30207900\" in 2.437115682s" Oct 9 01:05:05.061064 containerd[1475]: time="2024-10-09T01:05:05.060972195Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.0\" returns image reference \"sha256:ad83b2ca7b09e6162f96f933eecded731cbebf049c78f941fd0ce560a86b6494\"" Oct 9 01:05:05.064618 containerd[1475]: time="2024-10-09T01:05:05.064292370Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Oct 9 01:05:05.065865 containerd[1475]: time="2024-10-09T01:05:05.065821491Z" level=info msg="CreateContainer within sandbox \"86f6c3d448764faf56e3aeafb390285b41109ff43d3df378605dd5c08a92c52a\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 9 01:05:05.100612 containerd[1475]: time="2024-10-09T01:05:05.100481633Z" level=info msg="CreateContainer within sandbox \"86f6c3d448764faf56e3aeafb390285b41109ff43d3df378605dd5c08a92c52a\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e5cc2ac4509b13f6574483fc14b7ddab954226a93096bb85258f5d99dead125c\"" Oct 9 01:05:05.102520 containerd[1475]: time="2024-10-09T01:05:05.102457300Z" level=info msg="StartContainer for \"e5cc2ac4509b13f6574483fc14b7ddab954226a93096bb85258f5d99dead125c\"" Oct 9 01:05:05.163455 systemd[1]: Started cri-containerd-e5cc2ac4509b13f6574483fc14b7ddab954226a93096bb85258f5d99dead125c.scope - libcontainer container e5cc2ac4509b13f6574483fc14b7ddab954226a93096bb85258f5d99dead125c. Oct 9 01:05:05.221435 containerd[1475]: time="2024-10-09T01:05:05.221356188Z" level=info msg="StartContainer for \"e5cc2ac4509b13f6574483fc14b7ddab954226a93096bb85258f5d99dead125c\" returns successfully" Oct 9 01:05:05.676104 kubelet[1736]: E1009 01:05:05.675893 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:05.878001 kubelet[1736]: E1009 01:05:05.876682 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:06.676686 kubelet[1736]: E1009 01:05:06.676587 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:06.878307 kubelet[1736]: E1009 01:05:06.878235 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:07.187395 systemd-resolved[1332]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Oct 9 01:05:07.230144 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3418719107.mount: Deactivated successfully. Oct 9 01:05:07.291819 containerd[1475]: time="2024-10-09T01:05:07.291740917Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:07.293867 containerd[1475]: time="2024-10-09T01:05:07.293795902Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Oct 9 01:05:07.295543 containerd[1475]: time="2024-10-09T01:05:07.295481076Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:07.300502 containerd[1475]: time="2024-10-09T01:05:07.300444733Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:07.301573 containerd[1475]: time="2024-10-09T01:05:07.301506906Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.237158704s" Oct 9 01:05:07.301817 containerd[1475]: time="2024-10-09T01:05:07.301788308Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Oct 9 01:05:07.306246 containerd[1475]: time="2024-10-09T01:05:07.306023790Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 9 01:05:07.324863 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1998529031.mount: Deactivated successfully. Oct 9 01:05:07.332679 containerd[1475]: time="2024-10-09T01:05:07.332577612Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0\"" Oct 9 01:05:07.333829 containerd[1475]: time="2024-10-09T01:05:07.333780123Z" level=info msg="StartContainer for \"970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0\"" Oct 9 01:05:07.384199 systemd[1]: Started cri-containerd-970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0.scope - libcontainer container 970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0. Oct 9 01:05:07.420910 systemd[1]: cri-containerd-970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0.scope: Deactivated successfully. Oct 9 01:05:07.427713 containerd[1475]: time="2024-10-09T01:05:07.427617090Z" level=info msg="StartContainer for \"970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0\" returns successfully" Oct 9 01:05:07.541305 containerd[1475]: time="2024-10-09T01:05:07.541104019Z" level=info msg="shim disconnected" id=970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0 namespace=k8s.io Oct 9 01:05:07.541576 containerd[1475]: time="2024-10-09T01:05:07.541552830Z" level=warning msg="cleaning up after shim disconnected" id=970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0 namespace=k8s.io Oct 9 01:05:07.541637 containerd[1475]: time="2024-10-09T01:05:07.541623181Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 9 01:05:07.677120 kubelet[1736]: E1009 01:05:07.677033 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:07.882965 kubelet[1736]: E1009 01:05:07.882777 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:07.884753 containerd[1475]: time="2024-10-09T01:05:07.884384964Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Oct 9 01:05:07.902579 kubelet[1736]: I1009 01:05:07.901877 1736 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-sk8w4" podStartSLOduration=7.461665234 podStartE2EDuration="9.901851648s" podCreationTimestamp="2024-10-09 01:04:58 +0000 UTC" firstStartedPulling="2024-10-09 01:05:02.622433525 +0000 UTC m=+4.613562056" lastFinishedPulling="2024-10-09 01:05:05.062619961 +0000 UTC m=+7.053748470" observedRunningTime="2024-10-09 01:05:05.89283284 +0000 UTC m=+7.883961466" watchObservedRunningTime="2024-10-09 01:05:07.901851648 +0000 UTC m=+9.892980202" Oct 9 01:05:08.092222 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-970846e6cdcec55805af852d1536481e5cbb1b9951a177d54d36cb9f08af68f0-rootfs.mount: Deactivated successfully. Oct 9 01:05:08.678481 kubelet[1736]: E1009 01:05:08.678353 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:09.679205 kubelet[1736]: E1009 01:05:09.678941 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:09.834448 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount452867832.mount: Deactivated successfully. Oct 9 01:05:10.679691 kubelet[1736]: E1009 01:05:10.679627 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:10.780315 containerd[1475]: time="2024-10-09T01:05:10.779021387Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:10.780315 containerd[1475]: time="2024-10-09T01:05:10.780252121Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Oct 9 01:05:10.781033 containerd[1475]: time="2024-10-09T01:05:10.781006069Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:10.785470 containerd[1475]: time="2024-10-09T01:05:10.785409395Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:10.787732 containerd[1475]: time="2024-10-09T01:05:10.787670000Z" 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.903240622s" Oct 9 01:05:10.788042 containerd[1475]: time="2024-10-09T01:05:10.788014274Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Oct 9 01:05:10.790956 containerd[1475]: time="2024-10-09T01:05:10.790883867Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 9 01:05:10.808031 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3965459429.mount: Deactivated successfully. Oct 9 01:05:10.813502 containerd[1475]: time="2024-10-09T01:05:10.813441235Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31\"" Oct 9 01:05:10.814604 containerd[1475]: time="2024-10-09T01:05:10.814499020Z" level=info msg="StartContainer for \"e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31\"" Oct 9 01:05:10.880297 systemd[1]: Started cri-containerd-e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31.scope - libcontainer container e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31. Oct 9 01:05:10.919409 systemd[1]: cri-containerd-e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31.scope: Deactivated successfully. Oct 9 01:05:10.922002 containerd[1475]: time="2024-10-09T01:05:10.921749820Z" level=info msg="StartContainer for \"e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31\" returns successfully" Oct 9 01:05:10.930886 kubelet[1736]: I1009 01:05:10.930678 1736 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Oct 9 01:05:11.060069 containerd[1475]: time="2024-10-09T01:05:11.059986288Z" level=info msg="shim disconnected" id=e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31 namespace=k8s.io Oct 9 01:05:11.060069 containerd[1475]: time="2024-10-09T01:05:11.060053862Z" level=warning msg="cleaning up after shim disconnected" id=e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31 namespace=k8s.io Oct 9 01:05:11.060069 containerd[1475]: time="2024-10-09T01:05:11.060063385Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 9 01:05:11.680865 kubelet[1736]: E1009 01:05:11.680542 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:11.804547 systemd[1]: run-containerd-runc-k8s.io-e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31-runc.k1PEMe.mount: Deactivated successfully. Oct 9 01:05:11.804727 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e24a990f9d49c071fc3cc420bf9faf9a76e11467e2cd21355fbde40b0e758e31-rootfs.mount: Deactivated successfully. Oct 9 01:05:11.902175 kubelet[1736]: E1009 01:05:11.901559 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:11.905320 containerd[1475]: time="2024-10-09T01:05:11.904955148Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 9 01:05:11.941172 containerd[1475]: time="2024-10-09T01:05:11.940902402Z" level=info msg="CreateContainer within sandbox \"3c2395f93f772272fe278262f0e8d1f40b191250f003928f15351725c73af1d0\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"f957e19891739e32b7af15601308a43eb725ce04473445f07564089cc0b130ae\"" Oct 9 01:05:11.943988 containerd[1475]: time="2024-10-09T01:05:11.942706772Z" level=info msg="StartContainer for \"f957e19891739e32b7af15601308a43eb725ce04473445f07564089cc0b130ae\"" Oct 9 01:05:11.991210 systemd[1]: Started cri-containerd-f957e19891739e32b7af15601308a43eb725ce04473445f07564089cc0b130ae.scope - libcontainer container f957e19891739e32b7af15601308a43eb725ce04473445f07564089cc0b130ae. Oct 9 01:05:12.040518 containerd[1475]: time="2024-10-09T01:05:12.040342276Z" level=info msg="StartContainer for \"f957e19891739e32b7af15601308a43eb725ce04473445f07564089cc0b130ae\" returns successfully" Oct 9 01:05:12.681920 kubelet[1736]: E1009 01:05:12.681826 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:12.905571 kubelet[1736]: E1009 01:05:12.905501 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:13.115825 systemd-networkd[1369]: flannel.1: Link UP Oct 9 01:05:13.115839 systemd-networkd[1369]: flannel.1: Gained carrier Oct 9 01:05:13.682372 kubelet[1736]: E1009 01:05:13.682290 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:13.908114 kubelet[1736]: E1009 01:05:13.908062 1736 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Oct 9 01:05:14.163411 systemd-networkd[1369]: flannel.1: Gained IPv6LL Oct 9 01:05:14.682770 kubelet[1736]: E1009 01:05:14.682644 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:15.490540 kubelet[1736]: I1009 01:05:15.490423 1736 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-qdnwl" podStartSLOduration=9.324863209 podStartE2EDuration="17.490375315s" podCreationTimestamp="2024-10-09 01:04:58 +0000 UTC" firstStartedPulling="2024-10-09 01:05:02.623500775 +0000 UTC m=+4.614629290" lastFinishedPulling="2024-10-09 01:05:10.789012883 +0000 UTC m=+12.780141396" observedRunningTime="2024-10-09 01:05:12.921897498 +0000 UTC m=+14.913026031" watchObservedRunningTime="2024-10-09 01:05:15.490375315 +0000 UTC m=+17.481503842" Oct 9 01:05:15.498549 systemd[1]: Created slice kubepods-besteffort-poda50b69b3_2726_482b_8880_3b39767f880a.slice - libcontainer container kubepods-besteffort-poda50b69b3_2726_482b_8880_3b39767f880a.slice. Oct 9 01:05:15.581194 kubelet[1736]: I1009 01:05:15.581073 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gdld6\" (UniqueName: \"kubernetes.io/projected/a50b69b3-2726-482b-8880-3b39767f880a-kube-api-access-gdld6\") pod \"nginx-deployment-8587fbcb89-s8c8l\" (UID: \"a50b69b3-2726-482b-8880-3b39767f880a\") " pod="default/nginx-deployment-8587fbcb89-s8c8l" Oct 9 01:05:15.683807 kubelet[1736]: E1009 01:05:15.683731 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:15.802897 containerd[1475]: time="2024-10-09T01:05:15.802687728Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-s8c8l,Uid:a50b69b3-2726-482b-8880-3b39767f880a,Namespace:default,Attempt:0,}" Oct 9 01:05:15.846016 systemd-networkd[1369]: cni0: Link UP Oct 9 01:05:15.846028 systemd-networkd[1369]: cni0: Gained carrier Oct 9 01:05:15.847286 systemd-networkd[1369]: cni0: Lost carrier Oct 9 01:05:15.861600 systemd-networkd[1369]: veth5491da21: Link UP Oct 9 01:05:15.863958 kernel: cni0: port 1(veth5491da21) entered blocking state Oct 9 01:05:15.864066 kernel: cni0: port 1(veth5491da21) entered disabled state Oct 9 01:05:15.867894 kernel: veth5491da21: entered allmulticast mode Oct 9 01:05:15.868025 kernel: veth5491da21: entered promiscuous mode Oct 9 01:05:15.870905 kernel: cni0: port 1(veth5491da21) entered blocking state Oct 9 01:05:15.871055 kernel: cni0: port 1(veth5491da21) entered forwarding state Oct 9 01:05:15.871074 kernel: cni0: port 1(veth5491da21) entered disabled state Oct 9 01:05:15.885004 kernel: cni0: port 1(veth5491da21) entered blocking state Oct 9 01:05:15.885168 kernel: cni0: port 1(veth5491da21) entered forwarding state Oct 9 01:05:15.885015 systemd-networkd[1369]: veth5491da21: Gained carrier Oct 9 01:05:15.887433 systemd-networkd[1369]: cni0: Gained carrier Oct 9 01:05:15.898436 containerd[1475]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} Oct 9 01:05:15.898436 containerd[1475]: delegateAdd: netconf sent to delegate plugin: Oct 9 01:05:15.922356 containerd[1475]: {"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-10-09T01:05:15.922020510Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:05:15.922678 containerd[1475]: time="2024-10-09T01:05:15.922295066Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:05:15.922678 containerd[1475]: time="2024-10-09T01:05:15.922314493Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:15.923627 containerd[1475]: time="2024-10-09T01:05:15.923420280Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:15.954288 systemd[1]: Started cri-containerd-e6cfd5878b8d5cd13045726650dcfa1ad1acf5501943a8d84929c44ff831d5be.scope - libcontainer container e6cfd5878b8d5cd13045726650dcfa1ad1acf5501943a8d84929c44ff831d5be. Oct 9 01:05:16.015961 containerd[1475]: time="2024-10-09T01:05:16.015826964Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-s8c8l,Uid:a50b69b3-2726-482b-8880-3b39767f880a,Namespace:default,Attempt:0,} returns sandbox id \"e6cfd5878b8d5cd13045726650dcfa1ad1acf5501943a8d84929c44ff831d5be\"" Oct 9 01:05:16.018668 containerd[1475]: time="2024-10-09T01:05:16.018627057Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 9 01:05:16.021583 systemd-resolved[1332]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2. Oct 9 01:05:16.684992 kubelet[1736]: E1009 01:05:16.684854 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:17.685186 kubelet[1736]: E1009 01:05:17.685123 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:17.753096 systemd-networkd[1369]: cni0: Gained IPv6LL Oct 9 01:05:17.753798 systemd-networkd[1369]: veth5491da21: Gained IPv6LL Oct 9 01:05:18.670536 kubelet[1736]: E1009 01:05:18.670452 1736 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:18.687129 kubelet[1736]: E1009 01:05:18.687010 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:19.688084 kubelet[1736]: E1009 01:05:19.688014 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:19.923782 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2344339630.mount: Deactivated successfully. Oct 9 01:05:20.688460 kubelet[1736]: E1009 01:05:20.688366 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:21.688841 kubelet[1736]: E1009 01:05:21.688771 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:21.919990 containerd[1475]: time="2024-10-09T01:05:21.919431089Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:21.921019 containerd[1475]: time="2024-10-09T01:05:21.920775858Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71017677" Oct 9 01:05:21.922985 containerd[1475]: time="2024-10-09T01:05:21.921839337Z" level=info msg="ImageCreate event name:\"sha256:6ebdd2db239fe67ea1cc7d618e7d7bba7816de19bb50a6eeb1a14823ed6c5a4f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:21.925162 containerd[1475]: time="2024-10-09T01:05:21.925082860Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:21.926966 containerd[1475]: time="2024-10-09T01:05:21.926226316Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:6ebdd2db239fe67ea1cc7d618e7d7bba7816de19bb50a6eeb1a14823ed6c5a4f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\", size \"71017555\" in 5.907554967s" Oct 9 01:05:21.926966 containerd[1475]: time="2024-10-09T01:05:21.926271594Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:6ebdd2db239fe67ea1cc7d618e7d7bba7816de19bb50a6eeb1a14823ed6c5a4f\"" Oct 9 01:05:21.930788 containerd[1475]: time="2024-10-09T01:05:21.930726535Z" level=info msg="CreateContainer within sandbox \"e6cfd5878b8d5cd13045726650dcfa1ad1acf5501943a8d84929c44ff831d5be\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 9 01:05:21.952356 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4273402478.mount: Deactivated successfully. Oct 9 01:05:21.957222 containerd[1475]: time="2024-10-09T01:05:21.957010207Z" level=info msg="CreateContainer within sandbox \"e6cfd5878b8d5cd13045726650dcfa1ad1acf5501943a8d84929c44ff831d5be\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b1290069c13759961ba1745f6cce2c84618fedbefd880c7c707ea760b4e12721\"" Oct 9 01:05:21.959883 containerd[1475]: time="2024-10-09T01:05:21.958452274Z" level=info msg="StartContainer for \"b1290069c13759961ba1745f6cce2c84618fedbefd880c7c707ea760b4e12721\"" Oct 9 01:05:22.009251 systemd[1]: Started cri-containerd-b1290069c13759961ba1745f6cce2c84618fedbefd880c7c707ea760b4e12721.scope - libcontainer container b1290069c13759961ba1745f6cce2c84618fedbefd880c7c707ea760b4e12721. Oct 9 01:05:22.062851 containerd[1475]: time="2024-10-09T01:05:22.062559998Z" level=info msg="StartContainer for \"b1290069c13759961ba1745f6cce2c84618fedbefd880c7c707ea760b4e12721\" returns successfully" Oct 9 01:05:22.689143 kubelet[1736]: E1009 01:05:22.689061 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:22.967572 kubelet[1736]: I1009 01:05:22.967204 1736 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-s8c8l" podStartSLOduration=2.056463304 podStartE2EDuration="7.967179506s" podCreationTimestamp="2024-10-09 01:05:15 +0000 UTC" firstStartedPulling="2024-10-09 01:05:16.018004064 +0000 UTC m=+18.009132569" lastFinishedPulling="2024-10-09 01:05:21.92872025 +0000 UTC m=+23.919848771" observedRunningTime="2024-10-09 01:05:22.96697251 +0000 UTC m=+24.958101045" watchObservedRunningTime="2024-10-09 01:05:22.967179506 +0000 UTC m=+24.958308041" Oct 9 01:05:23.689407 kubelet[1736]: E1009 01:05:23.689333 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:24.689545 kubelet[1736]: E1009 01:05:24.689475 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:25.689887 kubelet[1736]: E1009 01:05:25.689797 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:26.691085 kubelet[1736]: E1009 01:05:26.691016 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:27.691999 kubelet[1736]: E1009 01:05:27.691903 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:28.377291 systemd[1]: Created slice kubepods-besteffort-pod311e95de_575b_4b1e_919f_5d4a9fb237f8.slice - libcontainer container kubepods-besteffort-pod311e95de_575b_4b1e_919f_5d4a9fb237f8.slice. Oct 9 01:05:28.481613 kubelet[1736]: I1009 01:05:28.481483 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-l99pl\" (UniqueName: \"kubernetes.io/projected/311e95de-575b-4b1e-919f-5d4a9fb237f8-kube-api-access-l99pl\") pod \"nfs-server-provisioner-0\" (UID: \"311e95de-575b-4b1e-919f-5d4a9fb237f8\") " pod="default/nfs-server-provisioner-0" Oct 9 01:05:28.481613 kubelet[1736]: I1009 01:05:28.481581 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/311e95de-575b-4b1e-919f-5d4a9fb237f8-data\") pod \"nfs-server-provisioner-0\" (UID: \"311e95de-575b-4b1e-919f-5d4a9fb237f8\") " pod="default/nfs-server-provisioner-0" Oct 9 01:05:28.684045 containerd[1475]: time="2024-10-09T01:05:28.683560277Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:311e95de-575b-4b1e-919f-5d4a9fb237f8,Namespace:default,Attempt:0,}" Oct 9 01:05:28.692489 kubelet[1736]: E1009 01:05:28.692418 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:28.723084 kernel: cni0: port 2(veth9ba37c91) entered blocking state Oct 9 01:05:28.723269 kernel: cni0: port 2(veth9ba37c91) entered disabled state Oct 9 01:05:28.724390 systemd-networkd[1369]: veth9ba37c91: Link UP Oct 9 01:05:28.725969 kernel: veth9ba37c91: entered allmulticast mode Oct 9 01:05:28.727983 kernel: veth9ba37c91: entered promiscuous mode Oct 9 01:05:28.739313 kernel: cni0: port 2(veth9ba37c91) entered blocking state Oct 9 01:05:28.739414 kernel: cni0: port 2(veth9ba37c91) entered forwarding state Oct 9 01:05:28.739535 systemd-networkd[1369]: veth9ba37c91: Gained carrier Oct 9 01:05:28.743034 containerd[1475]: 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)(0xc00001c938), "name":"cbr0", "type":"bridge"} Oct 9 01:05:28.743034 containerd[1475]: delegateAdd: netconf sent to delegate plugin: Oct 9 01:05:28.771434 containerd[1475]: {"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-10-09T01:05:28.770369154Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:05:28.771434 containerd[1475]: time="2024-10-09T01:05:28.770491594Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:05:28.771434 containerd[1475]: time="2024-10-09T01:05:28.770516535Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:28.771757 containerd[1475]: time="2024-10-09T01:05:28.771519457Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:28.810601 systemd[1]: Started cri-containerd-24e9dc30e56e563666c78723bf68763983146154c18abcd6cbb192bf385f90d4.scope - libcontainer container 24e9dc30e56e563666c78723bf68763983146154c18abcd6cbb192bf385f90d4. Oct 9 01:05:28.870775 containerd[1475]: time="2024-10-09T01:05:28.870632412Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:311e95de-575b-4b1e-919f-5d4a9fb237f8,Namespace:default,Attempt:0,} returns sandbox id \"24e9dc30e56e563666c78723bf68763983146154c18abcd6cbb192bf385f90d4\"" Oct 9 01:05:28.875110 containerd[1475]: time="2024-10-09T01:05:28.874867553Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 9 01:05:29.692946 kubelet[1736]: E1009 01:05:29.692872 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:30.099589 systemd-networkd[1369]: veth9ba37c91: Gained IPv6LL Oct 9 01:05:30.693259 kubelet[1736]: E1009 01:05:30.693204 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:31.472695 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount845241059.mount: Deactivated successfully. Oct 9 01:05:31.693616 kubelet[1736]: E1009 01:05:31.693399 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:32.693852 kubelet[1736]: E1009 01:05:32.693780 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:33.694861 kubelet[1736]: E1009 01:05:33.694778 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:33.912103 containerd[1475]: time="2024-10-09T01:05:33.910999493Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:33.913658 containerd[1475]: time="2024-10-09T01:05:33.913576772Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Oct 9 01:05:33.915241 containerd[1475]: time="2024-10-09T01:05:33.915106997Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:33.919698 containerd[1475]: time="2024-10-09T01:05:33.919614509Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:33.921147 containerd[1475]: time="2024-10-09T01:05:33.921007458Z" 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 5.046094483s" Oct 9 01:05:33.921147 containerd[1475]: time="2024-10-09T01:05:33.921046956Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Oct 9 01:05:33.924680 containerd[1475]: time="2024-10-09T01:05:33.924635319Z" level=info msg="CreateContainer within sandbox \"24e9dc30e56e563666c78723bf68763983146154c18abcd6cbb192bf385f90d4\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 9 01:05:33.940463 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3800137050.mount: Deactivated successfully. Oct 9 01:05:33.946298 containerd[1475]: time="2024-10-09T01:05:33.945878659Z" level=info msg="CreateContainer within sandbox \"24e9dc30e56e563666c78723bf68763983146154c18abcd6cbb192bf385f90d4\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b\"" Oct 9 01:05:33.947693 containerd[1475]: time="2024-10-09T01:05:33.946635121Z" level=info msg="StartContainer for \"0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b\"" Oct 9 01:05:33.993437 systemd[1]: run-containerd-runc-k8s.io-0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b-runc.ejW5Bw.mount: Deactivated successfully. Oct 9 01:05:34.010463 systemd[1]: Started cri-containerd-0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b.scope - libcontainer container 0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b. Oct 9 01:05:34.050265 containerd[1475]: time="2024-10-09T01:05:34.049589012Z" level=info msg="StartContainer for \"0a9517eeb11d2ef0082e63ce9615215d6ad3d6ccec0db4ef8a9f9e5e6173112b\" returns successfully" Oct 9 01:05:34.695285 kubelet[1736]: E1009 01:05:34.695223 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:35.022475 kubelet[1736]: I1009 01:05:35.022376 1736 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.9735999180000001 podStartE2EDuration="7.022352527s" podCreationTimestamp="2024-10-09 01:05:28 +0000 UTC" firstStartedPulling="2024-10-09 01:05:28.874280763 +0000 UTC m=+30.865409283" lastFinishedPulling="2024-10-09 01:05:33.92303338 +0000 UTC m=+35.914161892" observedRunningTime="2024-10-09 01:05:35.021651677 +0000 UTC m=+37.012780207" watchObservedRunningTime="2024-10-09 01:05:35.022352527 +0000 UTC m=+37.013481085" Oct 9 01:05:35.696019 kubelet[1736]: E1009 01:05:35.695960 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:36.696666 kubelet[1736]: E1009 01:05:36.696580 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:37.697074 kubelet[1736]: E1009 01:05:37.697002 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:38.670367 kubelet[1736]: E1009 01:05:38.670299 1736 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:38.697604 kubelet[1736]: E1009 01:05:38.697516 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:38.907016 update_engine[1457]: I20241009 01:05:38.906203 1457 update_attempter.cc:509] Updating boot flags... Oct 9 01:05:38.957964 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2750) Oct 9 01:05:39.040128 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2753) Oct 9 01:05:39.697753 kubelet[1736]: E1009 01:05:39.697668 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:40.698324 kubelet[1736]: E1009 01:05:40.698240 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:41.698525 kubelet[1736]: E1009 01:05:41.698433 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:42.699547 kubelet[1736]: E1009 01:05:42.699477 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:43.700878 kubelet[1736]: E1009 01:05:43.700729 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:44.093912 systemd[1]: Created slice kubepods-besteffort-pod8bf2bd81_34fc_4f7d_9f96_a940fe738db3.slice - libcontainer container kubepods-besteffort-pod8bf2bd81_34fc_4f7d_9f96_a940fe738db3.slice. Oct 9 01:05:44.187407 kubelet[1736]: I1009 01:05:44.187218 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-84b86a16-6d45-4a19-9270-32a6c156bb19\" (UniqueName: \"kubernetes.io/nfs/8bf2bd81-34fc-4f7d-9f96-a940fe738db3-pvc-84b86a16-6d45-4a19-9270-32a6c156bb19\") pod \"test-pod-1\" (UID: \"8bf2bd81-34fc-4f7d-9f96-a940fe738db3\") " pod="default/test-pod-1" Oct 9 01:05:44.187407 kubelet[1736]: I1009 01:05:44.187302 1736 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5fm77\" (UniqueName: \"kubernetes.io/projected/8bf2bd81-34fc-4f7d-9f96-a940fe738db3-kube-api-access-5fm77\") pod \"test-pod-1\" (UID: \"8bf2bd81-34fc-4f7d-9f96-a940fe738db3\") " pod="default/test-pod-1" Oct 9 01:05:44.334253 kernel: FS-Cache: Loaded Oct 9 01:05:44.452199 kernel: RPC: Registered named UNIX socket transport module. Oct 9 01:05:44.452407 kernel: RPC: Registered udp transport module. Oct 9 01:05:44.453256 kernel: RPC: Registered tcp transport module. Oct 9 01:05:44.453377 kernel: RPC: Registered tcp-with-tls transport module. Oct 9 01:05:44.454376 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 9 01:05:44.701776 kubelet[1736]: E1009 01:05:44.701692 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:44.806195 kernel: NFS: Registering the id_resolver key type Oct 9 01:05:44.808060 kernel: Key type id_resolver registered Oct 9 01:05:44.808164 kernel: Key type id_legacy registered Oct 9 01:05:44.864980 nfsidmap[2795]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-f-ecc45184e6' Oct 9 01:05:44.873237 nfsidmap[2796]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-f-ecc45184e6' Oct 9 01:05:44.999551 containerd[1475]: time="2024-10-09T01:05:44.999450620Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8bf2bd81-34fc-4f7d-9f96-a940fe738db3,Namespace:default,Attempt:0,}" Oct 9 01:05:45.049998 kernel: cni0: port 3(vethb8455e4f) entered blocking state Oct 9 01:05:45.050146 kernel: cni0: port 3(vethb8455e4f) entered disabled state Oct 9 01:05:45.049455 systemd-networkd[1369]: vethb8455e4f: Link UP Oct 9 01:05:45.054642 kernel: vethb8455e4f: entered allmulticast mode Oct 9 01:05:45.054772 kernel: vethb8455e4f: entered promiscuous mode Oct 9 01:05:45.070762 kernel: cni0: port 3(vethb8455e4f) entered blocking state Oct 9 01:05:45.070983 kernel: cni0: port 3(vethb8455e4f) entered forwarding state Oct 9 01:05:45.072557 systemd-networkd[1369]: vethb8455e4f: Gained carrier Oct 9 01:05:45.076565 containerd[1475]: 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)(0xc000106628), "name":"cbr0", "type":"bridge"} Oct 9 01:05:45.076565 containerd[1475]: delegateAdd: netconf sent to delegate plugin: Oct 9 01:05:45.153268 containerd[1475]: {"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-10-09T01:05:45.153046398Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:05:45.153268 containerd[1475]: time="2024-10-09T01:05:45.153199521Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:05:45.153577 containerd[1475]: time="2024-10-09T01:05:45.153226548Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:45.153577 containerd[1475]: time="2024-10-09T01:05:45.153465537Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:05:45.220277 systemd[1]: Started cri-containerd-dc0fefce3b842d1210dd7f9f924cb6413d4c7b3d07841073a87b1682c4ae7ccf.scope - libcontainer container dc0fefce3b842d1210dd7f9f924cb6413d4c7b3d07841073a87b1682c4ae7ccf. Oct 9 01:05:45.292987 containerd[1475]: time="2024-10-09T01:05:45.292828852Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8bf2bd81-34fc-4f7d-9f96-a940fe738db3,Namespace:default,Attempt:0,} returns sandbox id \"dc0fefce3b842d1210dd7f9f924cb6413d4c7b3d07841073a87b1682c4ae7ccf\"" Oct 9 01:05:45.303382 containerd[1475]: time="2024-10-09T01:05:45.301573633Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 9 01:05:45.649958 containerd[1475]: time="2024-10-09T01:05:45.648142916Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 9 01:05:45.649958 containerd[1475]: time="2024-10-09T01:05:45.649240635Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 9 01:05:45.653364 containerd[1475]: time="2024-10-09T01:05:45.653318085Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:6ebdd2db239fe67ea1cc7d618e7d7bba7816de19bb50a6eeb1a14823ed6c5a4f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e833b9da37130a69c1c2ed7b8c61e1f861f75e4804436b3cf81d8463a4e6e5f1\", size \"71017555\" in 351.674421ms" Oct 9 01:05:45.653608 containerd[1475]: time="2024-10-09T01:05:45.653588737Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:6ebdd2db239fe67ea1cc7d618e7d7bba7816de19bb50a6eeb1a14823ed6c5a4f\"" Oct 9 01:05:45.657648 containerd[1475]: time="2024-10-09T01:05:45.657557818Z" level=info msg="CreateContainer within sandbox \"dc0fefce3b842d1210dd7f9f924cb6413d4c7b3d07841073a87b1682c4ae7ccf\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 9 01:05:45.684121 containerd[1475]: time="2024-10-09T01:05:45.684039893Z" level=info msg="CreateContainer within sandbox \"dc0fefce3b842d1210dd7f9f924cb6413d4c7b3d07841073a87b1682c4ae7ccf\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"9a5241e1aa0aaf76212e6e197a934cde0bc93489041d4d894cd9ab4779375215\"" Oct 9 01:05:45.685406 containerd[1475]: time="2024-10-09T01:05:45.685354038Z" level=info msg="StartContainer for \"9a5241e1aa0aaf76212e6e197a934cde0bc93489041d4d894cd9ab4779375215\"" Oct 9 01:05:45.702694 kubelet[1736]: E1009 01:05:45.702482 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:45.739381 systemd[1]: Started cri-containerd-9a5241e1aa0aaf76212e6e197a934cde0bc93489041d4d894cd9ab4779375215.scope - libcontainer container 9a5241e1aa0aaf76212e6e197a934cde0bc93489041d4d894cd9ab4779375215. Oct 9 01:05:45.792917 containerd[1475]: time="2024-10-09T01:05:45.792841789Z" level=info msg="StartContainer for \"9a5241e1aa0aaf76212e6e197a934cde0bc93489041d4d894cd9ab4779375215\" returns successfully" Oct 9 01:05:46.675232 systemd-networkd[1369]: vethb8455e4f: Gained IPv6LL Oct 9 01:05:46.703522 kubelet[1736]: E1009 01:05:46.703443 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:47.704008 kubelet[1736]: E1009 01:05:47.703906 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:48.704920 kubelet[1736]: E1009 01:05:48.704843 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:49.705146 kubelet[1736]: E1009 01:05:49.705072 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:50.705699 kubelet[1736]: E1009 01:05:50.705639 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 9 01:05:51.706491 kubelet[1736]: E1009 01:05:51.706408 1736 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"