Aug 5 22:20:50.946941 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT_DYNAMIC Mon Aug 5 20:36:27 -00 2024 Aug 5 22:20:50.946972 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 5 22:20:50.946987 kernel: BIOS-provided physical RAM map: Aug 5 22:20:50.946996 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Aug 5 22:20:50.947004 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Aug 5 22:20:50.947013 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Aug 5 22:20:50.947024 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable Aug 5 22:20:50.947033 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved Aug 5 22:20:50.947042 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Aug 5 22:20:50.947053 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Aug 5 22:20:50.947062 kernel: NX (Execute Disable) protection: active Aug 5 22:20:50.947071 kernel: APIC: Static calls initialized Aug 5 22:20:50.947080 kernel: SMBIOS 2.8 present. Aug 5 22:20:50.947089 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014 Aug 5 22:20:50.947100 kernel: Hypervisor detected: KVM Aug 5 22:20:50.947112 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Aug 5 22:20:50.947121 kernel: kvm-clock: using sched offset of 6101855957 cycles Aug 5 22:20:50.947131 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Aug 5 22:20:50.947141 kernel: tsc: Detected 1996.249 MHz processor Aug 5 22:20:50.947151 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Aug 5 22:20:50.947162 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Aug 5 22:20:50.947171 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000 Aug 5 22:20:50.947182 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Aug 5 22:20:50.947193 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Aug 5 22:20:50.947630 kernel: ACPI: Early table checksum verification disabled Aug 5 22:20:50.947641 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS ) Aug 5 22:20:50.947652 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:20:50.947662 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:20:50.947672 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:20:50.947681 kernel: ACPI: FACS 0x000000007FFE0000 000040 Aug 5 22:20:50.947691 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:20:50.947701 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 22:20:50.947711 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f] Aug 5 22:20:50.947725 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b] Aug 5 22:20:50.947735 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Aug 5 22:20:50.947745 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f] Aug 5 22:20:50.947755 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847] Aug 5 22:20:50.947765 kernel: No NUMA configuration found Aug 5 22:20:50.947775 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff] Aug 5 22:20:50.947785 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff] Aug 5 22:20:50.947799 kernel: Zone ranges: Aug 5 22:20:50.947812 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Aug 5 22:20:50.947822 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdcfff] Aug 5 22:20:50.947833 kernel: Normal empty Aug 5 22:20:50.947844 kernel: Movable zone start for each node Aug 5 22:20:50.947854 kernel: Early memory node ranges Aug 5 22:20:50.947864 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Aug 5 22:20:50.947877 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdcfff] Aug 5 22:20:50.947888 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff] Aug 5 22:20:50.947898 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Aug 5 22:20:50.947908 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Aug 5 22:20:50.947942 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges Aug 5 22:20:50.947952 kernel: ACPI: PM-Timer IO Port: 0x608 Aug 5 22:20:50.947963 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Aug 5 22:20:50.947973 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Aug 5 22:20:50.947984 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Aug 5 22:20:50.947995 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Aug 5 22:20:50.948011 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Aug 5 22:20:50.948022 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Aug 5 22:20:50.948032 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Aug 5 22:20:50.948043 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Aug 5 22:20:50.948053 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Aug 5 22:20:50.948064 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Aug 5 22:20:50.948074 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Aug 5 22:20:50.948085 kernel: Booting paravirtualized kernel on KVM Aug 5 22:20:50.948096 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Aug 5 22:20:50.948108 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Aug 5 22:20:50.948119 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Aug 5 22:20:50.948130 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Aug 5 22:20:50.948140 kernel: pcpu-alloc: [0] 0 1 Aug 5 22:20:50.948150 kernel: kvm-guest: PV spinlocks disabled, no host support Aug 5 22:20:50.948162 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 5 22:20:50.948173 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 22:20:50.948184 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 22:20:50.948196 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Aug 5 22:20:50.948207 kernel: Fallback order for Node 0: 0 Aug 5 22:20:50.948217 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515805 Aug 5 22:20:50.948228 kernel: Policy zone: DMA32 Aug 5 22:20:50.948238 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 22:20:50.948249 kernel: Memory: 1965064K/2096620K available (12288K kernel code, 2302K rwdata, 22640K rodata, 49328K init, 2016K bss, 131296K reserved, 0K cma-reserved) Aug 5 22:20:50.948259 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 5 22:20:50.948270 kernel: ftrace: allocating 37659 entries in 148 pages Aug 5 22:20:50.948283 kernel: ftrace: allocated 148 pages with 3 groups Aug 5 22:20:50.948293 kernel: Dynamic Preempt: voluntary Aug 5 22:20:50.948304 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 22:20:50.948315 kernel: rcu: RCU event tracing is enabled. Aug 5 22:20:50.948326 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Aug 5 22:20:50.948337 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 22:20:50.948349 kernel: Rude variant of Tasks RCU enabled. Aug 5 22:20:50.948359 kernel: Tracing variant of Tasks RCU enabled. Aug 5 22:20:50.948369 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 22:20:50.948379 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 5 22:20:50.948391 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Aug 5 22:20:50.948401 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 22:20:50.948411 kernel: Console: colour VGA+ 80x25 Aug 5 22:20:50.948420 kernel: printk: console [tty0] enabled Aug 5 22:20:50.948430 kernel: printk: console [ttyS0] enabled Aug 5 22:20:50.948440 kernel: ACPI: Core revision 20230628 Aug 5 22:20:50.948449 kernel: APIC: Switch to symmetric I/O mode setup Aug 5 22:20:50.948459 kernel: x2apic enabled Aug 5 22:20:50.948469 kernel: APIC: Switched APIC routing to: physical x2apic Aug 5 22:20:50.948481 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Aug 5 22:20:50.948491 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Aug 5 22:20:50.948501 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Aug 5 22:20:50.948511 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Aug 5 22:20:50.948521 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Aug 5 22:20:50.948531 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Aug 5 22:20:50.948540 kernel: Spectre V2 : Mitigation: Retpolines Aug 5 22:20:50.948550 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Aug 5 22:20:50.948560 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Aug 5 22:20:50.948573 kernel: Speculative Store Bypass: Vulnerable Aug 5 22:20:50.948582 kernel: x86/fpu: x87 FPU will use FXSAVE Aug 5 22:20:50.948592 kernel: Freeing SMP alternatives memory: 32K Aug 5 22:20:50.948602 kernel: pid_max: default: 32768 minimum: 301 Aug 5 22:20:50.948612 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 22:20:50.948621 kernel: SELinux: Initializing. Aug 5 22:20:50.948631 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 5 22:20:50.948641 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Aug 5 22:20:50.948661 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Aug 5 22:20:50.948671 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:20:50.948681 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:20:50.948693 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 5 22:20:50.948704 kernel: Performance Events: AMD PMU driver. Aug 5 22:20:50.948714 kernel: ... version: 0 Aug 5 22:20:50.948724 kernel: ... bit width: 48 Aug 5 22:20:50.948735 kernel: ... generic registers: 4 Aug 5 22:20:50.948747 kernel: ... value mask: 0000ffffffffffff Aug 5 22:20:50.948757 kernel: ... max period: 00007fffffffffff Aug 5 22:20:50.948767 kernel: ... fixed-purpose events: 0 Aug 5 22:20:50.948778 kernel: ... event mask: 000000000000000f Aug 5 22:20:50.948788 kernel: signal: max sigframe size: 1440 Aug 5 22:20:50.948798 kernel: rcu: Hierarchical SRCU implementation. Aug 5 22:20:50.948809 kernel: rcu: Max phase no-delay instances is 400. Aug 5 22:20:50.948819 kernel: smp: Bringing up secondary CPUs ... Aug 5 22:20:50.948829 kernel: smpboot: x86: Booting SMP configuration: Aug 5 22:20:50.948839 kernel: .... node #0, CPUs: #1 Aug 5 22:20:50.948851 kernel: smp: Brought up 1 node, 2 CPUs Aug 5 22:20:50.948862 kernel: smpboot: Max logical packages: 2 Aug 5 22:20:50.948872 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Aug 5 22:20:50.948882 kernel: devtmpfs: initialized Aug 5 22:20:50.948892 kernel: x86/mm: Memory block size: 128MB Aug 5 22:20:50.948903 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 22:20:50.948925 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 5 22:20:50.948936 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 22:20:50.948946 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 22:20:50.948959 kernel: audit: initializing netlink subsys (disabled) Aug 5 22:20:50.948969 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 22:20:50.948980 kernel: thermal_sys: Registered thermal governor 'user_space' Aug 5 22:20:50.948990 kernel: audit: type=2000 audit(1722896450.190:1): state=initialized audit_enabled=0 res=1 Aug 5 22:20:50.949000 kernel: cpuidle: using governor menu Aug 5 22:20:50.949011 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 22:20:50.949021 kernel: dca service started, version 1.12.1 Aug 5 22:20:50.949031 kernel: PCI: Using configuration type 1 for base access Aug 5 22:20:50.949042 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Aug 5 22:20:50.949054 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 22:20:50.949065 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 22:20:50.949075 kernel: ACPI: Added _OSI(Module Device) Aug 5 22:20:50.949085 kernel: ACPI: Added _OSI(Processor Device) Aug 5 22:20:50.949095 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 22:20:50.949106 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 22:20:50.949116 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 22:20:50.949126 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Aug 5 22:20:50.949137 kernel: ACPI: Interpreter enabled Aug 5 22:20:50.949149 kernel: ACPI: PM: (supports S0 S3 S5) Aug 5 22:20:50.949159 kernel: ACPI: Using IOAPIC for interrupt routing Aug 5 22:20:50.949170 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Aug 5 22:20:50.949180 kernel: PCI: Using E820 reservations for host bridge windows Aug 5 22:20:50.949191 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Aug 5 22:20:50.949201 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 5 22:20:50.949341 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Aug 5 22:20:50.949446 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Aug 5 22:20:50.949549 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Aug 5 22:20:50.949564 kernel: acpiphp: Slot [3] registered Aug 5 22:20:50.949575 kernel: acpiphp: Slot [4] registered Aug 5 22:20:50.949585 kernel: acpiphp: Slot [5] registered Aug 5 22:20:50.949595 kernel: acpiphp: Slot [6] registered Aug 5 22:20:50.949605 kernel: acpiphp: Slot [7] registered Aug 5 22:20:50.949615 kernel: acpiphp: Slot [8] registered Aug 5 22:20:50.949625 kernel: acpiphp: Slot [9] registered Aug 5 22:20:50.949639 kernel: acpiphp: Slot [10] registered Aug 5 22:20:50.949649 kernel: acpiphp: Slot [11] registered Aug 5 22:20:50.949659 kernel: acpiphp: Slot [12] registered Aug 5 22:20:50.949669 kernel: acpiphp: Slot [13] registered Aug 5 22:20:50.949679 kernel: acpiphp: Slot [14] registered Aug 5 22:20:50.949690 kernel: acpiphp: Slot [15] registered Aug 5 22:20:50.949699 kernel: acpiphp: Slot [16] registered Aug 5 22:20:50.949710 kernel: acpiphp: Slot [17] registered Aug 5 22:20:50.949719 kernel: acpiphp: Slot [18] registered Aug 5 22:20:50.949729 kernel: acpiphp: Slot [19] registered Aug 5 22:20:50.949742 kernel: acpiphp: Slot [20] registered Aug 5 22:20:50.949752 kernel: acpiphp: Slot [21] registered Aug 5 22:20:50.949762 kernel: acpiphp: Slot [22] registered Aug 5 22:20:50.949772 kernel: acpiphp: Slot [23] registered Aug 5 22:20:50.949782 kernel: acpiphp: Slot [24] registered Aug 5 22:20:50.949792 kernel: acpiphp: Slot [25] registered Aug 5 22:20:50.949802 kernel: acpiphp: Slot [26] registered Aug 5 22:20:50.949812 kernel: acpiphp: Slot [27] registered Aug 5 22:20:50.949823 kernel: acpiphp: Slot [28] registered Aug 5 22:20:50.949835 kernel: acpiphp: Slot [29] registered Aug 5 22:20:50.949845 kernel: acpiphp: Slot [30] registered Aug 5 22:20:50.949855 kernel: acpiphp: Slot [31] registered Aug 5 22:20:50.949865 kernel: PCI host bridge to bus 0000:00 Aug 5 22:20:50.950029 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Aug 5 22:20:50.950125 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Aug 5 22:20:50.950221 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Aug 5 22:20:50.950314 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Aug 5 22:20:50.950413 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Aug 5 22:20:50.950505 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 5 22:20:50.950645 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Aug 5 22:20:50.950762 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Aug 5 22:20:50.950876 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Aug 5 22:20:50.951018 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Aug 5 22:20:50.951127 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Aug 5 22:20:50.951231 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Aug 5 22:20:50.951357 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Aug 5 22:20:50.951452 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Aug 5 22:20:50.951563 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Aug 5 22:20:50.951668 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Aug 5 22:20:50.951771 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Aug 5 22:20:50.951890 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Aug 5 22:20:50.952018 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Aug 5 22:20:50.952123 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Aug 5 22:20:50.952228 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Aug 5 22:20:50.952332 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Aug 5 22:20:50.952438 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Aug 5 22:20:50.955028 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Aug 5 22:20:50.955148 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Aug 5 22:20:50.955263 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Aug 5 22:20:50.955363 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Aug 5 22:20:50.955468 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Aug 5 22:20:50.955583 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Aug 5 22:20:50.955689 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Aug 5 22:20:50.955800 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Aug 5 22:20:50.955905 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Aug 5 22:20:50.956260 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Aug 5 22:20:50.956371 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Aug 5 22:20:50.956472 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Aug 5 22:20:50.956581 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Aug 5 22:20:50.956685 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Aug 5 22:20:50.956794 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Aug 5 22:20:50.956810 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Aug 5 22:20:50.956823 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Aug 5 22:20:50.956834 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Aug 5 22:20:50.956845 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Aug 5 22:20:50.956856 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Aug 5 22:20:50.956867 kernel: iommu: Default domain type: Translated Aug 5 22:20:50.956878 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Aug 5 22:20:50.956889 kernel: PCI: Using ACPI for IRQ routing Aug 5 22:20:50.956903 kernel: PCI: pci_cache_line_size set to 64 bytes Aug 5 22:20:50.956933 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Aug 5 22:20:50.956944 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff] Aug 5 22:20:50.957050 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Aug 5 22:20:50.957152 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Aug 5 22:20:50.957257 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Aug 5 22:20:50.957273 kernel: vgaarb: loaded Aug 5 22:20:50.957284 kernel: clocksource: Switched to clocksource kvm-clock Aug 5 22:20:50.957294 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 22:20:50.957310 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 22:20:50.957323 kernel: pnp: PnP ACPI init Aug 5 22:20:50.957420 kernel: pnp 00:03: [dma 2] Aug 5 22:20:50.957437 kernel: pnp: PnP ACPI: found 5 devices Aug 5 22:20:50.957447 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Aug 5 22:20:50.957458 kernel: NET: Registered PF_INET protocol family Aug 5 22:20:50.957468 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 22:20:50.957479 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Aug 5 22:20:50.957492 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 22:20:50.957503 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 5 22:20:50.957513 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Aug 5 22:20:50.957523 kernel: TCP: Hash tables configured (established 16384 bind 16384) Aug 5 22:20:50.957534 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 5 22:20:50.957544 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Aug 5 22:20:50.957554 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 22:20:50.957564 kernel: NET: Registered PF_XDP protocol family Aug 5 22:20:50.957653 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Aug 5 22:20:50.957744 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Aug 5 22:20:50.957828 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Aug 5 22:20:50.958861 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Aug 5 22:20:50.959023 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Aug 5 22:20:50.960083 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Aug 5 22:20:50.960192 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Aug 5 22:20:50.960209 kernel: PCI: CLS 0 bytes, default 64 Aug 5 22:20:50.960220 kernel: Initialise system trusted keyrings Aug 5 22:20:50.960236 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Aug 5 22:20:50.960247 kernel: Key type asymmetric registered Aug 5 22:20:50.960258 kernel: Asymmetric key parser 'x509' registered Aug 5 22:20:50.960269 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Aug 5 22:20:50.960280 kernel: io scheduler mq-deadline registered Aug 5 22:20:50.960291 kernel: io scheduler kyber registered Aug 5 22:20:50.960302 kernel: io scheduler bfq registered Aug 5 22:20:50.960313 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Aug 5 22:20:50.960325 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Aug 5 22:20:50.960338 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Aug 5 22:20:50.960350 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Aug 5 22:20:50.960361 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Aug 5 22:20:50.960373 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 22:20:50.960385 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Aug 5 22:20:50.960396 kernel: random: crng init done Aug 5 22:20:50.960406 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Aug 5 22:20:50.960417 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Aug 5 22:20:50.960427 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Aug 5 22:20:50.960440 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Aug 5 22:20:50.960555 kernel: rtc_cmos 00:04: RTC can wake from S4 Aug 5 22:20:50.960655 kernel: rtc_cmos 00:04: registered as rtc0 Aug 5 22:20:50.960749 kernel: rtc_cmos 00:04: setting system clock to 2024-08-05T22:20:50 UTC (1722896450) Aug 5 22:20:50.960842 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Aug 5 22:20:50.960857 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Aug 5 22:20:50.960869 kernel: NET: Registered PF_INET6 protocol family Aug 5 22:20:50.960880 kernel: Segment Routing with IPv6 Aug 5 22:20:50.960896 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 22:20:50.960907 kernel: NET: Registered PF_PACKET protocol family Aug 5 22:20:50.960941 kernel: Key type dns_resolver registered Aug 5 22:20:50.960953 kernel: IPI shorthand broadcast: enabled Aug 5 22:20:50.960964 kernel: sched_clock: Marking stable (919007427, 127667497)->(1049832972, -3158048) Aug 5 22:20:50.960975 kernel: registered taskstats version 1 Aug 5 22:20:50.960986 kernel: Loading compiled-in X.509 certificates Aug 5 22:20:50.960997 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: e31e857530e65c19b206dbf3ab8297cc37ac5d55' Aug 5 22:20:50.961008 kernel: Key type .fscrypt registered Aug 5 22:20:50.961021 kernel: Key type fscrypt-provisioning registered Aug 5 22:20:50.961032 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 22:20:50.961043 kernel: ima: Allocated hash algorithm: sha1 Aug 5 22:20:50.961054 kernel: ima: No architecture policies found Aug 5 22:20:50.961065 kernel: clk: Disabling unused clocks Aug 5 22:20:50.961076 kernel: Freeing unused kernel image (initmem) memory: 49328K Aug 5 22:20:50.961087 kernel: Write protecting the kernel read-only data: 36864k Aug 5 22:20:50.961098 kernel: Freeing unused kernel image (rodata/data gap) memory: 1936K Aug 5 22:20:50.961111 kernel: Run /init as init process Aug 5 22:20:50.961122 kernel: with arguments: Aug 5 22:20:50.961133 kernel: /init Aug 5 22:20:50.961144 kernel: with environment: Aug 5 22:20:50.961154 kernel: HOME=/ Aug 5 22:20:50.961165 kernel: TERM=linux Aug 5 22:20:50.961175 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 22:20:50.961190 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) Aug 5 22:20:50.961206 systemd[1]: Detected virtualization kvm. Aug 5 22:20:50.961219 systemd[1]: Detected architecture x86-64. Aug 5 22:20:50.961230 systemd[1]: Running in initrd. Aug 5 22:20:50.961242 systemd[1]: No hostname configured, using default hostname. Aug 5 22:20:50.961253 systemd[1]: Hostname set to . Aug 5 22:20:50.961266 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:20:50.961278 systemd[1]: Queued start job for default target initrd.target. Aug 5 22:20:50.961290 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:20:50.961303 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:20:50.961316 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 22:20:50.961328 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:20:50.961341 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 22:20:50.961353 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 22:20:50.961367 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 22:20:50.961378 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 22:20:50.961393 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:20:50.961405 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:20:50.961416 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:20:50.961428 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:20:50.961452 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:20:50.961466 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:20:50.961480 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:20:50.961493 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:20:50.961505 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 22:20:50.961517 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 22:20:50.961529 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:20:50.961542 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:20:50.961554 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:20:50.961566 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:20:50.961578 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 22:20:50.961593 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:20:50.961605 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 22:20:50.961617 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 22:20:50.961629 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:20:50.961641 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:20:50.961653 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:20:50.961686 systemd-journald[184]: Collecting audit messages is disabled. Aug 5 22:20:50.961718 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 22:20:50.961730 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:20:50.961743 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 22:20:50.961758 systemd-journald[184]: Journal started Aug 5 22:20:50.961785 systemd-journald[184]: Runtime Journal (/run/log/journal/139373b3515748a09c9c7e62e70698dd) is 4.9M, max 39.3M, 34.4M free. Aug 5 22:20:50.971937 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:20:50.969970 systemd-modules-load[186]: Inserted module 'overlay' Aug 5 22:20:51.016408 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 22:20:51.016435 kernel: Bridge firewalling registered Aug 5 22:20:51.016451 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:20:51.007607 systemd-modules-load[186]: Inserted module 'br_netfilter' Aug 5 22:20:51.019366 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:20:51.020091 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:20:51.024643 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:20:51.032087 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:20:51.035037 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:20:51.036426 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:20:51.047523 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:20:51.060345 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:20:51.063339 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:20:51.068971 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:20:51.070486 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:20:51.077068 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 22:20:51.080168 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:20:51.090783 dracut-cmdline[220]: dracut-dracut-053 Aug 5 22:20:51.093226 dracut-cmdline[220]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=4a86c72568bc3f74d57effa5e252d5620941ef6d74241fc198859d020a6392c5 Aug 5 22:20:51.126840 systemd-resolved[222]: Positive Trust Anchors: Aug 5 22:20:51.131232 systemd-resolved[222]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:20:51.131289 systemd-resolved[222]: 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 corp home internal intranet lan local private test Aug 5 22:20:51.135301 systemd-resolved[222]: Defaulting to hostname 'linux'. Aug 5 22:20:51.137044 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:20:51.141089 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:20:51.188993 kernel: SCSI subsystem initialized Aug 5 22:20:51.202968 kernel: Loading iSCSI transport class v2.0-870. Aug 5 22:20:51.219497 kernel: iscsi: registered transport (tcp) Aug 5 22:20:51.248143 kernel: iscsi: registered transport (qla4xxx) Aug 5 22:20:51.248257 kernel: QLogic iSCSI HBA Driver Aug 5 22:20:51.312484 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 22:20:51.322056 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 22:20:51.386331 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 22:20:51.386445 kernel: device-mapper: uevent: version 1.0.3 Aug 5 22:20:51.387134 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 22:20:51.444071 kernel: raid6: sse2x4 gen() 12594 MB/s Aug 5 22:20:51.461984 kernel: raid6: sse2x2 gen() 14059 MB/s Aug 5 22:20:51.479364 kernel: raid6: sse2x1 gen() 9186 MB/s Aug 5 22:20:51.479436 kernel: raid6: using algorithm sse2x2 gen() 14059 MB/s Aug 5 22:20:51.499385 kernel: raid6: .... xor() 7653 MB/s, rmw enabled Aug 5 22:20:51.499473 kernel: raid6: using ssse3x2 recovery algorithm Aug 5 22:20:51.531995 kernel: xor: measuring software checksum speed Aug 5 22:20:51.532074 kernel: prefetch64-sse : 17232 MB/sec Aug 5 22:20:51.534029 kernel: generic_sse : 15625 MB/sec Aug 5 22:20:51.535734 kernel: xor: using function: prefetch64-sse (17232 MB/sec) Aug 5 22:20:51.774015 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 22:20:51.794070 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:20:51.804144 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:20:51.852821 systemd-udevd[405]: Using default interface naming scheme 'v255'. Aug 5 22:20:51.863797 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:20:51.875257 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 22:20:51.905868 dracut-pre-trigger[413]: rd.md=0: removing MD RAID activation Aug 5 22:20:51.952022 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:20:51.959211 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:20:52.029862 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:20:52.047891 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 22:20:52.098795 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 22:20:52.100170 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:20:52.103286 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:20:52.105447 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:20:52.112379 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 22:20:52.132989 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:20:52.137946 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Aug 5 22:20:52.165521 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) Aug 5 22:20:52.165704 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 5 22:20:52.165724 kernel: GPT:17805311 != 41943039 Aug 5 22:20:52.165736 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 5 22:20:52.165748 kernel: GPT:17805311 != 41943039 Aug 5 22:20:52.165759 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 5 22:20:52.165769 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:20:52.151263 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:20:52.151445 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:20:52.157661 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:20:52.158200 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:20:52.158338 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:20:52.158866 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:20:52.166828 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:20:52.196503 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by (udev-worker) (468) Aug 5 22:20:52.209935 kernel: BTRFS: device fsid d3844c60-0a2c-449a-9ee9-2a875f8d8e12 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (458) Aug 5 22:20:52.229961 kernel: libata version 3.00 loaded. Aug 5 22:20:52.233957 kernel: ata_piix 0000:00:01.1: version 2.13 Aug 5 22:20:52.236847 kernel: scsi host0: ata_piix Aug 5 22:20:52.237015 kernel: scsi host1: ata_piix Aug 5 22:20:52.237139 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Aug 5 22:20:52.237157 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Aug 5 22:20:52.237065 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 5 22:20:52.269067 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 5 22:20:52.269971 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:20:52.276358 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 5 22:20:52.277064 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 5 22:20:52.285684 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:20:52.292195 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 22:20:52.295186 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 22:20:52.308733 disk-uuid[503]: Primary Header is updated. Aug 5 22:20:52.308733 disk-uuid[503]: Secondary Entries is updated. Aug 5 22:20:52.308733 disk-uuid[503]: Secondary Header is updated. Aug 5 22:20:52.319972 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:20:52.327378 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:20:52.337299 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:20:53.341064 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 22:20:53.342739 disk-uuid[504]: The operation has completed successfully. Aug 5 22:20:53.413804 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 22:20:53.414156 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 22:20:53.450213 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 22:20:53.453841 sh[526]: Success Aug 5 22:20:53.483436 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Aug 5 22:20:53.562456 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 22:20:53.566018 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 22:20:53.572207 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 22:20:53.592935 kernel: BTRFS info (device dm-0): first mount of filesystem d3844c60-0a2c-449a-9ee9-2a875f8d8e12 Aug 5 22:20:53.592986 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:20:53.593000 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 22:20:53.605745 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 22:20:53.608585 kernel: BTRFS info (device dm-0): using free space tree Aug 5 22:20:53.631819 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 22:20:53.634121 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 22:20:53.640227 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 22:20:53.654371 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 22:20:53.681094 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 5 22:20:53.681183 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:20:53.684144 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:20:53.701003 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:20:53.722488 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 22:20:53.727301 kernel: BTRFS info (device vda6): last unmount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 5 22:20:53.750196 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 22:20:53.756131 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 22:20:53.798948 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:20:53.803074 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:20:53.827769 systemd-networkd[708]: lo: Link UP Aug 5 22:20:53.828487 systemd-networkd[708]: lo: Gained carrier Aug 5 22:20:53.830095 systemd-networkd[708]: Enumeration completed Aug 5 22:20:53.830194 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:20:53.830526 systemd-networkd[708]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:20:53.830530 systemd-networkd[708]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:20:53.831651 systemd-networkd[708]: eth0: Link UP Aug 5 22:20:53.831655 systemd-networkd[708]: eth0: Gained carrier Aug 5 22:20:53.831662 systemd-networkd[708]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:20:53.834909 systemd[1]: Reached target network.target - Network. Aug 5 22:20:53.848971 systemd-networkd[708]: eth0: DHCPv4 address 172.24.4.186/24, gateway 172.24.4.1 acquired from 172.24.4.1 Aug 5 22:20:53.911169 ignition[645]: Ignition 2.18.0 Aug 5 22:20:53.911182 ignition[645]: Stage: fetch-offline Aug 5 22:20:53.913083 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:20:53.911229 ignition[645]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:20:53.911257 ignition[645]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:20:53.911403 ignition[645]: parsed url from cmdline: "" Aug 5 22:20:53.911408 ignition[645]: no config URL provided Aug 5 22:20:53.911414 ignition[645]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 22:20:53.911424 ignition[645]: no config at "/usr/lib/ignition/user.ign" Aug 5 22:20:53.911429 ignition[645]: failed to fetch config: resource requires networking Aug 5 22:20:53.911641 ignition[645]: Ignition finished successfully Aug 5 22:20:53.920137 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 5 22:20:53.932716 ignition[718]: Ignition 2.18.0 Aug 5 22:20:53.932731 ignition[718]: Stage: fetch Aug 5 22:20:53.932953 ignition[718]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:20:53.932966 ignition[718]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:20:53.933076 ignition[718]: parsed url from cmdline: "" Aug 5 22:20:53.933080 ignition[718]: no config URL provided Aug 5 22:20:53.933086 ignition[718]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 22:20:53.933095 ignition[718]: no config at "/usr/lib/ignition/user.ign" Aug 5 22:20:53.933187 ignition[718]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Aug 5 22:20:53.933560 ignition[718]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Aug 5 22:20:53.933613 ignition[718]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Aug 5 22:20:54.127765 ignition[718]: GET result: OK Aug 5 22:20:54.127882 ignition[718]: parsing config with SHA512: 123785433a37acc7b45d8e63cb3853d2613d9ea3d8afab7b155ff9a05f253ae04abd7f665cb7417eb719d4add0ec13c016ac8c5919ce9c4e1172cc5e81c31726 Aug 5 22:20:54.135404 unknown[718]: fetched base config from "system" Aug 5 22:20:54.135432 unknown[718]: fetched base config from "system" Aug 5 22:20:54.136485 ignition[718]: fetch: fetch complete Aug 5 22:20:54.135447 unknown[718]: fetched user config from "openstack" Aug 5 22:20:54.136498 ignition[718]: fetch: fetch passed Aug 5 22:20:54.140120 systemd-resolved[222]: Detected conflict on linux IN A 172.24.4.186 Aug 5 22:20:54.136584 ignition[718]: Ignition finished successfully Aug 5 22:20:54.140138 systemd-resolved[222]: Hostname conflict, changing published hostname from 'linux' to 'linux9'. Aug 5 22:20:54.141854 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 5 22:20:54.153340 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 22:20:54.197355 ignition[725]: Ignition 2.18.0 Aug 5 22:20:54.198965 ignition[725]: Stage: kargs Aug 5 22:20:54.199399 ignition[725]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:20:54.199426 ignition[725]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:20:54.205470 ignition[725]: kargs: kargs passed Aug 5 22:20:54.205572 ignition[725]: Ignition finished successfully Aug 5 22:20:54.207625 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 22:20:54.215189 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 22:20:54.259050 ignition[732]: Ignition 2.18.0 Aug 5 22:20:54.259074 ignition[732]: Stage: disks Aug 5 22:20:54.259514 ignition[732]: no configs at "/usr/lib/ignition/base.d" Aug 5 22:20:54.259541 ignition[732]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:20:54.265530 ignition[732]: disks: disks passed Aug 5 22:20:54.267971 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 22:20:54.265631 ignition[732]: Ignition finished successfully Aug 5 22:20:54.270875 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 22:20:54.272816 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 22:20:54.275400 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:20:54.278166 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:20:54.281062 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:20:54.295315 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 22:20:54.327545 systemd-fsck[742]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Aug 5 22:20:54.337442 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 22:20:54.349199 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 22:20:54.519957 kernel: EXT4-fs (vda9): mounted filesystem e865ac73-053b-4efa-9a0f-50dec3f650d9 r/w with ordered data mode. Quota mode: none. Aug 5 22:20:54.520955 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 22:20:54.522568 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 22:20:54.528990 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:20:54.531018 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 22:20:54.533297 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 22:20:54.540091 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Aug 5 22:20:54.541977 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 22:20:54.543208 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:20:54.548479 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 22:20:54.555951 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (750) Aug 5 22:20:54.557103 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 22:20:54.570243 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 5 22:20:54.570265 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:20:54.570283 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:20:54.582968 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:20:54.589377 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:20:54.697651 initrd-setup-root[779]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 22:20:54.704954 initrd-setup-root[786]: cut: /sysroot/etc/group: No such file or directory Aug 5 22:20:54.715346 initrd-setup-root[793]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 22:20:54.722973 initrd-setup-root[800]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 22:20:54.825440 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 22:20:54.832990 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 22:20:54.835774 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 22:20:54.843813 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 22:20:54.846552 kernel: BTRFS info (device vda6): last unmount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 5 22:20:54.872874 ignition[867]: INFO : Ignition 2.18.0 Aug 5 22:20:54.872874 ignition[867]: INFO : Stage: mount Aug 5 22:20:54.874743 ignition[867]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:20:54.874743 ignition[867]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:20:54.874743 ignition[867]: INFO : mount: mount passed Aug 5 22:20:54.874743 ignition[867]: INFO : Ignition finished successfully Aug 5 22:20:54.876495 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 22:20:54.885654 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 22:20:55.498469 systemd-networkd[708]: eth0: Gained IPv6LL Aug 5 22:21:01.764383 coreos-metadata[752]: Aug 05 22:21:01.764 WARN failed to locate config-drive, using the metadata service API instead Aug 5 22:21:01.795058 coreos-metadata[752]: Aug 05 22:21:01.795 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Aug 5 22:21:01.815089 coreos-metadata[752]: Aug 05 22:21:01.814 INFO Fetch successful Aug 5 22:21:01.816628 coreos-metadata[752]: Aug 05 22:21:01.816 INFO wrote hostname ci-3975-2-0-6-6e1d881ec2.novalocal to /sysroot/etc/hostname Aug 5 22:21:01.821230 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Aug 5 22:21:01.821650 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Aug 5 22:21:01.836092 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 22:21:01.874259 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 22:21:01.893004 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (885) Aug 5 22:21:01.899633 kernel: BTRFS info (device vda6): first mount of filesystem b6695624-d538-4f05-9ddd-23ee987404c1 Aug 5 22:21:01.899698 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Aug 5 22:21:01.904668 kernel: BTRFS info (device vda6): using free space tree Aug 5 22:21:01.912989 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 22:21:01.918060 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 22:21:01.976305 ignition[903]: INFO : Ignition 2.18.0 Aug 5 22:21:01.976305 ignition[903]: INFO : Stage: files Aug 5 22:21:01.979193 ignition[903]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:21:01.979193 ignition[903]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:21:01.979193 ignition[903]: DEBUG : files: compiled without relabeling support, skipping Aug 5 22:21:01.984625 ignition[903]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 22:21:01.984625 ignition[903]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 22:21:01.988663 ignition[903]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 22:21:01.990530 ignition[903]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 22:21:01.990530 ignition[903]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 22:21:01.990010 unknown[903]: wrote ssh authorized keys file for user: core Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-x86-64.raw" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.28.7-x86-64.raw" Aug 5 22:21:01.996225 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-x86-64.raw" Aug 5 22:21:02.016750 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.28.7-x86-64.raw: attempt #1 Aug 5 22:21:02.421220 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Aug 5 22:21:04.014778 ignition[903]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.28.7-x86-64.raw" Aug 5 22:21:04.014778 ignition[903]: INFO : files: op(8): [started] processing unit "containerd.service" Aug 5 22:21:04.154844 ignition[903]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Aug 5 22:21:04.158114 ignition[903]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Aug 5 22:21:04.158114 ignition[903]: INFO : files: op(8): [finished] processing unit "containerd.service" Aug 5 22:21:04.158114 ignition[903]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:21:04.158114 ignition[903]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 22:21:04.158114 ignition[903]: INFO : files: files passed Aug 5 22:21:04.158114 ignition[903]: INFO : Ignition finished successfully Aug 5 22:21:04.158744 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 22:21:04.171208 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 22:21:04.181207 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 22:21:04.225861 initrd-setup-root-after-ignition[931]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:21:04.225861 initrd-setup-root-after-ignition[931]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:21:04.230436 initrd-setup-root-after-ignition[935]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 22:21:04.233586 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:21:04.236581 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 22:21:04.247288 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 22:21:04.254827 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 22:21:04.255057 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 22:21:04.297160 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 22:21:04.297366 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 22:21:04.299780 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 22:21:04.301170 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 22:21:04.303779 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 22:21:04.316363 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 22:21:04.344098 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:21:04.352121 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 22:21:04.396811 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:21:04.398531 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:21:04.401705 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 22:21:04.404546 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 22:21:04.404837 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 22:21:04.408110 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 22:21:04.409818 systemd[1]: Stopped target basic.target - Basic System. Aug 5 22:21:04.412835 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 22:21:04.415403 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 22:21:04.429636 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 22:21:04.432475 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 22:21:04.435623 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 22:21:04.438997 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 22:21:04.442117 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 22:21:04.445270 systemd[1]: Stopped target swap.target - Swaps. Aug 5 22:21:04.447876 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 22:21:04.448338 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 22:21:04.451091 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:21:04.453118 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:21:04.455625 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 22:21:04.455960 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:21:04.458472 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 22:21:04.458759 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 22:21:04.462828 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 22:21:04.463211 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 22:21:04.465148 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 22:21:04.465527 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 22:21:04.474460 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 22:21:04.486133 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 22:21:04.489716 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 22:21:04.490088 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:21:04.497271 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 22:21:04.497430 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 22:21:04.505006 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 22:21:04.505107 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 22:21:04.515964 ignition[956]: INFO : Ignition 2.18.0 Aug 5 22:21:04.515964 ignition[956]: INFO : Stage: umount Aug 5 22:21:04.517422 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 22:21:04.517422 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Aug 5 22:21:04.519345 ignition[956]: INFO : umount: umount passed Aug 5 22:21:04.519345 ignition[956]: INFO : Ignition finished successfully Aug 5 22:21:04.523444 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 22:21:04.524242 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 22:21:04.525759 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 22:21:04.526359 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 22:21:04.527449 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 22:21:04.528053 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 22:21:04.529210 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 5 22:21:04.529797 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 5 22:21:04.530900 systemd[1]: Stopped target network.target - Network. Aug 5 22:21:04.531900 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 22:21:04.531968 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 22:21:04.533239 systemd[1]: Stopped target paths.target - Path Units. Aug 5 22:21:04.534189 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 22:21:04.534303 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:21:04.535039 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 22:21:04.535486 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 22:21:04.536011 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 22:21:04.536056 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 22:21:04.536555 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 22:21:04.536588 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 22:21:04.538259 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 22:21:04.538326 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 22:21:04.539673 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 22:21:04.539714 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 22:21:04.540885 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 22:21:04.542014 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 22:21:04.546968 systemd-networkd[708]: eth0: DHCPv6 lease lost Aug 5 22:21:04.548778 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 22:21:04.548905 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 22:21:04.550473 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 22:21:04.550504 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:21:04.563126 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 22:21:04.563931 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 22:21:04.563988 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 22:21:04.564629 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:21:04.565388 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 22:21:04.565909 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 22:21:04.580962 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 22:21:04.581459 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 22:21:04.581604 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:21:04.582522 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 22:21:04.582620 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 22:21:04.584228 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 22:21:04.584284 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 22:21:04.585408 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 22:21:04.585437 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:21:04.586318 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 22:21:04.586358 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 22:21:04.587953 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 22:21:04.587991 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 22:21:04.589031 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 22:21:04.589073 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 22:21:04.596115 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 22:21:04.598273 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 22:21:04.598327 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:21:04.599626 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 22:21:04.599669 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 22:21:04.603025 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 22:21:04.603082 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:21:04.606279 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 5 22:21:04.606322 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:21:04.607524 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 22:21:04.607566 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:21:04.608745 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 22:21:04.608787 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:21:04.609982 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:21:04.610023 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:21:04.611680 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 22:21:04.611781 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 22:21:04.733548 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 22:21:04.733756 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 22:21:04.737161 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 22:21:04.738839 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 22:21:04.739020 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 22:21:04.757620 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 22:21:04.942904 systemd[1]: Switching root. Aug 5 22:21:04.983369 systemd-journald[184]: Journal stopped Aug 5 22:21:07.894049 systemd-journald[184]: Received SIGTERM from PID 1 (systemd). Aug 5 22:21:07.894109 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 22:21:07.894128 kernel: SELinux: policy capability open_perms=1 Aug 5 22:21:07.894140 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 22:21:07.894151 kernel: SELinux: policy capability always_check_network=0 Aug 5 22:21:07.894165 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 22:21:07.894176 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 22:21:07.894187 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 22:21:07.894198 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 22:21:07.894209 kernel: audit: type=1403 audit(1722896466.207:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 22:21:07.894224 systemd[1]: Successfully loaded SELinux policy in 104.733ms. Aug 5 22:21:07.894247 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 24.388ms. Aug 5 22:21:07.894260 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) Aug 5 22:21:07.894277 systemd[1]: Detected virtualization kvm. Aug 5 22:21:07.894292 systemd[1]: Detected architecture x86-64. Aug 5 22:21:07.894304 systemd[1]: Detected first boot. Aug 5 22:21:07.894316 systemd[1]: Hostname set to . Aug 5 22:21:07.894328 systemd[1]: Initializing machine ID from VM UUID. Aug 5 22:21:07.894339 zram_generator::config[1018]: No configuration found. Aug 5 22:21:07.894353 systemd[1]: Populated /etc with preset unit settings. Aug 5 22:21:07.894364 systemd[1]: Queued start job for default target multi-user.target. Aug 5 22:21:07.894376 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 5 22:21:07.894391 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 22:21:07.894403 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 22:21:07.894414 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 22:21:07.894426 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 22:21:07.894438 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 22:21:07.894450 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 22:21:07.894462 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 22:21:07.894473 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 22:21:07.894488 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 22:21:07.894500 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 22:21:07.894512 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 22:21:07.894524 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 22:21:07.894536 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 22:21:07.894549 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 22:21:07.894561 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 5 22:21:07.894573 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 22:21:07.894584 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 22:21:07.894598 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 22:21:07.894611 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 22:21:07.894623 systemd[1]: Reached target slices.target - Slice Units. Aug 5 22:21:07.894634 systemd[1]: Reached target swap.target - Swaps. Aug 5 22:21:07.894646 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 22:21:07.894657 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 22:21:07.894669 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 22:21:07.894684 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 22:21:07.894695 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 22:21:07.894707 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 22:21:07.894719 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 22:21:07.894730 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 22:21:07.894742 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 22:21:07.894754 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 22:21:07.894766 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 22:21:07.894778 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:07.894792 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 22:21:07.894805 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 22:21:07.894816 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 22:21:07.894828 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 22:21:07.894840 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:21:07.894853 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 22:21:07.894864 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 22:21:07.894876 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:21:07.894890 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:21:07.894902 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:21:07.895051 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 22:21:07.895070 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:21:07.895082 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 22:21:07.895095 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Aug 5 22:21:07.895108 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Aug 5 22:21:07.895119 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 22:21:07.895131 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 22:21:07.895153 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 22:21:07.895194 systemd-journald[1132]: Collecting audit messages is disabled. Aug 5 22:21:07.895239 kernel: fuse: init (API version 7.39) Aug 5 22:21:07.895253 systemd-journald[1132]: Journal started Aug 5 22:21:07.895280 systemd-journald[1132]: Runtime Journal (/run/log/journal/139373b3515748a09c9c7e62e70698dd) is 4.9M, max 39.3M, 34.4M free. Aug 5 22:21:07.898992 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 22:21:07.907996 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 22:21:07.914049 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:07.918936 kernel: loop: module loaded Aug 5 22:21:07.926163 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 22:21:07.937350 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 22:21:07.938978 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 22:21:07.939676 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 22:21:07.940517 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 22:21:07.941345 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 22:21:07.942018 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 22:21:07.942832 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 22:21:07.943705 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 22:21:07.944610 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 22:21:07.944766 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 22:21:07.945504 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:21:07.945651 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:21:07.946541 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:21:07.950041 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:21:07.950867 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 22:21:07.951056 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 22:21:07.951831 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:21:07.951995 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:21:07.952756 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 22:21:07.953581 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 22:21:07.954419 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 22:21:07.965951 kernel: ACPI: bus type drm_connector registered Aug 5 22:21:07.965651 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 22:21:07.971158 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 22:21:07.974136 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 22:21:07.976093 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 22:21:07.984245 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 22:21:08.002113 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 22:21:08.002770 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:21:08.004861 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 22:21:08.007947 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:21:08.014347 systemd-journald[1132]: Time spent on flushing to /var/log/journal/139373b3515748a09c9c7e62e70698dd is 24.860ms for 905 entries. Aug 5 22:21:08.014347 systemd-journald[1132]: System Journal (/var/log/journal/139373b3515748a09c9c7e62e70698dd) is 8.0M, max 584.8M, 576.8M free. Aug 5 22:21:08.096424 systemd-journald[1132]: Received client request to flush runtime journal. Aug 5 22:21:08.018228 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 22:21:08.031469 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 22:21:08.037366 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:21:08.040579 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:21:08.041468 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 22:21:08.044813 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 22:21:08.074707 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 22:21:08.079427 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 22:21:08.090870 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 22:21:08.091681 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 22:21:08.109123 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 22:21:08.112591 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 22:21:08.118137 udevadm[1179]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 5 22:21:08.124843 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Aug 5 22:21:08.125660 systemd-tmpfiles[1169]: ACLs are not supported, ignoring. Aug 5 22:21:08.131800 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 22:21:08.141106 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 22:21:08.182707 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 22:21:08.190232 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 22:21:08.212764 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Aug 5 22:21:08.213221 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Aug 5 22:21:08.218628 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 22:21:08.813659 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 22:21:08.826315 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 22:21:08.863550 systemd-udevd[1199]: Using default interface naming scheme 'v255'. Aug 5 22:21:08.896479 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 22:21:08.907198 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 22:21:08.941093 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 22:21:08.980850 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Aug 5 22:21:09.002985 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1214) Aug 5 22:21:09.019940 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1201) Aug 5 22:21:09.066423 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 22:21:09.097698 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 22:21:09.107985 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Aug 5 22:21:09.113957 kernel: ACPI: button: Power Button [PWRF] Aug 5 22:21:09.129965 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Aug 5 22:21:09.160092 systemd-networkd[1209]: lo: Link UP Aug 5 22:21:09.160102 systemd-networkd[1209]: lo: Gained carrier Aug 5 22:21:09.161382 systemd-networkd[1209]: Enumeration completed Aug 5 22:21:09.161491 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 22:21:09.161775 systemd-networkd[1209]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:21:09.161779 systemd-networkd[1209]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 22:21:09.162434 systemd-networkd[1209]: eth0: Link UP Aug 5 22:21:09.162444 systemd-networkd[1209]: eth0: Gained carrier Aug 5 22:21:09.162457 systemd-networkd[1209]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 22:21:09.169314 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 22:21:09.172935 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Aug 5 22:21:09.174965 systemd-networkd[1209]: eth0: DHCPv4 address 172.24.4.186/24, gateway 172.24.4.1 acquired from 172.24.4.1 Aug 5 22:21:09.194073 kernel: mousedev: PS/2 mouse device common for all mice Aug 5 22:21:09.200010 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:21:09.226212 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Aug 5 22:21:09.226311 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Aug 5 22:21:09.230375 kernel: Console: switching to colour dummy device 80x25 Aug 5 22:21:09.231233 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Aug 5 22:21:09.231271 kernel: [drm] features: -context_init Aug 5 22:21:09.233114 kernel: [drm] number of scanouts: 1 Aug 5 22:21:09.233197 kernel: [drm] number of cap sets: 0 Aug 5 22:21:09.234205 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Aug 5 22:21:09.243789 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Aug 5 22:21:09.243856 kernel: Console: switching to colour frame buffer device 128x48 Aug 5 22:21:09.251062 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 22:21:09.251358 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:21:09.252187 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Aug 5 22:21:09.256203 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 22:21:09.267269 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 22:21:09.271068 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 22:21:09.297807 lvm[1246]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:21:09.325695 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 22:21:09.326006 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 22:21:09.336260 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 22:21:09.337366 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 22:21:09.344957 lvm[1253]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 22:21:09.383123 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 22:21:09.383970 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 22:21:09.384147 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). Aug 5 22:21:09.384176 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 22:21:09.384305 systemd[1]: Reached target machines.target - Containers. Aug 5 22:21:09.387733 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 22:21:09.396188 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 22:21:09.404087 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 22:21:09.405524 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:21:09.407078 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 22:21:09.416146 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 22:21:09.425180 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 22:21:09.429652 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 22:21:09.449445 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 22:21:09.474381 kernel: loop0: detected capacity change from 0 to 80568 Aug 5 22:21:09.477756 kernel: block loop0: the capability attribute has been deprecated. Aug 5 22:21:09.493053 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 22:21:09.494377 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 22:21:09.541186 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 22:21:09.576978 kernel: loop1: detected capacity change from 0 to 139904 Aug 5 22:21:09.656023 kernel: loop2: detected capacity change from 0 to 209816 Aug 5 22:21:09.738316 kernel: loop3: detected capacity change from 0 to 8 Aug 5 22:21:09.766597 kernel: loop4: detected capacity change from 0 to 80568 Aug 5 22:21:09.798326 kernel: loop5: detected capacity change from 0 to 139904 Aug 5 22:21:09.846008 kernel: loop6: detected capacity change from 0 to 209816 Aug 5 22:21:09.896033 kernel: loop7: detected capacity change from 0 to 8 Aug 5 22:21:09.897801 (sd-merge)[1276]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-openstack'. Aug 5 22:21:09.900991 (sd-merge)[1276]: Merged extensions into '/usr'. Aug 5 22:21:09.913024 systemd[1]: Reloading requested from client PID 1263 ('systemd-sysext') (unit systemd-sysext.service)... Aug 5 22:21:09.913419 systemd[1]: Reloading... Aug 5 22:21:10.040966 zram_generator::config[1308]: No configuration found. Aug 5 22:21:10.211872 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. Aug 5 22:21:10.298020 systemd[1]: Reloading finished in 383 ms. Aug 5 22:21:10.321837 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 22:21:10.335954 systemd[1]: Starting ensure-sysext.service... Aug 5 22:21:10.348071 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 22:21:10.364627 systemd[1]: Reloading requested from client PID 1363 ('systemctl') (unit ensure-sysext.service)... Aug 5 22:21:10.364667 systemd[1]: Reloading... Aug 5 22:21:10.381321 systemd-tmpfiles[1364]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 22:21:10.382112 systemd-tmpfiles[1364]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 22:21:10.383146 systemd-tmpfiles[1364]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 22:21:10.383585 systemd-tmpfiles[1364]: ACLs are not supported, ignoring. Aug 5 22:21:10.383719 systemd-tmpfiles[1364]: ACLs are not supported, ignoring. Aug 5 22:21:10.386807 systemd-tmpfiles[1364]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:21:10.386889 systemd-tmpfiles[1364]: Skipping /boot Aug 5 22:21:10.394116 systemd-tmpfiles[1364]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 22:21:10.394214 systemd-tmpfiles[1364]: Skipping /boot Aug 5 22:21:10.457828 ldconfig[1260]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 22:21:10.470415 zram_generator::config[1393]: No configuration found. Aug 5 22:21:10.629308 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. Aug 5 22:21:10.703140 systemd[1]: Reloading finished in 337 ms. Aug 5 22:21:10.720669 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 22:21:10.735501 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 22:21:10.758810 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 22:21:10.795066 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 22:21:10.801104 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 22:21:10.810086 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 22:21:10.816093 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 22:21:10.825412 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.825752 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:21:10.831207 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:21:10.845109 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:21:10.860048 systemd-networkd[1209]: eth0: Gained IPv6LL Aug 5 22:21:10.861496 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:21:10.868234 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:21:10.868513 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.872719 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 22:21:10.876457 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:21:10.876777 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:21:10.878737 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:21:10.879123 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:21:10.880852 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:21:10.881170 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:21:10.885760 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:21:10.886634 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:21:10.889426 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.889650 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:21:10.893183 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:21:10.905291 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:21:10.922153 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:21:10.922808 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:21:10.925295 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.928012 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:21:10.928188 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:21:10.930840 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:21:10.931034 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:21:10.933940 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:21:10.934145 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:21:10.944591 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.947005 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 22:21:10.954685 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 22:21:10.968181 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 22:21:10.973891 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 22:21:10.993315 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 22:21:10.994636 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 22:21:10.994824 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Aug 5 22:21:10.998521 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 22:21:10.999594 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 22:21:10.999761 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 22:21:11.009708 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 22:21:11.009902 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 22:21:11.012747 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 22:21:11.013965 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 22:21:11.015273 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 22:21:11.015624 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 22:21:11.023866 systemd[1]: Finished ensure-sysext.service. Aug 5 22:21:11.030358 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 22:21:11.030413 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 22:21:11.037059 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 5 22:21:11.067163 systemd-resolved[1463]: Positive Trust Anchors: Aug 5 22:21:11.067184 systemd-resolved[1463]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 22:21:11.067290 systemd-resolved[1463]: 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 corp home internal intranet lan local private test Aug 5 22:21:11.115266 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 5 22:21:11.116736 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 22:21:11.659060 systemd-timesyncd[1514]: Contacted time server 82.67.71.253:123 (0.flatcar.pool.ntp.org). Aug 5 22:21:11.659134 systemd-timesyncd[1514]: Initial clock synchronization to Mon 2024-08-05 22:21:11.658831 UTC. Aug 5 22:21:11.663553 systemd-resolved[1463]: Using system hostname 'ci-3975-2-0-6-6e1d881ec2.novalocal'. Aug 5 22:21:11.667213 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 22:21:11.668282 systemd[1]: Reached target network.target - Network. Aug 5 22:21:11.669015 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 22:21:11.672344 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 22:21:11.743321 augenrules[1520]: No rules Aug 5 22:21:11.744776 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 22:21:12.017634 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 22:21:12.030710 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 22:21:12.078846 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 22:21:12.156973 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 22:21:12.160668 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). Aug 5 22:21:12.160789 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 22:21:12.164658 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 22:21:12.165802 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 22:21:12.170596 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 22:21:12.173033 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 22:21:12.175282 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 22:21:12.177530 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 22:21:12.177918 systemd[1]: Reached target paths.target - Path Units. Aug 5 22:21:12.180394 systemd[1]: Reached target timers.target - Timer Units. Aug 5 22:21:12.184867 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 22:21:12.192062 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 22:21:12.199830 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 22:21:12.205767 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 22:21:12.207736 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 22:21:12.210043 systemd[1]: Reached target basic.target - Basic System. Aug 5 22:21:12.212508 systemd[1]: System is tainted: cgroupsv1 Aug 5 22:21:12.212780 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:21:12.212841 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 22:21:12.222407 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 22:21:12.238506 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 5 22:21:12.249490 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 22:21:12.256496 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 22:21:12.267527 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 22:21:12.268884 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). Aug 5 22:21:12.277187 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:21:12.289017 jq[1541]: false Aug 5 22:21:12.291476 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 22:21:12.305899 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 22:21:12.315541 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 22:21:12.323909 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 22:21:12.329227 dbus-daemon[1539]: [system] SELinux support is enabled Aug 5 22:21:12.339420 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 22:21:12.341587 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 22:21:12.349605 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 22:21:12.352441 extend-filesystems[1543]: Found loop4 Aug 5 22:21:12.352441 extend-filesystems[1543]: Found loop5 Aug 5 22:21:12.352441 extend-filesystems[1543]: Found loop6 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found loop7 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda1 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda2 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda3 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found usr Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda4 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda6 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda7 Aug 5 22:21:12.358166 extend-filesystems[1543]: Found vda9 Aug 5 22:21:12.358166 extend-filesystems[1543]: Checking size of /dev/vda9 Aug 5 22:21:12.368125 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 22:21:12.384610 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 22:21:12.398739 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 22:21:12.398983 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 22:21:12.401126 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 22:21:12.401396 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 22:21:12.405776 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 22:21:12.406026 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 22:21:12.421779 jq[1563]: true Aug 5 22:21:12.439529 update_engine[1560]: I0805 22:21:12.439147 1560 main.cc:92] Flatcar Update Engine starting Aug 5 22:21:12.447460 update_engine[1560]: I0805 22:21:12.447418 1560 update_check_scheduler.cc:74] Next update check in 6m40s Aug 5 22:21:12.457160 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 22:21:12.461703 extend-filesystems[1543]: Resized partition /dev/vda9 Aug 5 22:21:12.459866 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). Aug 5 22:21:12.463203 extend-filesystems[1587]: resize2fs 1.47.0 (5-Feb-2023) Aug 5 22:21:12.459892 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 22:21:12.460341 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). Aug 5 22:21:12.460359 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 22:21:12.475050 jq[1573]: true Aug 5 22:21:12.464540 (ntainerd)[1585]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 22:21:12.465675 systemd[1]: Started update-engine.service - Update Engine. Aug 5 22:21:12.467139 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 22:21:12.478524 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 22:21:12.497255 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks Aug 5 22:21:12.504894 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 22:21:12.520093 systemd-logind[1559]: New seat seat0. Aug 5 22:21:12.529807 systemd-logind[1559]: Watching system buttons on /dev/input/event1 (Power Button) Aug 5 22:21:12.529832 systemd-logind[1559]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Aug 5 22:21:12.530082 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 22:21:12.585606 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1610) Aug 5 22:21:12.690972 locksmithd[1589]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 5 22:21:12.741497 kernel: EXT4-fs (vda9): resized filesystem to 4635643 Aug 5 22:21:12.826393 extend-filesystems[1587]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 5 22:21:12.826393 extend-filesystems[1587]: old_desc_blocks = 1, new_desc_blocks = 3 Aug 5 22:21:12.826393 extend-filesystems[1587]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long. Aug 5 22:21:12.850776 extend-filesystems[1543]: Resized filesystem in /dev/vda9 Aug 5 22:21:12.831810 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 22:21:12.837353 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 22:21:12.860732 bash[1620]: Updated "/home/core/.ssh/authorized_keys" Aug 5 22:21:12.863247 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 22:21:12.877915 systemd[1]: Starting sshkeys.service... Aug 5 22:21:12.908813 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Aug 5 22:21:12.919335 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Aug 5 22:21:12.962241 sshd_keygen[1567]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 22:21:13.012910 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 22:21:13.022748 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 22:21:13.039918 systemd[1]: Started sshd@0-172.24.4.186:22-172.24.4.1:60990.service - OpenSSH per-connection server daemon (172.24.4.1:60990). Aug 5 22:21:13.050998 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 22:21:13.051285 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 22:21:13.068185 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 22:21:13.090674 containerd[1585]: time="2024-08-05T22:21:13.090596679Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Aug 5 22:21:13.105613 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 22:21:13.118139 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 22:21:13.124312 containerd[1585]: time="2024-08-05T22:21:13.124215922Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 22:21:13.124440 containerd[1585]: time="2024-08-05T22:21:13.124424604Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.127353 containerd[1585]: time="2024-08-05T22:21:13.127313201Z" 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.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:21:13.127476 containerd[1585]: time="2024-08-05T22:21:13.127459906Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.127786038Z" 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 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.127813630Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.127903799Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.127957850Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.127972578Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.128045334Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.128270386Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.128290825Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 22:21:13.128328 containerd[1585]: time="2024-08-05T22:21:13.128302246Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 22:21:13.128687 containerd[1585]: time="2024-08-05T22:21:13.128666068Z" 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 Aug 5 22:21:13.128753 containerd[1585]: time="2024-08-05T22:21:13.128739907Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 22:21:13.128864 containerd[1585]: time="2024-08-05T22:21:13.128845946Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 22:21:13.128921 containerd[1585]: time="2024-08-05T22:21:13.128909395Z" level=info msg="metadata content store policy set" policy=shared Aug 5 22:21:13.135415 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 5 22:21:13.140583 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151227012Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151303335Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151323072Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151369208Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151420845Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151436414Z" level=info msg="NRI interface is disabled by configuration." Aug 5 22:21:13.151437 containerd[1585]: time="2024-08-05T22:21:13.151451112Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151614067Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151636249Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151651959Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151669321Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151686573Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151706912Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151725146Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151740505Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151758268Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151774398Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151812720Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151829552Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 22:21:13.152378 containerd[1585]: time="2024-08-05T22:21:13.151938065Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 22:21:13.153187 containerd[1585]: time="2024-08-05T22:21:13.152907523Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 22:21:13.153187 containerd[1585]: time="2024-08-05T22:21:13.152964731Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153187 containerd[1585]: time="2024-08-05T22:21:13.152991962Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 22:21:13.153187 containerd[1585]: time="2024-08-05T22:21:13.153035944Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 22:21:13.153525 containerd[1585]: time="2024-08-05T22:21:13.153329845Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153525 containerd[1585]: time="2024-08-05T22:21:13.153363288Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153525 containerd[1585]: time="2024-08-05T22:21:13.153386281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153525 containerd[1585]: time="2024-08-05T22:21:13.153494504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153525 containerd[1585]: time="2024-08-05T22:21:13.153516996Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153821 containerd[1585]: time="2024-08-05T22:21:13.153539038Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153821 containerd[1585]: time="2024-08-05T22:21:13.153560037Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153821 containerd[1585]: time="2024-08-05T22:21:13.153666547Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.153821 containerd[1585]: time="2024-08-05T22:21:13.153696132Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.153858807Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.153887351Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.153968964Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.153991326Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.154010722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154089 containerd[1585]: time="2024-08-05T22:21:13.154033685Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154485 containerd[1585]: time="2024-08-05T22:21:13.154095501Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.154485 containerd[1585]: time="2024-08-05T22:21:13.154114717Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 22:21:13.155045 containerd[1585]: time="2024-08-05T22:21:13.154684226Z" 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:false] 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:false 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}" Aug 5 22:21:13.155045 containerd[1585]: time="2024-08-05T22:21:13.154989157Z" level=info msg="Connect containerd service" Aug 5 22:21:13.155045 containerd[1585]: time="2024-08-05T22:21:13.155046275Z" level=info msg="using legacy CRI server" Aug 5 22:21:13.155428 containerd[1585]: time="2024-08-05T22:21:13.155061964Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 22:21:13.156371 containerd[1585]: time="2024-08-05T22:21:13.155781794Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 22:21:13.157390 containerd[1585]: time="2024-08-05T22:21:13.157318777Z" 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" Aug 5 22:21:13.157620 containerd[1585]: time="2024-08-05T22:21:13.157588022Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 22:21:13.157741 containerd[1585]: time="2024-08-05T22:21:13.157677790Z" level=info msg="Start subscribing containerd event" Aug 5 22:21:13.157799 containerd[1585]: time="2024-08-05T22:21:13.157757810Z" level=info msg="Start recovering state" Aug 5 22:21:13.157884 containerd[1585]: time="2024-08-05T22:21:13.157830687Z" level=info msg="Start event monitor" Aug 5 22:21:13.157884 containerd[1585]: time="2024-08-05T22:21:13.157851746Z" level=info msg="Start snapshots syncer" Aug 5 22:21:13.157884 containerd[1585]: time="2024-08-05T22:21:13.157709700Z" 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 Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.157865001Z" level=info msg="Start cni network conf syncer for default" Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.158967308Z" level=info msg="Start streaming server" Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.158972037Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.159006051Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.159311093Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.159371356Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 22:21:13.159465 containerd[1585]: time="2024-08-05T22:21:13.159445906Z" level=info msg="containerd successfully booted in 0.070332s" Aug 5 22:21:13.160259 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 22:21:15.129805 sshd[1651]: Accepted publickey for core from 172.24.4.1 port 60990 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:15.142873 sshd[1651]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:15.169999 systemd-logind[1559]: New session 1 of user core. Aug 5 22:21:15.175010 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 22:21:15.188008 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 22:21:15.257611 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 22:21:15.278551 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 22:21:15.316088 (systemd)[1672]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:15.620789 systemd[1672]: Queued start job for default target default.target. Aug 5 22:21:15.621498 systemd[1672]: Created slice app.slice - User Application Slice. Aug 5 22:21:15.621523 systemd[1672]: Reached target paths.target - Paths. Aug 5 22:21:15.621538 systemd[1672]: Reached target timers.target - Timers. Aug 5 22:21:15.631420 systemd[1672]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 22:21:15.652575 systemd[1672]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 22:21:15.652744 systemd[1672]: Reached target sockets.target - Sockets. Aug 5 22:21:15.652823 systemd[1672]: Reached target basic.target - Basic System. Aug 5 22:21:15.652932 systemd[1672]: Reached target default.target - Main User Target. Aug 5 22:21:15.653043 systemd[1672]: Startup finished in 315ms. Aug 5 22:21:15.653208 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 22:21:15.676026 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 22:21:16.036551 systemd[1]: Started sshd@1-172.24.4.186:22-172.24.4.1:37628.service - OpenSSH per-connection server daemon (172.24.4.1:37628). Aug 5 22:21:17.038591 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:17.051599 (kubelet)[1696]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 5 22:21:17.443228 sshd[1686]: Accepted publickey for core from 172.24.4.1 port 37628 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:17.446293 sshd[1686]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:17.457298 systemd-logind[1559]: New session 2 of user core. Aug 5 22:21:17.470200 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 22:21:18.087558 sshd[1686]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:18.101484 systemd[1]: Started sshd@2-172.24.4.186:22-172.24.4.1:37638.service - OpenSSH per-connection server daemon (172.24.4.1:37638). Aug 5 22:21:18.113817 systemd[1]: sshd@1-172.24.4.186:22-172.24.4.1:37628.service: Deactivated successfully. Aug 5 22:21:18.117745 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 22:21:18.120841 systemd-logind[1559]: Session 2 logged out. Waiting for processes to exit. Aug 5 22:21:18.124489 systemd-logind[1559]: Removed session 2. Aug 5 22:21:18.167507 login[1660]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 5 22:21:18.176359 systemd-logind[1559]: New session 3 of user core. Aug 5 22:21:18.176609 login[1663]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Aug 5 22:21:18.186673 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 22:21:18.193822 systemd-logind[1559]: New session 4 of user core. Aug 5 22:21:18.201612 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 22:21:18.474650 kubelet[1696]: E0805 22:21:18.474322 1696 run.go:74] "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" Aug 5 22:21:18.480851 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 5 22:21:18.482001 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 5 22:21:19.355642 coreos-metadata[1538]: Aug 05 22:21:19.355 WARN failed to locate config-drive, using the metadata service API instead Aug 5 22:21:19.405416 coreos-metadata[1538]: Aug 05 22:21:19.405 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Aug 5 22:21:19.498100 sshd[1705]: Accepted publickey for core from 172.24.4.1 port 37638 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:19.501351 sshd[1705]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:19.514197 systemd-logind[1559]: New session 5 of user core. Aug 5 22:21:19.521914 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 22:21:19.617991 coreos-metadata[1538]: Aug 05 22:21:19.617 INFO Fetch successful Aug 5 22:21:19.617991 coreos-metadata[1538]: Aug 05 22:21:19.617 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Aug 5 22:21:19.636968 coreos-metadata[1538]: Aug 05 22:21:19.636 INFO Fetch successful Aug 5 22:21:19.636968 coreos-metadata[1538]: Aug 05 22:21:19.636 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Aug 5 22:21:19.652774 coreos-metadata[1538]: Aug 05 22:21:19.652 INFO Fetch successful Aug 5 22:21:19.652774 coreos-metadata[1538]: Aug 05 22:21:19.652 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Aug 5 22:21:19.667827 coreos-metadata[1538]: Aug 05 22:21:19.667 INFO Fetch successful Aug 5 22:21:19.668002 coreos-metadata[1538]: Aug 05 22:21:19.667 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Aug 5 22:21:19.682412 coreos-metadata[1538]: Aug 05 22:21:19.682 INFO Fetch successful Aug 5 22:21:19.682412 coreos-metadata[1538]: Aug 05 22:21:19.682 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Aug 5 22:21:19.698207 coreos-metadata[1538]: Aug 05 22:21:19.698 INFO Fetch successful Aug 5 22:21:19.739740 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 5 22:21:19.742638 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 22:21:20.034835 coreos-metadata[1637]: Aug 05 22:21:20.034 WARN failed to locate config-drive, using the metadata service API instead Aug 5 22:21:20.079910 coreos-metadata[1637]: Aug 05 22:21:20.079 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Aug 5 22:21:20.097832 coreos-metadata[1637]: Aug 05 22:21:20.097 INFO Fetch successful Aug 5 22:21:20.097832 coreos-metadata[1637]: Aug 05 22:21:20.097 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Aug 5 22:21:20.113136 coreos-metadata[1637]: Aug 05 22:21:20.113 INFO Fetch successful Aug 5 22:21:20.116898 unknown[1637]: wrote ssh authorized keys file for user: core Aug 5 22:21:20.140471 sshd[1705]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:20.146574 systemd[1]: sshd@2-172.24.4.186:22-172.24.4.1:37638.service: Deactivated successfully. Aug 5 22:21:20.155505 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 22:21:20.158341 systemd-logind[1559]: Session 5 logged out. Waiting for processes to exit. Aug 5 22:21:20.160788 systemd-logind[1559]: Removed session 5. Aug 5 22:21:20.167309 update-ssh-keys[1749]: Updated "/home/core/.ssh/authorized_keys" Aug 5 22:21:20.170504 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Aug 5 22:21:20.178217 systemd[1]: Finished sshkeys.service. Aug 5 22:21:20.184610 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 22:21:20.185047 systemd[1]: Startup finished in 16.499s (kernel) + 13.583s (userspace) = 30.083s. Aug 5 22:21:28.732100 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Aug 5 22:21:28.739594 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:21:28.928563 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:28.945955 (kubelet)[1771]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 5 22:21:29.399669 kubelet[1771]: E0805 22:21:29.399564 1771 run.go:74] "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" Aug 5 22:21:29.406840 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 5 22:21:29.407171 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 5 22:21:30.156745 systemd[1]: Started sshd@3-172.24.4.186:22-172.24.4.1:40656.service - OpenSSH per-connection server daemon (172.24.4.1:40656). Aug 5 22:21:31.535680 sshd[1781]: Accepted publickey for core from 172.24.4.1 port 40656 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:31.537440 sshd[1781]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:31.545720 systemd-logind[1559]: New session 6 of user core. Aug 5 22:21:31.553815 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 5 22:21:32.187164 sshd[1781]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:32.193703 systemd[1]: Started sshd@4-172.24.4.186:22-172.24.4.1:40664.service - OpenSSH per-connection server daemon (172.24.4.1:40664). Aug 5 22:21:32.195826 systemd[1]: sshd@3-172.24.4.186:22-172.24.4.1:40656.service: Deactivated successfully. Aug 5 22:21:32.204068 systemd[1]: session-6.scope: Deactivated successfully. Aug 5 22:21:32.206375 systemd-logind[1559]: Session 6 logged out. Waiting for processes to exit. Aug 5 22:21:32.208977 systemd-logind[1559]: Removed session 6. Aug 5 22:21:33.603878 sshd[1786]: Accepted publickey for core from 172.24.4.1 port 40664 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:33.606797 sshd[1786]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:33.617746 systemd-logind[1559]: New session 7 of user core. Aug 5 22:21:33.628813 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 5 22:21:34.255536 sshd[1786]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:34.269938 systemd[1]: Started sshd@5-172.24.4.186:22-172.24.4.1:40668.service - OpenSSH per-connection server daemon (172.24.4.1:40668). Aug 5 22:21:34.274004 systemd[1]: sshd@4-172.24.4.186:22-172.24.4.1:40664.service: Deactivated successfully. Aug 5 22:21:34.283347 systemd[1]: session-7.scope: Deactivated successfully. Aug 5 22:21:34.286796 systemd-logind[1559]: Session 7 logged out. Waiting for processes to exit. Aug 5 22:21:34.290441 systemd-logind[1559]: Removed session 7. Aug 5 22:21:35.473513 sshd[1794]: Accepted publickey for core from 172.24.4.1 port 40668 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:35.476292 sshd[1794]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:35.486944 systemd-logind[1559]: New session 8 of user core. Aug 5 22:21:35.496961 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 5 22:21:36.096578 sshd[1794]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:36.106876 systemd[1]: Started sshd@6-172.24.4.186:22-172.24.4.1:56514.service - OpenSSH per-connection server daemon (172.24.4.1:56514). Aug 5 22:21:36.107934 systemd[1]: sshd@5-172.24.4.186:22-172.24.4.1:40668.service: Deactivated successfully. Aug 5 22:21:36.115565 systemd-logind[1559]: Session 8 logged out. Waiting for processes to exit. Aug 5 22:21:36.116638 systemd[1]: session-8.scope: Deactivated successfully. Aug 5 22:21:36.122810 systemd-logind[1559]: Removed session 8. Aug 5 22:21:37.605926 sshd[1802]: Accepted publickey for core from 172.24.4.1 port 56514 ssh2: RSA SHA256:wJOsecOnS68Cf9bfpO6HbyavHDudnwLnl2CjsDHwoC8 Aug 5 22:21:37.608769 sshd[1802]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 22:21:37.619435 systemd-logind[1559]: New session 9 of user core. Aug 5 22:21:37.631743 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 5 22:21:38.188385 sudo[1809]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 5 22:21:38.189008 sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 22:21:39.658661 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Aug 5 22:21:39.668737 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:21:40.716966 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:40.736006 (kubelet)[1835]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 5 22:21:40.836167 kubelet[1835]: E0805 22:21:40.834841 1835 run.go:74] "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" Aug 5 22:21:40.844488 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 5 22:21:40.844644 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 5 22:21:41.205341 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:41.221746 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:21:41.269931 systemd[1]: Reloading requested from client PID 1869 ('systemctl') (unit session-9.scope)... Aug 5 22:21:41.269960 systemd[1]: Reloading... Aug 5 22:21:41.352337 zram_generator::config[1905]: No configuration found. Aug 5 22:21:41.845891 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. Aug 5 22:21:41.925807 systemd[1]: Reloading finished in 655 ms. Aug 5 22:21:41.972380 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Aug 5 22:21:41.972465 systemd[1]: kubelet.service: Failed with result 'signal'. Aug 5 22:21:41.972834 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:41.983283 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 5 22:21:42.104422 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 5 22:21:42.105452 (kubelet)[1981]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 5 22:21:42.180330 kubelet[1981]: 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. Aug 5 22:21:42.180330 kubelet[1981]: 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. Aug 5 22:21:42.180330 kubelet[1981]: 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. Aug 5 22:21:42.180728 kubelet[1981]: I0805 22:21:42.180372 1981 server.go:203] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 5 22:21:43.218952 kubelet[1981]: I0805 22:21:43.218907 1981 server.go:467] "Kubelet version" kubeletVersion="v1.28.7" Aug 5 22:21:43.218952 kubelet[1981]: I0805 22:21:43.218939 1981 server.go:469] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 5 22:21:43.219697 kubelet[1981]: I0805 22:21:43.219168 1981 server.go:895] "Client rotation is on, will bootstrap in background" Aug 5 22:21:43.241730 kubelet[1981]: I0805 22:21:43.241483 1981 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 5 22:21:43.255630 kubelet[1981]: I0805 22:21:43.255607 1981 server.go:725] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 5 22:21:43.257707 kubelet[1981]: I0805 22:21:43.257461 1981 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 5 22:21:43.257707 kubelet[1981]: I0805 22:21:43.257642 1981 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Aug 5 22:21:43.258276 kubelet[1981]: I0805 22:21:43.258218 1981 topology_manager.go:138] "Creating topology manager with none policy" Aug 5 22:21:43.258350 kubelet[1981]: I0805 22:21:43.258340 1981 container_manager_linux.go:301] "Creating device plugin manager" Aug 5 22:21:43.259614 kubelet[1981]: I0805 22:21:43.259504 1981 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:21:43.261270 kubelet[1981]: I0805 22:21:43.261250 1981 kubelet.go:393] "Attempting to sync node with API server" Aug 5 22:21:43.261523 kubelet[1981]: I0805 22:21:43.261341 1981 kubelet.go:298] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 5 22:21:43.261523 kubelet[1981]: I0805 22:21:43.261375 1981 kubelet.go:309] "Adding apiserver pod source" Aug 5 22:21:43.261523 kubelet[1981]: I0805 22:21:43.261388 1981 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 5 22:21:43.262832 kubelet[1981]: E0805 22:21:43.262343 1981 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:43.262832 kubelet[1981]: E0805 22:21:43.262520 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:43.263658 kubelet[1981]: I0805 22:21:43.263631 1981 kuberuntime_manager.go:257] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Aug 5 22:21:43.267392 kubelet[1981]: W0805 22:21:43.267346 1981 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 5 22:21:43.268681 kubelet[1981]: I0805 22:21:43.268410 1981 server.go:1232] "Started kubelet" Aug 5 22:21:43.274292 kubelet[1981]: I0805 22:21:43.274106 1981 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 5 22:21:43.275433 kubelet[1981]: E0805 22:21:43.275419 1981 cri_stats_provider.go:448] "Failed to get the info of the filesystem with mountpoint" err="unable to find data in memory cache" mountpoint="/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs" Aug 5 22:21:43.275577 kubelet[1981]: E0805 22:21:43.275524 1981 kubelet.go:1431] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 5 22:21:43.277478 kubelet[1981]: I0805 22:21:43.277468 1981 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Aug 5 22:21:43.278386 kubelet[1981]: I0805 22:21:43.278374 1981 server.go:462] "Adding debug handlers to kubelet server" Aug 5 22:21:43.279614 kubelet[1981]: I0805 22:21:43.279599 1981 volume_manager.go:291] "Starting Kubelet Volume Manager" Aug 5 22:21:43.281657 kubelet[1981]: I0805 22:21:43.281625 1981 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Aug 5 22:21:43.281887 kubelet[1981]: I0805 22:21:43.281863 1981 reconciler_new.go:29] "Reconciler: start to sync state" Aug 5 22:21:43.282791 kubelet[1981]: I0805 22:21:43.282685 1981 ratelimit.go:65] "Setting rate limiting for podresources endpoint" qps=100 burstTokens=10 Aug 5 22:21:43.283023 kubelet[1981]: I0805 22:21:43.283012 1981 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 5 22:21:43.307859 kubelet[1981]: E0805 22:21:43.307057 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dce12c204", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"Starting", Message:"Starting kubelet.", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 268360708, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 268360708, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.309557 kubelet[1981]: E0805 22:21:43.309520 1981 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.24.4.186\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Aug 5 22:21:43.309869 kubelet[1981]: W0805 22:21:43.309740 1981 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Aug 5 22:21:43.309869 kubelet[1981]: E0805 22:21:43.309763 1981 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Aug 5 22:21:43.310023 kubelet[1981]: W0805 22:21:43.310010 1981 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:21:43.310717 kubelet[1981]: E0805 22:21:43.310638 1981 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Aug 5 22:21:43.310717 kubelet[1981]: W0805 22:21:43.310397 1981 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.Node: nodes "172.24.4.186" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:21:43.310717 kubelet[1981]: E0805 22:21:43.310657 1981 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.24.4.186" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 5 22:21:43.321926 kubelet[1981]: E0805 22:21:43.321716 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dce7fdd1d", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"InvalidDiskCapacity", Message:"invalid capacity 0 on image filesystem", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 275511069, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 275511069, time.Local), Count:1, Type:"Warning", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.340695 kubelet[1981]: I0805 22:21:43.340452 1981 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 5 22:21:43.340695 kubelet[1981]: I0805 22:21:43.340513 1981 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 5 22:21:43.340695 kubelet[1981]: I0805 22:21:43.340539 1981 state_mem.go:36] "Initialized new in-memory state store" Aug 5 22:21:43.342906 kubelet[1981]: E0805 22:21:43.342584 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd2488d8b", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 172.24.4.186 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 338995083, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 338995083, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.345244 kubelet[1981]: E0805 22:21:43.344903 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248b9a6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 172.24.4.186 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339006374, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339006374, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.346383 kubelet[1981]: E0805 22:21:43.346319 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248d352", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 172.24.4.186 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339012946, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339012946, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.347253 kubelet[1981]: I0805 22:21:43.347098 1981 policy_none.go:49] "None policy: Start" Aug 5 22:21:43.348207 kubelet[1981]: I0805 22:21:43.348197 1981 memory_manager.go:169] "Starting memorymanager" policy="None" Aug 5 22:21:43.348368 kubelet[1981]: I0805 22:21:43.348302 1981 state_mem.go:35] "Initializing new in-memory state store" Aug 5 22:21:43.357921 kubelet[1981]: I0805 22:21:43.357675 1981 manager.go:471] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 5 22:21:43.358052 kubelet[1981]: I0805 22:21:43.358028 1981 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 5 22:21:43.361056 kubelet[1981]: E0805 22:21:43.361041 1981 eviction_manager.go:258] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.24.4.186\" not found" Aug 5 22:21:43.361619 kubelet[1981]: E0805 22:21:43.361441 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd3824ba4", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeAllocatableEnforced", Message:"Updated Node Allocatable limit across pods", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 359556516, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 359556516, time.Local), Count:1, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events is forbidden: User "system:anonymous" cannot create resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.381892 kubelet[1981]: I0805 22:21:43.381852 1981 kubelet_node_status.go:70] "Attempting to register node" node="172.24.4.186" Aug 5 22:21:43.383836 kubelet[1981]: E0805 22:21:43.383804 1981 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="172.24.4.186" Aug 5 22:21:43.385067 kubelet[1981]: E0805 22:21:43.384798 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd2488d8b", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 172.24.4.186 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 338995083, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 381791417, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd2488d8b" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.386091 kubelet[1981]: E0805 22:21:43.386030 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248b9a6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 172.24.4.186 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339006374, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 381801286, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248b9a6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.387493 kubelet[1981]: E0805 22:21:43.387421 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248d352", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 172.24.4.186 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339012946, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 381805083, time.Local), Count:2, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248d352" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.450845 kubelet[1981]: I0805 22:21:43.450743 1981 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 5 22:21:43.453460 kubelet[1981]: I0805 22:21:43.453414 1981 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 5 22:21:43.453460 kubelet[1981]: I0805 22:21:43.453466 1981 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 5 22:21:43.453668 kubelet[1981]: I0805 22:21:43.453518 1981 kubelet.go:2303] "Starting kubelet main sync loop" Aug 5 22:21:43.453668 kubelet[1981]: E0805 22:21:43.453617 1981 kubelet.go:2327] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Aug 5 22:21:43.458168 kubelet[1981]: W0805 22:21:43.457867 1981 reflector.go:535] vendor/k8s.io/client-go/informers/factory.go:150: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Aug 5 22:21:43.458168 kubelet[1981]: E0805 22:21:43.457935 1981 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:150: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: runtimeclasses.node.k8s.io is forbidden: User "system:anonymous" cannot list resource "runtimeclasses" in API group "node.k8s.io" at the cluster scope Aug 5 22:21:43.512835 kubelet[1981]: E0805 22:21:43.512575 1981 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.24.4.186\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="400ms" Aug 5 22:21:43.586842 kubelet[1981]: I0805 22:21:43.586340 1981 kubelet_node_status.go:70] "Attempting to register node" node="172.24.4.186" Aug 5 22:21:43.589026 kubelet[1981]: E0805 22:21:43.588955 1981 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="172.24.4.186" Aug 5 22:21:43.589026 kubelet[1981]: E0805 22:21:43.588880 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd2488d8b", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 172.24.4.186 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 338995083, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 585961168, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd2488d8b" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.590935 kubelet[1981]: E0805 22:21:43.590756 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248b9a6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 172.24.4.186 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339006374, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 585986896, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248b9a6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.593026 kubelet[1981]: E0805 22:21:43.592895 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248d352", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 172.24.4.186 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339012946, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 586196780, time.Local), Count:3, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248d352" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.915927 kubelet[1981]: E0805 22:21:43.915852 1981 controller.go:146] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.24.4.186\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="800ms" Aug 5 22:21:43.991036 kubelet[1981]: I0805 22:21:43.990934 1981 kubelet_node_status.go:70] "Attempting to register node" node="172.24.4.186" Aug 5 22:21:43.992840 kubelet[1981]: E0805 22:21:43.992758 1981 kubelet_node_status.go:92] "Unable to register node with API server" err="nodes is forbidden: User \"system:anonymous\" cannot create resource \"nodes\" in API group \"\" at the cluster scope" node="172.24.4.186" Aug 5 22:21:43.993267 kubelet[1981]: E0805 22:21:43.993079 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd2488d8b", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientMemory", Message:"Node 172.24.4.186 status is now: NodeHasSufficientMemory", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 338995083, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 990830029, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd2488d8b" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.994853 kubelet[1981]: E0805 22:21:43.994707 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248b9a6", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasNoDiskPressure", Message:"Node 172.24.4.186 status is now: NodeHasNoDiskPressure", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339006374, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 990858262, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248b9a6" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:43.996446 kubelet[1981]: E0805 22:21:43.996300 1981 event.go:280] Server rejected event '&v1.Event{TypeMeta:v1.TypeMeta{Kind:"", APIVersion:""}, ObjectMeta:v1.ObjectMeta{Name:"172.24.4.186.17e8f53dd248d352", GenerateName:"", Namespace:"default", SelfLink:"", UID:"", ResourceVersion:"", Generation:0, CreationTimestamp:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), DeletionTimestamp:, DeletionGracePeriodSeconds:(*int64)(nil), Labels:map[string]string(nil), Annotations:map[string]string(nil), OwnerReferences:[]v1.OwnerReference(nil), Finalizers:[]string(nil), ManagedFields:[]v1.ManagedFieldsEntry(nil)}, InvolvedObject:v1.ObjectReference{Kind:"Node", Namespace:"", Name:"172.24.4.186", UID:"172.24.4.186", APIVersion:"", ResourceVersion:"", FieldPath:""}, Reason:"NodeHasSufficientPID", Message:"Node 172.24.4.186 status is now: NodeHasSufficientPID", Source:v1.EventSource{Component:"kubelet", Host:"172.24.4.186"}, FirstTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 339012946, time.Local), LastTimestamp:time.Date(2024, time.August, 5, 22, 21, 43, 990871737, time.Local), Count:4, Type:"Normal", EventTime:time.Date(1, time.January, 1, 0, 0, 0, 0, time.UTC), Series:(*v1.EventSeries)(nil), Action:"", Related:(*v1.ObjectReference)(nil), ReportingController:"kubelet", ReportingInstance:"172.24.4.186"}': 'events "172.24.4.186.17e8f53dd248d352" is forbidden: User "system:anonymous" cannot patch resource "events" in API group "" in the namespace "default"' (will not retry!) Aug 5 22:21:44.221671 kubelet[1981]: I0805 22:21:44.221471 1981 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 5 22:21:44.262674 kubelet[1981]: E0805 22:21:44.262595 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:44.675313 kubelet[1981]: E0805 22:21:44.675214 1981 csi_plugin.go:295] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "172.24.4.186" not found Aug 5 22:21:44.722840 kubelet[1981]: E0805 22:21:44.722776 1981 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.24.4.186\" not found" node="172.24.4.186" Aug 5 22:21:44.795417 kubelet[1981]: I0805 22:21:44.794831 1981 kubelet_node_status.go:70] "Attempting to register node" node="172.24.4.186" Aug 5 22:21:44.839820 kubelet[1981]: I0805 22:21:44.839594 1981 kubelet_node_status.go:73] "Successfully registered node" node="172.24.4.186" Aug 5 22:21:45.263375 kubelet[1981]: I0805 22:21:45.263106 1981 apiserver.go:52] "Watching apiserver" Aug 5 22:21:45.263375 kubelet[1981]: E0805 22:21:45.263290 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:45.274011 kubelet[1981]: I0805 22:21:45.273979 1981 kuberuntime_manager.go:1528] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 5 22:21:45.275371 containerd[1585]: time="2024-08-05T22:21:45.275279400Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 5 22:21:45.276606 kubelet[1981]: I0805 22:21:45.275627 1981 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 5 22:21:45.282648 kubelet[1981]: I0805 22:21:45.282574 1981 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Aug 5 22:21:45.713081 sudo[1809]: pam_unix(sudo:session): session closed for user root Aug 5 22:21:45.815761 kubelet[1981]: I0805 22:21:45.815501 1981 topology_manager.go:215] "Topology Admit Handler" podUID="b43f10e3-fcbb-42ae-980e-9f4d0b5df345" podNamespace="kube-flannel" podName="kube-flannel-ds-pndst" Aug 5 22:21:45.896206 kubelet[1981]: I0805 22:21:45.896129 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-cni-plugin\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.896444 kubelet[1981]: I0805 22:21:45.896288 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-cni\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.896444 kubelet[1981]: I0805 22:21:45.896391 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-flannel-cfg\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.896601 kubelet[1981]: I0805 22:21:45.896453 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-xtables-lock\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.896601 kubelet[1981]: I0805 22:21:45.896511 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4gj7q\" (UniqueName: \"kubernetes.io/projected/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-kube-api-access-4gj7q\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.896601 kubelet[1981]: I0805 22:21:45.896562 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b43f10e3-fcbb-42ae-980e-9f4d0b5df345-run\") pod \"kube-flannel-ds-pndst\" (UID: \"b43f10e3-fcbb-42ae-980e-9f4d0b5df345\") " pod="kube-flannel/kube-flannel-ds-pndst" Aug 5 22:21:45.996450 kubelet[1981]: I0805 22:21:45.994902 1981 topology_manager.go:215] "Topology Admit Handler" podUID="27bb7f13-61cb-4607-a297-3e3393b71d91" podNamespace="kube-system" podName="kube-proxy-7zfdh" Aug 5 22:21:46.006858 sshd[1802]: pam_unix(sshd:session): session closed for user core Aug 5 22:21:46.017332 systemd[1]: sshd@6-172.24.4.186:22-172.24.4.1:56514.service: Deactivated successfully. Aug 5 22:21:46.018610 systemd-logind[1559]: Session 9 logged out. Waiting for processes to exit. Aug 5 22:21:46.023140 systemd[1]: session-9.scope: Deactivated successfully. Aug 5 22:21:46.025713 systemd-logind[1559]: Removed session 9. Aug 5 22:21:46.098809 kubelet[1981]: I0805 22:21:46.098721 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bqjrm\" (UniqueName: \"kubernetes.io/projected/27bb7f13-61cb-4607-a297-3e3393b71d91-kube-api-access-bqjrm\") pod \"kube-proxy-7zfdh\" (UID: \"27bb7f13-61cb-4607-a297-3e3393b71d91\") " pod="kube-system/kube-proxy-7zfdh" Aug 5 22:21:46.099047 kubelet[1981]: I0805 22:21:46.098845 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/27bb7f13-61cb-4607-a297-3e3393b71d91-xtables-lock\") pod \"kube-proxy-7zfdh\" (UID: \"27bb7f13-61cb-4607-a297-3e3393b71d91\") " pod="kube-system/kube-proxy-7zfdh" Aug 5 22:21:46.099047 kubelet[1981]: I0805 22:21:46.098905 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/27bb7f13-61cb-4607-a297-3e3393b71d91-kube-proxy\") pod \"kube-proxy-7zfdh\" (UID: \"27bb7f13-61cb-4607-a297-3e3393b71d91\") " pod="kube-system/kube-proxy-7zfdh" Aug 5 22:21:46.099047 kubelet[1981]: I0805 22:21:46.098959 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/27bb7f13-61cb-4607-a297-3e3393b71d91-lib-modules\") pod \"kube-proxy-7zfdh\" (UID: \"27bb7f13-61cb-4607-a297-3e3393b71d91\") " pod="kube-system/kube-proxy-7zfdh" Aug 5 22:21:46.264380 kubelet[1981]: E0805 22:21:46.264179 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:46.304579 containerd[1585]: time="2024-08-05T22:21:46.304474537Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7zfdh,Uid:27bb7f13-61cb-4607-a297-3e3393b71d91,Namespace:kube-system,Attempt:0,}" Aug 5 22:21:46.421969 containerd[1585]: time="2024-08-05T22:21:46.421899840Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pndst,Uid:b43f10e3-fcbb-42ae-980e-9f4d0b5df345,Namespace:kube-flannel,Attempt:0,}" Aug 5 22:21:47.054678 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount126194888.mount: Deactivated successfully. Aug 5 22:21:47.070998 containerd[1585]: time="2024-08-05T22:21:47.070813996Z" 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\"}" Aug 5 22:21:47.073437 containerd[1585]: time="2024-08-05T22:21:47.073298806Z" 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\"}" Aug 5 22:21:47.074570 containerd[1585]: time="2024-08-05T22:21:47.074448390Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312064" Aug 5 22:21:47.079154 containerd[1585]: time="2024-08-05T22:21:47.076307367Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 5 22:21:47.084142 containerd[1585]: time="2024-08-05T22:21:47.084045024Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 5 22:21:47.088635 containerd[1585]: time="2024-08-05T22:21:47.088593617Z" 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\"}" Aug 5 22:21:47.091410 containerd[1585]: time="2024-08-05T22:21:47.091339311Z" 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 786.636932ms" Aug 5 22:21:47.094829 containerd[1585]: time="2024-08-05T22:21:47.094790359Z" 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 672.69568ms" Aug 5 22:21:47.265311 kubelet[1981]: E0805 22:21:47.265273 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:47.578056 containerd[1585]: time="2024-08-05T22:21:47.577881572Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:21:47.578056 containerd[1585]: time="2024-08-05T22:21:47.577967855Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:21:47.578056 containerd[1585]: time="2024-08-05T22:21:47.577986319Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:21:47.578056 containerd[1585]: time="2024-08-05T22:21:47.577999274Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:21:47.599656 containerd[1585]: time="2024-08-05T22:21:47.590433083Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:21:47.599656 containerd[1585]: time="2024-08-05T22:21:47.590496553Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:21:47.599656 containerd[1585]: time="2024-08-05T22:21:47.590515830Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:21:47.599656 containerd[1585]: time="2024-08-05T22:21:47.590530498Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:21:47.726468 containerd[1585]: time="2024-08-05T22:21:47.726418948Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7zfdh,Uid:27bb7f13-61cb-4607-a297-3e3393b71d91,Namespace:kube-system,Attempt:0,} returns sandbox id \"445889099db85b46bc06b3a736595d7cabbe4149e9a4424fa1ebc7ccac69e6d4\"" Aug 5 22:21:47.732323 containerd[1585]: time="2024-08-05T22:21:47.732283591Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.12\"" Aug 5 22:21:47.736249 containerd[1585]: time="2024-08-05T22:21:47.736190321Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pndst,Uid:b43f10e3-fcbb-42ae-980e-9f4d0b5df345,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\"" Aug 5 22:21:48.266319 kubelet[1981]: E0805 22:21:48.266183 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:49.267337 kubelet[1981]: E0805 22:21:49.267271 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:49.999054 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2607469454.mount: Deactivated successfully. Aug 5 22:21:50.267866 kubelet[1981]: E0805 22:21:50.267419 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:50.519527 containerd[1585]: time="2024-08-05T22:21:50.518483529Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.28.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:50.521290 containerd[1585]: time="2024-08-05T22:21:50.521254974Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.28.12: active requests=0, bytes read=28303777" Aug 5 22:21:50.523712 containerd[1585]: time="2024-08-05T22:21:50.523687878Z" level=info msg="ImageCreate event name:\"sha256:1610963ec6edeaf744dc6bc6475bb85db4736faef7394a1ad6f0ccb9d30d2ab3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:50.527129 containerd[1585]: time="2024-08-05T22:21:50.527079504Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:7dd7829fa889ac805a0b1047eba04599fa5006bdbcb5cb9c8d14e1dc8910488b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:50.527829 containerd[1585]: time="2024-08-05T22:21:50.527799724Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.28.12\" with image id \"sha256:1610963ec6edeaf744dc6bc6475bb85db4736faef7394a1ad6f0ccb9d30d2ab3\", repo tag \"registry.k8s.io/kube-proxy:v1.28.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:7dd7829fa889ac805a0b1047eba04599fa5006bdbcb5cb9c8d14e1dc8910488b\", size \"28302788\" in 2.795445479s" Aug 5 22:21:50.527918 containerd[1585]: time="2024-08-05T22:21:50.527901647Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.28.12\" returns image reference \"sha256:1610963ec6edeaf744dc6bc6475bb85db4736faef7394a1ad6f0ccb9d30d2ab3\"" Aug 5 22:21:50.528862 containerd[1585]: time="2024-08-05T22:21:50.528842243Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 5 22:21:50.530289 containerd[1585]: time="2024-08-05T22:21:50.530256625Z" level=info msg="CreateContainer within sandbox \"445889099db85b46bc06b3a736595d7cabbe4149e9a4424fa1ebc7ccac69e6d4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 5 22:21:50.552135 containerd[1585]: time="2024-08-05T22:21:50.552076707Z" level=info msg="CreateContainer within sandbox \"445889099db85b46bc06b3a736595d7cabbe4149e9a4424fa1ebc7ccac69e6d4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"523adaa0baf849efddb3cd7b7a813718c2568f198a3161546c67c1dabd5b83b9\"" Aug 5 22:21:50.553278 containerd[1585]: time="2024-08-05T22:21:50.552962641Z" level=info msg="StartContainer for \"523adaa0baf849efddb3cd7b7a813718c2568f198a3161546c67c1dabd5b83b9\"" Aug 5 22:21:50.624409 containerd[1585]: time="2024-08-05T22:21:50.624085182Z" level=info msg="StartContainer for \"523adaa0baf849efddb3cd7b7a813718c2568f198a3161546c67c1dabd5b83b9\" returns successfully" Aug 5 22:21:51.268417 kubelet[1981]: E0805 22:21:51.268206 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:52.269443 kubelet[1981]: E0805 22:21:52.269294 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:53.261684 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2885543997.mount: Deactivated successfully. Aug 5 22:21:53.270121 kubelet[1981]: E0805 22:21:53.270093 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:53.511051 containerd[1585]: time="2024-08-05T22:21:53.510851092Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:53.513180 containerd[1585]: time="2024-08-05T22:21:53.512936416Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Aug 5 22:21:53.518601 containerd[1585]: time="2024-08-05T22:21:53.518395804Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:53.523133 containerd[1585]: time="2024-08-05T22:21:53.522971726Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:21:53.525331 containerd[1585]: time="2024-08-05T22:21:53.525107265Z" 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.996097845s" Aug 5 22:21:53.525331 containerd[1585]: time="2024-08-05T22:21:53.525163891Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Aug 5 22:21:53.529104 containerd[1585]: time="2024-08-05T22:21:53.528866536Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 5 22:21:53.557915 containerd[1585]: time="2024-08-05T22:21:53.557782053Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094\"" Aug 5 22:21:53.558943 containerd[1585]: time="2024-08-05T22:21:53.558854345Z" level=info msg="StartContainer for \"29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094\"" Aug 5 22:21:53.810200 containerd[1585]: time="2024-08-05T22:21:53.809918663Z" level=info msg="StartContainer for \"29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094\" returns successfully" Aug 5 22:21:54.088818 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094-rootfs.mount: Deactivated successfully. Aug 5 22:21:54.271591 kubelet[1981]: E0805 22:21:54.271514 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:54.980131 kubelet[1981]: I0805 22:21:54.519825 1981 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-7zfdh" podStartSLOduration=6.7226479139999995 podCreationTimestamp="2024-08-05 22:21:45 +0000 UTC" firstStartedPulling="2024-08-05 22:21:47.731362459 +0000 UTC m=+5.615561247" lastFinishedPulling="2024-08-05 22:21:50.528415738 +0000 UTC m=+8.412614526" observedRunningTime="2024-08-05 22:21:51.505418563 +0000 UTC m=+9.389617402" watchObservedRunningTime="2024-08-05 22:21:54.519701193 +0000 UTC m=+12.403900021" Aug 5 22:21:55.272305 kubelet[1981]: E0805 22:21:55.272010 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:55.617202 containerd[1585]: time="2024-08-05T22:21:55.617092234Z" level=info msg="shim disconnected" id=29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094 namespace=k8s.io Aug 5 22:21:55.617202 containerd[1585]: time="2024-08-05T22:21:55.617202612Z" level=warning msg="cleaning up after shim disconnected" id=29ef1fbbcbccc2850e5ddbd3472050083742470c42c9aabda19c13490d8d3094 namespace=k8s.io Aug 5 22:21:55.618112 containerd[1585]: time="2024-08-05T22:21:55.617225906Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:21:56.272424 kubelet[1981]: E0805 22:21:56.272350 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:56.507152 containerd[1585]: time="2024-08-05T22:21:56.507060703Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 5 22:21:57.272955 kubelet[1981]: E0805 22:21:57.272844 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:58.114148 update_engine[1560]: I0805 22:21:58.114001 1560 update_attempter.cc:509] Updating boot flags... Aug 5 22:21:58.186640 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2353) Aug 5 22:21:58.241570 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2351) Aug 5 22:21:58.273738 kubelet[1981]: E0805 22:21:58.273697 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:21:58.290376 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2351) Aug 5 22:21:59.270364 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3187413208.mount: Deactivated successfully. Aug 5 22:21:59.274048 kubelet[1981]: E0805 22:21:59.273982 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:00.274583 kubelet[1981]: E0805 22:22:00.274468 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:00.584850 containerd[1585]: time="2024-08-05T22:22:00.584710346Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:00.586497 containerd[1585]: time="2024-08-05T22:22:00.586443909Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Aug 5 22:22:00.587616 containerd[1585]: time="2024-08-05T22:22:00.587497482Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:00.592345 containerd[1585]: time="2024-08-05T22:22:00.592214902Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:00.593462 containerd[1585]: time="2024-08-05T22:22:00.593411114Z" 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 4.08625968s" Aug 5 22:22:00.593462 containerd[1585]: time="2024-08-05T22:22:00.593453053Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Aug 5 22:22:00.596473 containerd[1585]: time="2024-08-05T22:22:00.596427803Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 5 22:22:00.614807 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount612393174.mount: Deactivated successfully. Aug 5 22:22:00.620330 containerd[1585]: time="2024-08-05T22:22:00.620192879Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf\"" Aug 5 22:22:00.621193 containerd[1585]: time="2024-08-05T22:22:00.621069880Z" level=info msg="StartContainer for \"17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf\"" Aug 5 22:22:00.659138 systemd[1]: run-containerd-runc-k8s.io-17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf-runc.c4bvRi.mount: Deactivated successfully. Aug 5 22:22:00.701566 containerd[1585]: time="2024-08-05T22:22:00.701458326Z" level=info msg="StartContainer for \"17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf\" returns successfully" Aug 5 22:22:00.724658 kubelet[1981]: I0805 22:22:00.724621 1981 kubelet_node_status.go:493] "Fast updating node status as it just became ready" Aug 5 22:22:01.315001 kubelet[1981]: E0805 22:22:01.274617 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:01.344770 containerd[1585]: time="2024-08-05T22:22:01.344638061Z" level=info msg="shim disconnected" id=17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf namespace=k8s.io Aug 5 22:22:01.344770 containerd[1585]: time="2024-08-05T22:22:01.344747648Z" level=warning msg="cleaning up after shim disconnected" id=17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf namespace=k8s.io Aug 5 22:22:01.344770 containerd[1585]: time="2024-08-05T22:22:01.344770060Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 5 22:22:01.523953 containerd[1585]: time="2024-08-05T22:22:01.523638459Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 5 22:22:01.547747 containerd[1585]: time="2024-08-05T22:22:01.547671140Z" level=info msg="CreateContainer within sandbox \"6cbd298edaeb44d8ced8b8725a9a23dac76c847ba0c94adfc3d3134332717be2\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b483ae276bea3316e82c5433a1b41661e0e1aa3e8159c65948f3cdc6c5b36a50\"" Aug 5 22:22:01.549421 containerd[1585]: time="2024-08-05T22:22:01.549173398Z" level=info msg="StartContainer for \"b483ae276bea3316e82c5433a1b41661e0e1aa3e8159c65948f3cdc6c5b36a50\"" Aug 5 22:22:01.618138 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-17f893261373105d1ae3d537b77d73a19150f1ff8fa942ee72fe3e1baccecfbf-rootfs.mount: Deactivated successfully. Aug 5 22:22:01.644458 containerd[1585]: time="2024-08-05T22:22:01.643859107Z" level=info msg="StartContainer for \"b483ae276bea3316e82c5433a1b41661e0e1aa3e8159c65948f3cdc6c5b36a50\" returns successfully" Aug 5 22:22:02.275062 kubelet[1981]: E0805 22:22:02.274978 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:02.792535 systemd-networkd[1209]: flannel.1: Link UP Aug 5 22:22:02.792580 systemd-networkd[1209]: flannel.1: Gained carrier Aug 5 22:22:03.262138 kubelet[1981]: E0805 22:22:03.262058 1981 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:03.275875 kubelet[1981]: E0805 22:22:03.275819 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:03.961581 systemd-networkd[1209]: flannel.1: Gained IPv6LL Aug 5 22:22:04.276508 kubelet[1981]: E0805 22:22:04.276188 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:05.277438 kubelet[1981]: E0805 22:22:05.277369 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:05.641001 kubelet[1981]: I0805 22:22:05.640558 1981 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-pndst" podStartSLOduration=7.784413698 podCreationTimestamp="2024-08-05 22:21:45 +0000 UTC" firstStartedPulling="2024-08-05 22:21:47.737665552 +0000 UTC m=+5.621864340" lastFinishedPulling="2024-08-05 22:22:00.593724143 +0000 UTC m=+18.477922921" observedRunningTime="2024-08-05 22:22:02.541444108 +0000 UTC m=+20.425642956" watchObservedRunningTime="2024-08-05 22:22:05.640472279 +0000 UTC m=+23.524671107" Aug 5 22:22:05.641001 kubelet[1981]: I0805 22:22:05.641002 1981 topology_manager.go:215] "Topology Admit Handler" podUID="b9f2eef4-aeb8-4876-9d5b-4262b2533ecc" podNamespace="default" podName="nginx-deployment-6d5f899847-nws7v" Aug 5 22:22:05.738811 kubelet[1981]: I0805 22:22:05.738666 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dp4vh\" (UniqueName: \"kubernetes.io/projected/b9f2eef4-aeb8-4876-9d5b-4262b2533ecc-kube-api-access-dp4vh\") pod \"nginx-deployment-6d5f899847-nws7v\" (UID: \"b9f2eef4-aeb8-4876-9d5b-4262b2533ecc\") " pod="default/nginx-deployment-6d5f899847-nws7v" Aug 5 22:22:05.948536 containerd[1585]: time="2024-08-05T22:22:05.947951629Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-nws7v,Uid:b9f2eef4-aeb8-4876-9d5b-4262b2533ecc,Namespace:default,Attempt:0,}" Aug 5 22:22:06.006987 systemd-networkd[1209]: cni0: Link UP Aug 5 22:22:06.007005 systemd-networkd[1209]: cni0: Gained carrier Aug 5 22:22:06.012338 systemd-networkd[1209]: cni0: Lost carrier Aug 5 22:22:06.032405 systemd-networkd[1209]: veth02ed4e5f: Link UP Aug 5 22:22:06.038360 kernel: cni0: port 1(veth02ed4e5f) entered blocking state Aug 5 22:22:06.038464 kernel: cni0: port 1(veth02ed4e5f) entered disabled state Aug 5 22:22:06.041605 kernel: veth02ed4e5f: entered allmulticast mode Aug 5 22:22:06.041665 kernel: veth02ed4e5f: entered promiscuous mode Aug 5 22:22:06.047254 kernel: cni0: port 1(veth02ed4e5f) entered blocking state Aug 5 22:22:06.047301 kernel: cni0: port 1(veth02ed4e5f) entered forwarding state Aug 5 22:22:06.047321 kernel: cni0: port 1(veth02ed4e5f) entered disabled state Aug 5 22:22:06.055272 kernel: cni0: port 1(veth02ed4e5f) entered blocking state Aug 5 22:22:06.055371 kernel: cni0: port 1(veth02ed4e5f) entered forwarding state Aug 5 22:22:06.057161 systemd-networkd[1209]: veth02ed4e5f: Gained carrier Aug 5 22:22:06.057895 systemd-networkd[1209]: cni0: Gained carrier Aug 5 22:22:06.059217 containerd[1585]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000a48e8), "name":"cbr0", "type":"bridge"} Aug 5 22:22:06.059217 containerd[1585]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:22:06.079080 containerd[1585]: {"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-08-05T22:22:06.078761326Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:22:06.079080 containerd[1585]: time="2024-08-05T22:22:06.078830937Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:06.079080 containerd[1585]: time="2024-08-05T22:22:06.078857327Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:22:06.079080 containerd[1585]: time="2024-08-05T22:22:06.078876333Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:06.148751 containerd[1585]: time="2024-08-05T22:22:06.148663306Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-nws7v,Uid:b9f2eef4-aeb8-4876-9d5b-4262b2533ecc,Namespace:default,Attempt:0,} returns sandbox id \"06aef8ef30b25826b2aa8999f761b7ec55b743d4be3776ebe8ee6613162e4457\"" Aug 5 22:22:06.151366 containerd[1585]: time="2024-08-05T22:22:06.151325302Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:22:06.279378 kubelet[1981]: E0805 22:22:06.278222 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:07.278789 kubelet[1981]: E0805 22:22:07.278655 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:07.481567 systemd-networkd[1209]: cni0: Gained IPv6LL Aug 5 22:22:07.867429 systemd-networkd[1209]: veth02ed4e5f: Gained IPv6LL Aug 5 22:22:08.279630 kubelet[1981]: E0805 22:22:08.279519 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:09.280243 kubelet[1981]: E0805 22:22:09.280173 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:09.661174 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2491312574.mount: Deactivated successfully. Aug 5 22:22:10.281627 kubelet[1981]: E0805 22:22:10.281558 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:11.004990 containerd[1585]: time="2024-08-05T22:22:11.004864603Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:11.007553 containerd[1585]: time="2024-08-05T22:22:11.007347477Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70974994" Aug 5 22:22:11.009407 containerd[1585]: time="2024-08-05T22:22:11.009298223Z" level=info msg="ImageCreate event name:\"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:11.016835 containerd[1585]: time="2024-08-05T22:22:11.016778989Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:11.019939 containerd[1585]: time="2024-08-05T22:22:11.019702432Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"70974872\" in 4.86831804s" Aug 5 22:22:11.019939 containerd[1585]: time="2024-08-05T22:22:11.019776461Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\"" Aug 5 22:22:11.024545 containerd[1585]: time="2024-08-05T22:22:11.024461003Z" level=info msg="CreateContainer within sandbox \"06aef8ef30b25826b2aa8999f761b7ec55b743d4be3776ebe8ee6613162e4457\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 5 22:22:11.063284 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount77668194.mount: Deactivated successfully. Aug 5 22:22:11.064828 containerd[1585]: time="2024-08-05T22:22:11.064477891Z" level=info msg="CreateContainer within sandbox \"06aef8ef30b25826b2aa8999f761b7ec55b743d4be3776ebe8ee6613162e4457\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"5f1a5c814efe5fe955971d0a40c300e10f81fef8d7d57bf925d0875670231065\"" Aug 5 22:22:11.066734 containerd[1585]: time="2024-08-05T22:22:11.066586292Z" level=info msg="StartContainer for \"5f1a5c814efe5fe955971d0a40c300e10f81fef8d7d57bf925d0875670231065\"" Aug 5 22:22:11.158490 containerd[1585]: time="2024-08-05T22:22:11.158431311Z" level=info msg="StartContainer for \"5f1a5c814efe5fe955971d0a40c300e10f81fef8d7d57bf925d0875670231065\" returns successfully" Aug 5 22:22:11.282868 kubelet[1981]: E0805 22:22:11.282591 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:12.283410 kubelet[1981]: E0805 22:22:12.283338 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:13.284003 kubelet[1981]: E0805 22:22:13.283918 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:14.284741 kubelet[1981]: E0805 22:22:14.284654 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:15.285973 kubelet[1981]: E0805 22:22:15.285825 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:16.287060 kubelet[1981]: E0805 22:22:16.286973 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:17.287734 kubelet[1981]: E0805 22:22:17.287666 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:18.288546 kubelet[1981]: E0805 22:22:18.288391 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:19.289572 kubelet[1981]: E0805 22:22:19.289477 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:20.289809 kubelet[1981]: E0805 22:22:20.289721 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:20.517301 kubelet[1981]: I0805 22:22:20.517107 1981 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-nws7v" podStartSLOduration=10.647265094 podCreationTimestamp="2024-08-05 22:22:05 +0000 UTC" firstStartedPulling="2024-08-05 22:22:06.150613713 +0000 UTC m=+24.034812491" lastFinishedPulling="2024-08-05 22:22:11.020332175 +0000 UTC m=+28.904531003" observedRunningTime="2024-08-05 22:22:11.612042395 +0000 UTC m=+29.496241223" watchObservedRunningTime="2024-08-05 22:22:20.516983606 +0000 UTC m=+38.401182444" Aug 5 22:22:20.518569 kubelet[1981]: I0805 22:22:20.518190 1981 topology_manager.go:215] "Topology Admit Handler" podUID="d768dac3-4265-4905-9057-501f52cd21e7" podNamespace="default" podName="nfs-server-provisioner-0" Aug 5 22:22:20.544766 kubelet[1981]: I0805 22:22:20.544211 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d768dac3-4265-4905-9057-501f52cd21e7-data\") pod \"nfs-server-provisioner-0\" (UID: \"d768dac3-4265-4905-9057-501f52cd21e7\") " pod="default/nfs-server-provisioner-0" Aug 5 22:22:20.544766 kubelet[1981]: I0805 22:22:20.544374 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gn2zz\" (UniqueName: \"kubernetes.io/projected/d768dac3-4265-4905-9057-501f52cd21e7-kube-api-access-gn2zz\") pod \"nfs-server-provisioner-0\" (UID: \"d768dac3-4265-4905-9057-501f52cd21e7\") " pod="default/nfs-server-provisioner-0" Aug 5 22:22:20.835772 containerd[1585]: time="2024-08-05T22:22:20.835592784Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d768dac3-4265-4905-9057-501f52cd21e7,Namespace:default,Attempt:0,}" Aug 5 22:22:20.894880 systemd-networkd[1209]: veth35ac8113: Link UP Aug 5 22:22:20.897878 kernel: cni0: port 2(veth35ac8113) entered blocking state Aug 5 22:22:20.897986 kernel: cni0: port 2(veth35ac8113) entered disabled state Aug 5 22:22:20.898038 kernel: veth35ac8113: entered allmulticast mode Aug 5 22:22:20.901428 kernel: veth35ac8113: entered promiscuous mode Aug 5 22:22:20.918014 kernel: cni0: port 2(veth35ac8113) entered blocking state Aug 5 22:22:20.918969 kernel: cni0: port 2(veth35ac8113) entered forwarding state Aug 5 22:22:20.916440 systemd-networkd[1209]: veth35ac8113: Gained carrier Aug 5 22:22:20.921160 containerd[1585]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000a48e8), "name":"cbr0", "type":"bridge"} Aug 5 22:22:20.921160 containerd[1585]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:22:20.960968 containerd[1585]: {"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-08-05T22:22:20.960714357Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:22:20.960968 containerd[1585]: time="2024-08-05T22:22:20.960786091Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:20.960968 containerd[1585]: time="2024-08-05T22:22:20.960824354Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:22:20.960968 containerd[1585]: time="2024-08-05T22:22:20.960848890Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:21.018402 containerd[1585]: time="2024-08-05T22:22:21.018366622Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d768dac3-4265-4905-9057-501f52cd21e7,Namespace:default,Attempt:0,} returns sandbox id \"3e07d228bfa6e5cd4b81d7f458664d059c26a4cf0b6b4e1dfd72780f8b8064d9\"" Aug 5 22:22:21.020512 containerd[1585]: time="2024-08-05T22:22:21.020219319Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 5 22:22:21.290960 kubelet[1981]: E0805 22:22:21.290712 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:22.291536 kubelet[1981]: E0805 22:22:22.291448 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:22.777780 systemd-networkd[1209]: veth35ac8113: Gained IPv6LL Aug 5 22:22:23.262184 kubelet[1981]: E0805 22:22:23.261956 1981 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:23.292265 kubelet[1981]: E0805 22:22:23.292008 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:24.293067 kubelet[1981]: E0805 22:22:24.292966 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:24.527474 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3488638285.mount: Deactivated successfully. Aug 5 22:22:25.294359 kubelet[1981]: E0805 22:22:25.294309 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:26.294503 kubelet[1981]: E0805 22:22:26.294465 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:27.028545 containerd[1585]: time="2024-08-05T22:22:27.028457015Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:27.031427 containerd[1585]: time="2024-08-05T22:22:27.031151812Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039414" Aug 5 22:22:27.033655 containerd[1585]: time="2024-08-05T22:22:27.033448873Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:27.044931 containerd[1585]: time="2024-08-05T22:22:27.044755723Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:27.048441 containerd[1585]: time="2024-08-05T22:22:27.048355228Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 6.028049276s" Aug 5 22:22:27.048951 containerd[1585]: time="2024-08-05T22:22:27.048691149Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Aug 5 22:22:27.054477 containerd[1585]: time="2024-08-05T22:22:27.054368123Z" level=info msg="CreateContainer within sandbox \"3e07d228bfa6e5cd4b81d7f458664d059c26a4cf0b6b4e1dfd72780f8b8064d9\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 5 22:22:27.086225 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount498240646.mount: Deactivated successfully. Aug 5 22:22:27.097365 containerd[1585]: time="2024-08-05T22:22:27.095933469Z" level=info msg="CreateContainer within sandbox \"3e07d228bfa6e5cd4b81d7f458664d059c26a4cf0b6b4e1dfd72780f8b8064d9\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"44b46fabfbf0f29e2f63c50a557deddf3a24033339cf5cf6c903481507301cd2\"" Aug 5 22:22:27.097557 containerd[1585]: time="2024-08-05T22:22:27.097445407Z" level=info msg="StartContainer for \"44b46fabfbf0f29e2f63c50a557deddf3a24033339cf5cf6c903481507301cd2\"" Aug 5 22:22:27.275071 containerd[1585]: time="2024-08-05T22:22:27.274919134Z" level=info msg="StartContainer for \"44b46fabfbf0f29e2f63c50a557deddf3a24033339cf5cf6c903481507301cd2\" returns successfully" Aug 5 22:22:27.295649 kubelet[1981]: E0805 22:22:27.295436 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:27.711753 kubelet[1981]: I0805 22:22:27.711616 1981 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.681933859 podCreationTimestamp="2024-08-05 22:22:20 +0000 UTC" firstStartedPulling="2024-08-05 22:22:21.019922523 +0000 UTC m=+38.904121301" lastFinishedPulling="2024-08-05 22:22:27.049484709 +0000 UTC m=+44.933683537" observedRunningTime="2024-08-05 22:22:27.711026784 +0000 UTC m=+45.595225662" watchObservedRunningTime="2024-08-05 22:22:27.711496095 +0000 UTC m=+45.595694923" Aug 5 22:22:28.296068 kubelet[1981]: E0805 22:22:28.295957 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:29.297317 kubelet[1981]: E0805 22:22:29.297194 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:30.297973 kubelet[1981]: E0805 22:22:30.297907 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:31.298272 kubelet[1981]: E0805 22:22:31.298058 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:32.298529 kubelet[1981]: E0805 22:22:32.298444 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:33.299297 kubelet[1981]: E0805 22:22:33.299123 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:34.300265 kubelet[1981]: E0805 22:22:34.300142 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:35.300929 kubelet[1981]: E0805 22:22:35.300849 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:36.301167 kubelet[1981]: E0805 22:22:36.301091 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:37.301598 kubelet[1981]: E0805 22:22:37.301424 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:37.383617 kubelet[1981]: I0805 22:22:37.383558 1981 topology_manager.go:215] "Topology Admit Handler" podUID="940d86d3-b261-44e5-a4c1-505d0899572b" podNamespace="default" podName="test-pod-1" Aug 5 22:22:37.572937 kubelet[1981]: I0805 22:22:37.572691 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-83e2da28-3282-4360-9606-b95db3a2105d\" (UniqueName: \"kubernetes.io/nfs/940d86d3-b261-44e5-a4c1-505d0899572b-pvc-83e2da28-3282-4360-9606-b95db3a2105d\") pod \"test-pod-1\" (UID: \"940d86d3-b261-44e5-a4c1-505d0899572b\") " pod="default/test-pod-1" Aug 5 22:22:37.572937 kubelet[1981]: I0805 22:22:37.572767 1981 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tdn99\" (UniqueName: \"kubernetes.io/projected/940d86d3-b261-44e5-a4c1-505d0899572b-kube-api-access-tdn99\") pod \"test-pod-1\" (UID: \"940d86d3-b261-44e5-a4c1-505d0899572b\") " pod="default/test-pod-1" Aug 5 22:22:37.783334 kernel: FS-Cache: Loaded Aug 5 22:22:37.883453 kernel: RPC: Registered named UNIX socket transport module. Aug 5 22:22:37.883631 kernel: RPC: Registered udp transport module. Aug 5 22:22:37.883655 kernel: RPC: Registered tcp transport module. Aug 5 22:22:37.883678 kernel: RPC: Registered tcp-with-tls transport module. Aug 5 22:22:37.884601 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 5 22:22:38.248107 kernel: NFS: Registering the id_resolver key type Aug 5 22:22:38.248449 kernel: Key type id_resolver registered Aug 5 22:22:38.248568 kernel: Key type id_legacy registered Aug 5 22:22:38.301852 kubelet[1981]: E0805 22:22:38.301798 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:38.311971 nfsidmap[3062]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'novalocal' Aug 5 22:22:38.320294 nfsidmap[3063]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'novalocal' Aug 5 22:22:38.593141 containerd[1585]: time="2024-08-05T22:22:38.593017533Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:940d86d3-b261-44e5-a4c1-505d0899572b,Namespace:default,Attempt:0,}" Aug 5 22:22:38.663119 systemd-networkd[1209]: veth8d711ceb: Link UP Aug 5 22:22:38.670117 kernel: cni0: port 3(veth8d711ceb) entered blocking state Aug 5 22:22:38.670357 kernel: cni0: port 3(veth8d711ceb) entered disabled state Aug 5 22:22:38.670449 kernel: veth8d711ceb: entered allmulticast mode Aug 5 22:22:38.673144 kernel: veth8d711ceb: entered promiscuous mode Aug 5 22:22:38.696289 kernel: cni0: port 3(veth8d711ceb) entered blocking state Aug 5 22:22:38.696435 kernel: cni0: port 3(veth8d711ceb) entered forwarding state Aug 5 22:22:38.696738 systemd-networkd[1209]: veth8d711ceb: Gained carrier Aug 5 22:22:38.702317 containerd[1585]: 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)(0xc000020938), "name":"cbr0", "type":"bridge"} Aug 5 22:22:38.702317 containerd[1585]: delegateAdd: netconf sent to delegate plugin: Aug 5 22:22:38.745866 containerd[1585]: {"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-08-05T22:22:38.745751697Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 22:22:38.746267 containerd[1585]: time="2024-08-05T22:22:38.745829794Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:38.746901 containerd[1585]: time="2024-08-05T22:22:38.746818268Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 22:22:38.746901 containerd[1585]: time="2024-08-05T22:22:38.746850930Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 22:22:38.769213 systemd[1]: run-containerd-runc-k8s.io-d2616c8c09b01305223febd0148bc6af7559ea3c737a8f64fd62def0b0d99472-runc.aP5k7J.mount: Deactivated successfully. Aug 5 22:22:38.822671 containerd[1585]: time="2024-08-05T22:22:38.822628335Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:940d86d3-b261-44e5-a4c1-505d0899572b,Namespace:default,Attempt:0,} returns sandbox id \"d2616c8c09b01305223febd0148bc6af7559ea3c737a8f64fd62def0b0d99472\"" Aug 5 22:22:38.825685 containerd[1585]: time="2024-08-05T22:22:38.825430402Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 5 22:22:39.236705 containerd[1585]: time="2024-08-05T22:22:39.236570773Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 5 22:22:39.238485 containerd[1585]: time="2024-08-05T22:22:39.238376861Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 5 22:22:39.247962 containerd[1585]: time="2024-08-05T22:22:39.247861494Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:57fce0d39ce5904a8ade9fc245b9e6ebca359113d98cf0b850f93b66294bcc36\", size \"70974872\" in 422.368505ms" Aug 5 22:22:39.248212 containerd[1585]: time="2024-08-05T22:22:39.247960541Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fcb81a1d00e70f650105e1b8ddc093ad0164f1dd64114e5b38c184d6c75a8bc0\"" Aug 5 22:22:39.251714 containerd[1585]: time="2024-08-05T22:22:39.251633131Z" level=info msg="CreateContainer within sandbox \"d2616c8c09b01305223febd0148bc6af7559ea3c737a8f64fd62def0b0d99472\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 5 22:22:39.294320 containerd[1585]: time="2024-08-05T22:22:39.294045989Z" level=info msg="CreateContainer within sandbox \"d2616c8c09b01305223febd0148bc6af7559ea3c737a8f64fd62def0b0d99472\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"2fe618097331a1f8c0389cef3739192dd1f42ba4349bfa4e780d33d873f1fbcf\"" Aug 5 22:22:39.298284 containerd[1585]: time="2024-08-05T22:22:39.296651648Z" level=info msg="StartContainer for \"2fe618097331a1f8c0389cef3739192dd1f42ba4349bfa4e780d33d873f1fbcf\"" Aug 5 22:22:39.303190 kubelet[1981]: E0805 22:22:39.303146 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:39.394632 containerd[1585]: time="2024-08-05T22:22:39.393990872Z" level=info msg="StartContainer for \"2fe618097331a1f8c0389cef3739192dd1f42ba4349bfa4e780d33d873f1fbcf\" returns successfully" Aug 5 22:22:39.750295 kubelet[1981]: I0805 22:22:39.749115 1981 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.32536019 podCreationTimestamp="2024-08-05 22:22:23 +0000 UTC" firstStartedPulling="2024-08-05 22:22:38.824873146 +0000 UTC m=+56.709071934" lastFinishedPulling="2024-08-05 22:22:39.248546931 +0000 UTC m=+57.132745759" observedRunningTime="2024-08-05 22:22:39.748554125 +0000 UTC m=+57.632752953" watchObservedRunningTime="2024-08-05 22:22:39.749034015 +0000 UTC m=+57.633232843" Aug 5 22:22:40.304670 kubelet[1981]: E0805 22:22:40.304560 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:40.441631 systemd-networkd[1209]: veth8d711ceb: Gained IPv6LL Aug 5 22:22:41.305703 kubelet[1981]: E0805 22:22:41.305631 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:42.305991 kubelet[1981]: E0805 22:22:42.305850 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:43.261842 kubelet[1981]: E0805 22:22:43.261763 1981 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:43.307127 kubelet[1981]: E0805 22:22:43.307046 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:44.307419 kubelet[1981]: E0805 22:22:44.307344 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:45.308520 kubelet[1981]: E0805 22:22:45.308391 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 5 22:22:46.308684 kubelet[1981]: E0805 22:22:46.308580 1981 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"