Jul 1 08:54:22.014826 kernel: Linux version 6.12.34-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Jun 30 19:26:54 -00 2025 Jul 1 08:54:22.014908 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=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:54:22.014918 kernel: BIOS-provided physical RAM map: Jul 1 08:54:22.014929 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Jul 1 08:54:22.014937 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Jul 1 08:54:22.014944 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jul 1 08:54:22.014991 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable Jul 1 08:54:22.015000 kernel: BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved Jul 1 08:54:22.015007 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Jul 1 08:54:22.015014 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jul 1 08:54:22.015022 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable Jul 1 08:54:22.015029 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Jul 1 08:54:22.015040 kernel: NX (Execute Disable) protection: active Jul 1 08:54:22.015048 kernel: APIC: Static calls initialized Jul 1 08:54:22.015056 kernel: SMBIOS 3.0.0 present. Jul 1 08:54:22.015065 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2 04/01/2014 Jul 1 08:54:22.015073 kernel: DMI: Memory slots populated: 1/1 Jul 1 08:54:22.015082 kernel: Hypervisor detected: KVM Jul 1 08:54:22.015090 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jul 1 08:54:22.015098 kernel: kvm-clock: using sched offset of 5148018424 cycles Jul 1 08:54:22.015106 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jul 1 08:54:22.015115 kernel: tsc: Detected 1996.249 MHz processor Jul 1 08:54:22.015124 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 1 08:54:22.015132 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 1 08:54:22.015141 kernel: last_pfn = 0x140000 max_arch_pfn = 0x400000000 Jul 1 08:54:22.015149 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Jul 1 08:54:22.015160 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 1 08:54:22.015168 kernel: last_pfn = 0xbffdd max_arch_pfn = 0x400000000 Jul 1 08:54:22.015176 kernel: ACPI: Early table checksum verification disabled Jul 1 08:54:22.015184 kernel: ACPI: RSDP 0x00000000000F51E0 000014 (v00 BOCHS ) Jul 1 08:54:22.015193 kernel: ACPI: RSDT 0x00000000BFFE1B65 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 1 08:54:22.015201 kernel: ACPI: FACP 0x00000000BFFE1A49 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 1 08:54:22.015209 kernel: ACPI: DSDT 0x00000000BFFE0040 001A09 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 1 08:54:22.015218 kernel: ACPI: FACS 0x00000000BFFE0000 000040 Jul 1 08:54:22.015226 kernel: ACPI: APIC 0x00000000BFFE1ABD 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 1 08:54:22.015236 kernel: ACPI: WAET 0x00000000BFFE1B3D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 1 08:54:22.015244 kernel: ACPI: Reserving FACP table memory at [mem 0xbffe1a49-0xbffe1abc] Jul 1 08:54:22.015252 kernel: ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a48] Jul 1 08:54:22.015260 kernel: ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] Jul 1 08:54:22.015269 kernel: ACPI: Reserving APIC table memory at [mem 0xbffe1abd-0xbffe1b3c] Jul 1 08:54:22.015280 kernel: ACPI: Reserving WAET table memory at [mem 0xbffe1b3d-0xbffe1b64] Jul 1 08:54:22.015289 kernel: No NUMA configuration found Jul 1 08:54:22.015299 kernel: Faking a node at [mem 0x0000000000000000-0x000000013fffffff] Jul 1 08:54:22.015308 kernel: NODE_DATA(0) allocated [mem 0x13fff8dc0-0x13fffffff] Jul 1 08:54:22.015316 kernel: Zone ranges: Jul 1 08:54:22.015325 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 1 08:54:22.015334 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 1 08:54:22.015342 kernel: Normal [mem 0x0000000100000000-0x000000013fffffff] Jul 1 08:54:22.015351 kernel: Device empty Jul 1 08:54:22.015359 kernel: Movable zone start for each node Jul 1 08:54:22.015369 kernel: Early memory node ranges Jul 1 08:54:22.015378 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jul 1 08:54:22.015386 kernel: node 0: [mem 0x0000000000100000-0x00000000bffdcfff] Jul 1 08:54:22.015395 kernel: node 0: [mem 0x0000000100000000-0x000000013fffffff] Jul 1 08:54:22.015404 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] Jul 1 08:54:22.015412 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 1 08:54:22.015421 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jul 1 08:54:22.015429 kernel: On node 0, zone Normal: 35 pages in unavailable ranges Jul 1 08:54:22.015438 kernel: ACPI: PM-Timer IO Port: 0x608 Jul 1 08:54:22.015448 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jul 1 08:54:22.015457 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Jul 1 08:54:22.015466 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Jul 1 08:54:22.015474 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jul 1 08:54:22.015483 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 1 08:54:22.015491 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jul 1 08:54:22.015500 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jul 1 08:54:22.015509 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 1 08:54:22.015517 kernel: CPU topo: Max. logical packages: 2 Jul 1 08:54:22.015528 kernel: CPU topo: Max. logical dies: 2 Jul 1 08:54:22.015536 kernel: CPU topo: Max. dies per package: 1 Jul 1 08:54:22.015545 kernel: CPU topo: Max. threads per core: 1 Jul 1 08:54:22.015553 kernel: CPU topo: Num. cores per package: 1 Jul 1 08:54:22.015562 kernel: CPU topo: Num. threads per package: 1 Jul 1 08:54:22.015570 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Jul 1 08:54:22.015579 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jul 1 08:54:22.015587 kernel: [mem 0xc0000000-0xfeffbfff] available for PCI devices Jul 1 08:54:22.015596 kernel: Booting paravirtualized kernel on KVM Jul 1 08:54:22.015606 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 1 08:54:22.015615 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jul 1 08:54:22.015624 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 Jul 1 08:54:22.015632 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 Jul 1 08:54:22.015641 kernel: pcpu-alloc: [0] 0 1 Jul 1 08:54:22.015649 kernel: kvm-guest: PV spinlocks disabled, no host support Jul 1 08:54:22.015659 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=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:54:22.015668 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 1 08:54:22.015678 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 1 08:54:22.015687 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 1 08:54:22.015695 kernel: Fallback order for Node 0: 0 Jul 1 08:54:22.015704 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048443 Jul 1 08:54:22.015713 kernel: Policy zone: Normal Jul 1 08:54:22.015721 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 1 08:54:22.015730 kernel: software IO TLB: area num 2. Jul 1 08:54:22.015738 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jul 1 08:54:22.015747 kernel: ftrace: allocating 40095 entries in 157 pages Jul 1 08:54:22.015757 kernel: ftrace: allocated 157 pages with 5 groups Jul 1 08:54:22.015766 kernel: Dynamic Preempt: voluntary Jul 1 08:54:22.015774 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 1 08:54:22.015784 kernel: rcu: RCU event tracing is enabled. Jul 1 08:54:22.015793 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jul 1 08:54:22.015801 kernel: Trampoline variant of Tasks RCU enabled. Jul 1 08:54:22.015810 kernel: Rude variant of Tasks RCU enabled. Jul 1 08:54:22.015819 kernel: Tracing variant of Tasks RCU enabled. Jul 1 08:54:22.015827 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 1 08:54:22.015836 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jul 1 08:54:22.015846 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 1 08:54:22.015855 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 1 08:54:22.015864 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jul 1 08:54:22.015872 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Jul 1 08:54:22.015881 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 1 08:54:22.015890 kernel: Console: colour VGA+ 80x25 Jul 1 08:54:22.015898 kernel: printk: legacy console [tty0] enabled Jul 1 08:54:22.015907 kernel: printk: legacy console [ttyS0] enabled Jul 1 08:54:22.015917 kernel: ACPI: Core revision 20240827 Jul 1 08:54:22.015925 kernel: APIC: Switch to symmetric I/O mode setup Jul 1 08:54:22.015934 kernel: x2apic enabled Jul 1 08:54:22.015943 kernel: APIC: Switched APIC routing to: physical x2apic Jul 1 08:54:22.016070 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Jul 1 08:54:22.016080 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Jul 1 08:54:22.016097 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Jul 1 08:54:22.016107 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Jul 1 08:54:22.016116 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Jul 1 08:54:22.016125 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 1 08:54:22.016134 kernel: Spectre V2 : Mitigation: Retpolines Jul 1 08:54:22.016143 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 1 08:54:22.016154 kernel: Speculative Store Bypass: Vulnerable Jul 1 08:54:22.016163 kernel: x86/fpu: x87 FPU will use FXSAVE Jul 1 08:54:22.016172 kernel: Freeing SMP alternatives memory: 32K Jul 1 08:54:22.016181 kernel: pid_max: default: 32768 minimum: 301 Jul 1 08:54:22.016190 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 1 08:54:22.016201 kernel: landlock: Up and running. Jul 1 08:54:22.016210 kernel: SELinux: Initializing. Jul 1 08:54:22.016220 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 1 08:54:22.016229 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 1 08:54:22.016238 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Jul 1 08:54:22.016247 kernel: Performance Events: AMD PMU driver. Jul 1 08:54:22.016256 kernel: ... version: 0 Jul 1 08:54:22.016265 kernel: ... bit width: 48 Jul 1 08:54:22.016274 kernel: ... generic registers: 4 Jul 1 08:54:22.016285 kernel: ... value mask: 0000ffffffffffff Jul 1 08:54:22.016294 kernel: ... max period: 00007fffffffffff Jul 1 08:54:22.016303 kernel: ... fixed-purpose events: 0 Jul 1 08:54:22.016312 kernel: ... event mask: 000000000000000f Jul 1 08:54:22.016321 kernel: signal: max sigframe size: 1440 Jul 1 08:54:22.016330 kernel: rcu: Hierarchical SRCU implementation. Jul 1 08:54:22.016340 kernel: rcu: Max phase no-delay instances is 400. Jul 1 08:54:22.016349 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 1 08:54:22.016358 kernel: smp: Bringing up secondary CPUs ... Jul 1 08:54:22.016369 kernel: smpboot: x86: Booting SMP configuration: Jul 1 08:54:22.016378 kernel: .... node #0, CPUs: #1 Jul 1 08:54:22.016387 kernel: smp: Brought up 1 node, 2 CPUs Jul 1 08:54:22.016396 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Jul 1 08:54:22.016406 kernel: Memory: 3962040K/4193772K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54508K init, 2460K bss, 227284K reserved, 0K cma-reserved) Jul 1 08:54:22.016415 kernel: devtmpfs: initialized Jul 1 08:54:22.016424 kernel: x86/mm: Memory block size: 128MB Jul 1 08:54:22.016433 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 1 08:54:22.016442 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jul 1 08:54:22.016453 kernel: pinctrl core: initialized pinctrl subsystem Jul 1 08:54:22.016462 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 1 08:54:22.016471 kernel: audit: initializing netlink subsys (disabled) Jul 1 08:54:22.016480 kernel: audit: type=2000 audit(1751360058.149:1): state=initialized audit_enabled=0 res=1 Jul 1 08:54:22.016489 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 1 08:54:22.016498 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 1 08:54:22.016507 kernel: cpuidle: using governor menu Jul 1 08:54:22.016516 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 1 08:54:22.016525 kernel: dca service started, version 1.12.1 Jul 1 08:54:22.016535 kernel: PCI: Using configuration type 1 for base access Jul 1 08:54:22.016544 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 1 08:54:22.016554 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 1 08:54:22.016563 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 1 08:54:22.016572 kernel: ACPI: Added _OSI(Module Device) Jul 1 08:54:22.016581 kernel: ACPI: Added _OSI(Processor Device) Jul 1 08:54:22.016590 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 1 08:54:22.016599 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 1 08:54:22.016608 kernel: ACPI: Interpreter enabled Jul 1 08:54:22.016617 kernel: ACPI: PM: (supports S0 S3 S5) Jul 1 08:54:22.016628 kernel: ACPI: Using IOAPIC for interrupt routing Jul 1 08:54:22.016637 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 1 08:54:22.016646 kernel: PCI: Using E820 reservations for host bridge windows Jul 1 08:54:22.016655 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Jul 1 08:54:22.016664 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 1 08:54:22.017021 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Jul 1 08:54:22.017123 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Jul 1 08:54:22.017220 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Jul 1 08:54:22.017235 kernel: acpiphp: Slot [3] registered Jul 1 08:54:22.017245 kernel: acpiphp: Slot [4] registered Jul 1 08:54:22.017255 kernel: acpiphp: Slot [5] registered Jul 1 08:54:22.017265 kernel: acpiphp: Slot [6] registered Jul 1 08:54:22.017274 kernel: acpiphp: Slot [7] registered Jul 1 08:54:22.017284 kernel: acpiphp: Slot [8] registered Jul 1 08:54:22.017293 kernel: acpiphp: Slot [9] registered Jul 1 08:54:22.017303 kernel: acpiphp: Slot [10] registered Jul 1 08:54:22.017316 kernel: acpiphp: Slot [11] registered Jul 1 08:54:22.017325 kernel: acpiphp: Slot [12] registered Jul 1 08:54:22.017335 kernel: acpiphp: Slot [13] registered Jul 1 08:54:22.017345 kernel: acpiphp: Slot [14] registered Jul 1 08:54:22.017354 kernel: acpiphp: Slot [15] registered Jul 1 08:54:22.017364 kernel: acpiphp: Slot [16] registered Jul 1 08:54:22.017373 kernel: acpiphp: Slot [17] registered Jul 1 08:54:22.017383 kernel: acpiphp: Slot [18] registered Jul 1 08:54:22.017392 kernel: acpiphp: Slot [19] registered Jul 1 08:54:22.017404 kernel: acpiphp: Slot [20] registered Jul 1 08:54:22.017413 kernel: acpiphp: Slot [21] registered Jul 1 08:54:22.017423 kernel: acpiphp: Slot [22] registered Jul 1 08:54:22.017432 kernel: acpiphp: Slot [23] registered Jul 1 08:54:22.017442 kernel: acpiphp: Slot [24] registered Jul 1 08:54:22.017451 kernel: acpiphp: Slot [25] registered Jul 1 08:54:22.017461 kernel: acpiphp: Slot [26] registered Jul 1 08:54:22.017470 kernel: acpiphp: Slot [27] registered Jul 1 08:54:22.017480 kernel: acpiphp: Slot [28] registered Jul 1 08:54:22.017489 kernel: acpiphp: Slot [29] registered Jul 1 08:54:22.017500 kernel: acpiphp: Slot [30] registered Jul 1 08:54:22.017510 kernel: acpiphp: Slot [31] registered Jul 1 08:54:22.017519 kernel: PCI host bridge to bus 0000:00 Jul 1 08:54:22.017635 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jul 1 08:54:22.017722 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jul 1 08:54:22.017804 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jul 1 08:54:22.017885 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Jul 1 08:54:22.017994 kernel: pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] Jul 1 08:54:22.018078 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 1 08:54:22.018218 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint Jul 1 08:54:22.018336 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint Jul 1 08:54:22.018450 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint Jul 1 08:54:22.018547 kernel: pci 0000:00:01.1: BAR 4 [io 0xc120-0xc12f] Jul 1 08:54:22.018664 kernel: pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk Jul 1 08:54:22.018758 kernel: pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk Jul 1 08:54:22.018850 kernel: pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk Jul 1 08:54:22.018943 kernel: pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk Jul 1 08:54:22.019102 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint Jul 1 08:54:22.019200 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Jul 1 08:54:22.019293 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Jul 1 08:54:22.019442 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 conventional PCI endpoint Jul 1 08:54:22.019540 kernel: pci 0000:00:02.0: BAR 0 [mem 0xfe000000-0xfe7fffff pref] Jul 1 08:54:22.019635 kernel: pci 0000:00:02.0: BAR 2 [mem 0xc000000000-0xc000003fff 64bit pref] Jul 1 08:54:22.019729 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfeb90000-0xfeb90fff] Jul 1 08:54:22.019823 kernel: pci 0000:00:02.0: ROM [mem 0xfeb80000-0xfeb8ffff pref] Jul 1 08:54:22.019919 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jul 1 08:54:22.020094 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Jul 1 08:54:22.020198 kernel: pci 0000:00:03.0: BAR 0 [io 0xc080-0xc0bf] Jul 1 08:54:22.020295 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfeb91000-0xfeb91fff] Jul 1 08:54:22.020390 kernel: pci 0000:00:03.0: BAR 4 [mem 0xc000004000-0xc000007fff 64bit pref] Jul 1 08:54:22.020484 kernel: pci 0000:00:03.0: ROM [mem 0xfeb00000-0xfeb7ffff pref] Jul 1 08:54:22.020606 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Jul 1 08:54:22.020703 kernel: pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f] Jul 1 08:54:22.020796 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfeb92000-0xfeb92fff] Jul 1 08:54:22.020894 kernel: pci 0000:00:04.0: BAR 4 [mem 0xc000008000-0xc00000bfff 64bit pref] Jul 1 08:54:22.021028 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint Jul 1 08:54:22.021122 kernel: pci 0000:00:05.0: BAR 0 [io 0xc0c0-0xc0ff] Jul 1 08:54:22.021211 kernel: pci 0000:00:05.0: BAR 4 [mem 0xc00000c000-0xc00000ffff 64bit pref] Jul 1 08:54:22.021335 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jul 1 08:54:22.021426 kernel: pci 0000:00:06.0: BAR 0 [io 0xc100-0xc11f] Jul 1 08:54:22.021518 kernel: pci 0000:00:06.0: BAR 1 [mem 0xfeb93000-0xfeb93fff] Jul 1 08:54:22.021605 kernel: pci 0000:00:06.0: BAR 4 [mem 0xc000010000-0xc000013fff 64bit pref] Jul 1 08:54:22.021618 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jul 1 08:54:22.021627 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jul 1 08:54:22.021637 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jul 1 08:54:22.021646 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jul 1 08:54:22.021655 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Jul 1 08:54:22.021664 kernel: iommu: Default domain type: Translated Jul 1 08:54:22.021674 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 1 08:54:22.021686 kernel: PCI: Using ACPI for IRQ routing Jul 1 08:54:22.021695 kernel: PCI: pci_cache_line_size set to 64 bytes Jul 1 08:54:22.021704 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Jul 1 08:54:22.021713 kernel: e820: reserve RAM buffer [mem 0xbffdd000-0xbfffffff] Jul 1 08:54:22.021799 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Jul 1 08:54:22.021885 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Jul 1 08:54:22.024629 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jul 1 08:54:22.024745 kernel: vgaarb: loaded Jul 1 08:54:22.024772 kernel: clocksource: Switched to clocksource kvm-clock Jul 1 08:54:22.024819 kernel: VFS: Disk quotas dquot_6.6.0 Jul 1 08:54:22.024844 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 1 08:54:22.024868 kernel: pnp: PnP ACPI init Jul 1 08:54:22.025399 kernel: pnp 00:03: [dma 2] Jul 1 08:54:22.025441 kernel: pnp: PnP ACPI: found 5 devices Jul 1 08:54:22.025470 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 1 08:54:22.025505 kernel: NET: Registered PF_INET protocol family Jul 1 08:54:22.025544 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 1 08:54:22.025592 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 1 08:54:22.025619 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 1 08:54:22.025643 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 1 08:54:22.025666 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 1 08:54:22.025689 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 1 08:54:22.025712 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 1 08:54:22.025735 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 1 08:54:22.025759 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 1 08:54:22.025781 kernel: NET: Registered PF_XDP protocol family Jul 1 08:54:22.026172 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jul 1 08:54:22.026379 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jul 1 08:54:22.026574 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jul 1 08:54:22.026819 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] Jul 1 08:54:22.027077 kernel: pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] Jul 1 08:54:22.027416 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Jul 1 08:54:22.027708 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Jul 1 08:54:22.027746 kernel: PCI: CLS 0 bytes, default 64 Jul 1 08:54:22.027779 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 1 08:54:22.027802 kernel: software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) Jul 1 08:54:22.027826 kernel: Initialise system trusted keyrings Jul 1 08:54:22.027849 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 1 08:54:22.027872 kernel: Key type asymmetric registered Jul 1 08:54:22.027895 kernel: Asymmetric key parser 'x509' registered Jul 1 08:54:22.027918 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 1 08:54:22.027942 kernel: io scheduler mq-deadline registered Jul 1 08:54:22.030019 kernel: io scheduler kyber registered Jul 1 08:54:22.030045 kernel: io scheduler bfq registered Jul 1 08:54:22.030063 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 1 08:54:22.030083 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Jul 1 08:54:22.030109 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Jul 1 08:54:22.030135 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Jul 1 08:54:22.030161 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Jul 1 08:54:22.030183 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 1 08:54:22.030211 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 1 08:54:22.030239 kernel: random: crng init done Jul 1 08:54:22.030270 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jul 1 08:54:22.030290 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jul 1 08:54:22.030310 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jul 1 08:54:22.030580 kernel: rtc_cmos 00:04: RTC can wake from S4 Jul 1 08:54:22.030634 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Jul 1 08:54:22.030804 kernel: rtc_cmos 00:04: registered as rtc0 Jul 1 08:54:22.031000 kernel: rtc_cmos 00:04: setting system clock to 2025-07-01T08:54:21 UTC (1751360061) Jul 1 08:54:22.031171 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Jul 1 08:54:22.031206 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Jul 1 08:54:22.031226 kernel: NET: Registered PF_INET6 protocol family Jul 1 08:54:22.031246 kernel: Segment Routing with IPv6 Jul 1 08:54:22.031266 kernel: In-situ OAM (IOAM) with IPv6 Jul 1 08:54:22.031285 kernel: NET: Registered PF_PACKET protocol family Jul 1 08:54:22.031304 kernel: Key type dns_resolver registered Jul 1 08:54:22.031323 kernel: IPI shorthand broadcast: enabled Jul 1 08:54:22.031343 kernel: sched_clock: Marking stable (4605019369, 184747481)->(4822171191, -32404341) Jul 1 08:54:22.031362 kernel: registered taskstats version 1 Jul 1 08:54:22.031385 kernel: Loading compiled-in X.509 certificates Jul 1 08:54:22.031405 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: bdab85da21e6e40e781d68d3bf17f0a40ee7357c' Jul 1 08:54:22.031425 kernel: Demotion targets for Node 0: null Jul 1 08:54:22.031444 kernel: Key type .fscrypt registered Jul 1 08:54:22.031462 kernel: Key type fscrypt-provisioning registered Jul 1 08:54:22.031482 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 1 08:54:22.031501 kernel: ima: Allocated hash algorithm: sha1 Jul 1 08:54:22.031520 kernel: ima: No architecture policies found Jul 1 08:54:22.031543 kernel: clk: Disabling unused clocks Jul 1 08:54:22.031563 kernel: Warning: unable to open an initial console. Jul 1 08:54:22.031583 kernel: Freeing unused kernel image (initmem) memory: 54508K Jul 1 08:54:22.031602 kernel: Write protecting the kernel read-only data: 24576k Jul 1 08:54:22.031621 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 1 08:54:22.031641 kernel: Run /init as init process Jul 1 08:54:22.031660 kernel: with arguments: Jul 1 08:54:22.031678 kernel: /init Jul 1 08:54:22.031697 kernel: with environment: Jul 1 08:54:22.031719 kernel: HOME=/ Jul 1 08:54:22.031737 kernel: TERM=linux Jul 1 08:54:22.031756 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 1 08:54:22.031820 systemd[1]: Successfully made /usr/ read-only. Jul 1 08:54:22.031852 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 1 08:54:22.031875 systemd[1]: Detected virtualization kvm. Jul 1 08:54:22.031896 systemd[1]: Detected architecture x86-64. Jul 1 08:54:22.031933 systemd[1]: Running in initrd. Jul 1 08:54:22.031990 systemd[1]: No hostname configured, using default hostname. Jul 1 08:54:22.032013 systemd[1]: Hostname set to . Jul 1 08:54:22.032034 systemd[1]: Initializing machine ID from VM UUID. Jul 1 08:54:22.032055 systemd[1]: Queued start job for default target initrd.target. Jul 1 08:54:22.032076 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:54:22.032103 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:54:22.032125 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 1 08:54:22.032147 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 1 08:54:22.032168 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 1 08:54:22.032191 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 1 08:54:22.032214 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 1 08:54:22.032235 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 1 08:54:22.032252 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:54:22.032266 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:54:22.032281 systemd[1]: Reached target paths.target - Path Units. Jul 1 08:54:22.032295 systemd[1]: Reached target slices.target - Slice Units. Jul 1 08:54:22.032307 systemd[1]: Reached target swap.target - Swaps. Jul 1 08:54:22.032318 systemd[1]: Reached target timers.target - Timer Units. Jul 1 08:54:22.032329 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 1 08:54:22.032341 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 1 08:54:22.032352 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 1 08:54:22.032365 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 1 08:54:22.032377 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:54:22.032388 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 1 08:54:22.032400 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:54:22.032411 systemd[1]: Reached target sockets.target - Socket Units. Jul 1 08:54:22.032422 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 1 08:54:22.032434 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 1 08:54:22.032446 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 1 08:54:22.032459 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Jul 1 08:54:22.032471 systemd[1]: Starting systemd-fsck-usr.service... Jul 1 08:54:22.032485 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 1 08:54:22.032497 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 1 08:54:22.032508 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:54:22.032521 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 1 08:54:22.032534 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:54:22.032546 systemd[1]: Finished systemd-fsck-usr.service. Jul 1 08:54:22.032557 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 1 08:54:22.032631 systemd-journald[213]: Collecting audit messages is disabled. Jul 1 08:54:22.032666 systemd-journald[213]: Journal started Jul 1 08:54:22.032708 systemd-journald[213]: Runtime Journal (/run/log/journal/349aa8e7aa044fd1b49ed065651e1eca) is 8M, max 78.5M, 70.5M free. Jul 1 08:54:22.034997 systemd[1]: Started systemd-journald.service - Journal Service. Jul 1 08:54:22.039264 systemd-modules-load[215]: Inserted module 'overlay' Jul 1 08:54:22.044158 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 1 08:54:22.064574 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:54:22.067521 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 1 08:54:22.075977 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 1 08:54:22.080997 kernel: Bridge firewalling registered Jul 1 08:54:22.081008 systemd-modules-load[215]: Inserted module 'br_netfilter' Jul 1 08:54:22.082511 systemd-tmpfiles[228]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 1 08:54:22.083393 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 1 08:54:22.119035 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:22.119836 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:54:22.121166 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:54:22.128248 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 1 08:54:22.133074 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 1 08:54:22.148999 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:54:22.153066 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 1 08:54:22.156979 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 1 08:54:22.159874 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 1 08:54:22.190807 dracut-cmdline[253]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:54:22.202115 systemd-resolved[248]: Positive Trust Anchors: Jul 1 08:54:22.202201 systemd-resolved[248]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 1 08:54:22.202244 systemd-resolved[248]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 1 08:54:22.206206 systemd-resolved[248]: Defaulting to hostname 'linux'. Jul 1 08:54:22.208568 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 1 08:54:22.213880 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:54:22.329048 kernel: SCSI subsystem initialized Jul 1 08:54:22.341009 kernel: Loading iSCSI transport class v2.0-870. Jul 1 08:54:22.354020 kernel: iscsi: registered transport (tcp) Jul 1 08:54:22.410363 kernel: iscsi: registered transport (qla4xxx) Jul 1 08:54:22.410478 kernel: QLogic iSCSI HBA Driver Jul 1 08:54:22.460292 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 1 08:54:22.482229 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:54:22.489332 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 1 08:54:22.581662 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 1 08:54:22.586511 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 1 08:54:22.662065 kernel: raid6: sse2x4 gen() 12502 MB/s Jul 1 08:54:22.680051 kernel: raid6: sse2x2 gen() 14450 MB/s Jul 1 08:54:22.698672 kernel: raid6: sse2x1 gen() 9291 MB/s Jul 1 08:54:22.698740 kernel: raid6: using algorithm sse2x2 gen() 14450 MB/s Jul 1 08:54:22.717471 kernel: raid6: .... xor() 8638 MB/s, rmw enabled Jul 1 08:54:22.717539 kernel: raid6: using ssse3x2 recovery algorithm Jul 1 08:54:22.773064 kernel: xor: measuring software checksum speed Jul 1 08:54:22.777070 kernel: prefetch64-sse : 2091 MB/sec Jul 1 08:54:22.785198 kernel: generic_sse : 1211 MB/sec Jul 1 08:54:22.785264 kernel: xor: using function: prefetch64-sse (2091 MB/sec) Jul 1 08:54:23.001053 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 1 08:54:23.012904 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 1 08:54:23.015574 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:54:23.078762 systemd-udevd[462]: Using default interface naming scheme 'v255'. Jul 1 08:54:23.095142 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:54:23.102327 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 1 08:54:23.131002 dracut-pre-trigger[478]: rd.md=0: removing MD RAID activation Jul 1 08:54:23.168133 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 1 08:54:23.173338 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 1 08:54:23.252348 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:54:23.260904 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 1 08:54:23.353024 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Jul 1 08:54:23.382982 kernel: virtio_blk virtio2: [vda] 20971520 512-byte logical blocks (10.7 GB/10.0 GiB) Jul 1 08:54:23.391476 kernel: libata version 3.00 loaded. Jul 1 08:54:23.393067 kernel: ata_piix 0000:00:01.1: version 2.13 Jul 1 08:54:23.396256 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:54:23.400166 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Jul 1 08:54:23.396428 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:23.403356 kernel: scsi host0: ata_piix Jul 1 08:54:23.403402 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 1 08:54:23.403416 kernel: GPT:17805311 != 20971519 Jul 1 08:54:23.403428 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 1 08:54:23.403445 kernel: GPT:17805311 != 20971519 Jul 1 08:54:23.403458 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 1 08:54:23.403470 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 1 08:54:23.402483 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:54:23.419787 kernel: scsi host1: ata_piix Jul 1 08:54:23.419974 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 lpm-pol 0 Jul 1 08:54:23.419991 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 lpm-pol 0 Jul 1 08:54:23.420188 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:54:23.421186 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:54:23.498758 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:23.623681 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 1 08:54:23.634978 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 1 08:54:23.647647 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 1 08:54:23.667264 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 1 08:54:23.676998 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 1 08:54:23.677646 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 1 08:54:23.680118 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 1 08:54:23.683135 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:54:23.685404 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 1 08:54:23.688632 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 1 08:54:23.693108 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 1 08:54:23.710866 disk-uuid[568]: Primary Header is updated. Jul 1 08:54:23.710866 disk-uuid[568]: Secondary Entries is updated. Jul 1 08:54:23.710866 disk-uuid[568]: Secondary Header is updated. Jul 1 08:54:23.725996 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 1 08:54:23.749207 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 1 08:54:24.744140 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 1 08:54:24.748642 disk-uuid[569]: The operation has completed successfully. Jul 1 08:54:24.832491 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 1 08:54:24.832676 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 1 08:54:24.888789 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 1 08:54:24.918592 sh[587]: Success Jul 1 08:54:24.974634 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 1 08:54:24.974768 kernel: device-mapper: uevent: version 1.0.3 Jul 1 08:54:24.983459 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 1 08:54:24.998027 kernel: device-mapper: verity: sha256 using shash "sha256-ssse3" Jul 1 08:54:25.099556 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 1 08:54:25.107211 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 1 08:54:25.129183 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 1 08:54:25.159400 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 1 08:54:25.159511 kernel: BTRFS: device fsid aeab36fb-d8a9-440c-a872-a8cce0218739 devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (599) Jul 1 08:54:25.169458 kernel: BTRFS info (device dm-0): first mount of filesystem aeab36fb-d8a9-440c-a872-a8cce0218739 Jul 1 08:54:25.169564 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:54:25.171301 kernel: BTRFS info (device dm-0): using free-space-tree Jul 1 08:54:25.186982 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 1 08:54:25.190169 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 1 08:54:25.191661 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 1 08:54:25.193646 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 1 08:54:25.197061 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 1 08:54:25.249001 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (640) Jul 1 08:54:25.252967 kernel: BTRFS info (device vda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:54:25.256832 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:54:25.256862 kernel: BTRFS info (device vda6): using free-space-tree Jul 1 08:54:25.276004 kernel: BTRFS info (device vda6): last unmount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:54:25.279751 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 1 08:54:25.284084 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 1 08:54:25.336488 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 1 08:54:25.340405 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 1 08:54:25.399244 systemd-networkd[771]: lo: Link UP Jul 1 08:54:25.399256 systemd-networkd[771]: lo: Gained carrier Jul 1 08:54:25.400698 systemd-networkd[771]: Enumeration completed Jul 1 08:54:25.402821 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 1 08:54:25.403452 systemd[1]: Reached target network.target - Network. Jul 1 08:54:25.404686 systemd-networkd[771]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:54:25.404690 systemd-networkd[771]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 1 08:54:25.406267 systemd-networkd[771]: eth0: Link UP Jul 1 08:54:25.406270 systemd-networkd[771]: eth0: Gained carrier Jul 1 08:54:25.406280 systemd-networkd[771]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:54:25.465083 systemd-networkd[771]: eth0: DHCPv4 address 172.24.4.138/24, gateway 172.24.4.1 acquired from 172.24.4.1 Jul 1 08:54:25.561866 ignition[707]: Ignition 2.21.0 Jul 1 08:54:25.562708 ignition[707]: Stage: fetch-offline Jul 1 08:54:25.562785 ignition[707]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:25.562800 ignition[707]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:25.563234 ignition[707]: parsed url from cmdline: "" Jul 1 08:54:25.563239 ignition[707]: no config URL provided Jul 1 08:54:25.563246 ignition[707]: reading system config file "/usr/lib/ignition/user.ign" Jul 1 08:54:25.563261 ignition[707]: no config at "/usr/lib/ignition/user.ign" Jul 1 08:54:25.567023 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 1 08:54:25.563267 ignition[707]: failed to fetch config: resource requires networking Jul 1 08:54:25.563584 ignition[707]: Ignition finished successfully Jul 1 08:54:25.571799 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 1 08:54:25.609829 ignition[781]: Ignition 2.21.0 Jul 1 08:54:25.609847 ignition[781]: Stage: fetch Jul 1 08:54:25.610024 ignition[781]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:25.610036 ignition[781]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:25.610122 ignition[781]: parsed url from cmdline: "" Jul 1 08:54:25.610126 ignition[781]: no config URL provided Jul 1 08:54:25.610132 ignition[781]: reading system config file "/usr/lib/ignition/user.ign" Jul 1 08:54:25.610141 ignition[781]: no config at "/usr/lib/ignition/user.ign" Jul 1 08:54:25.610247 ignition[781]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Jul 1 08:54:25.611711 ignition[781]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Jul 1 08:54:25.611739 ignition[781]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Jul 1 08:54:25.804004 ignition[781]: GET result: OK Jul 1 08:54:25.806191 ignition[781]: parsing config with SHA512: eaf26d91aa801ccb1927d9330ff632573ad57e3323402739e162f2f40a4d2c1656e38ee86a535b67f62f1f85eed9bb6724f2c4fff0dc7e025afe71139f4c2243 Jul 1 08:54:25.823511 unknown[781]: fetched base config from "system" Jul 1 08:54:25.823536 unknown[781]: fetched base config from "system" Jul 1 08:54:25.825056 ignition[781]: fetch: fetch complete Jul 1 08:54:25.823549 unknown[781]: fetched user config from "openstack" Jul 1 08:54:25.825076 ignition[781]: fetch: fetch passed Jul 1 08:54:25.830489 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 1 08:54:25.825199 ignition[781]: Ignition finished successfully Jul 1 08:54:25.835621 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 1 08:54:25.929062 ignition[788]: Ignition 2.21.0 Jul 1 08:54:25.929081 ignition[788]: Stage: kargs Jul 1 08:54:25.929288 ignition[788]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:25.929301 ignition[788]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:25.931359 ignition[788]: kargs: kargs passed Jul 1 08:54:25.931477 ignition[788]: Ignition finished successfully Jul 1 08:54:25.934454 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 1 08:54:25.939144 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 1 08:54:25.989330 ignition[795]: Ignition 2.21.0 Jul 1 08:54:25.989347 ignition[795]: Stage: disks Jul 1 08:54:25.989567 ignition[795]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:25.989580 ignition[795]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:25.995695 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 1 08:54:25.993759 ignition[795]: disks: disks passed Jul 1 08:54:25.997819 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 1 08:54:25.993896 ignition[795]: Ignition finished successfully Jul 1 08:54:25.999789 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 1 08:54:26.001691 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 1 08:54:26.003695 systemd[1]: Reached target sysinit.target - System Initialization. Jul 1 08:54:26.005540 systemd[1]: Reached target basic.target - Basic System. Jul 1 08:54:26.010237 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 1 08:54:26.065009 systemd-fsck[804]: ROOT: clean, 15/1628000 files, 120826/1617920 blocks Jul 1 08:54:26.079418 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 1 08:54:26.083179 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 1 08:54:26.300032 kernel: EXT4-fs (vda9): mounted filesystem 18421243-07cc-41b2-b496-d6a2cef84352 r/w with ordered data mode. Quota mode: none. Jul 1 08:54:26.302651 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 1 08:54:26.306404 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 1 08:54:26.311143 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 1 08:54:26.316136 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 1 08:54:26.321507 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 1 08:54:26.330303 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Jul 1 08:54:26.338770 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 1 08:54:26.338865 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 1 08:54:26.353232 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 1 08:54:26.359172 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 1 08:54:26.379996 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (812) Jul 1 08:54:26.389112 kernel: BTRFS info (device vda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:54:26.389192 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:54:26.391104 kernel: BTRFS info (device vda6): using free-space-tree Jul 1 08:54:26.402739 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 1 08:54:26.491013 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:26.511072 initrd-setup-root[840]: cut: /sysroot/etc/passwd: No such file or directory Jul 1 08:54:26.516057 initrd-setup-root[847]: cut: /sysroot/etc/group: No such file or directory Jul 1 08:54:26.528755 initrd-setup-root[854]: cut: /sysroot/etc/shadow: No such file or directory Jul 1 08:54:26.532246 initrd-setup-root[861]: cut: /sysroot/etc/gshadow: No such file or directory Jul 1 08:54:26.657922 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 1 08:54:26.660495 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 1 08:54:26.661668 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 1 08:54:26.684495 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 1 08:54:26.692386 kernel: BTRFS info (device vda6): last unmount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:54:26.693115 systemd-networkd[771]: eth0: Gained IPv6LL Jul 1 08:54:26.710283 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 1 08:54:26.724990 ignition[929]: INFO : Ignition 2.21.0 Jul 1 08:54:26.724990 ignition[929]: INFO : Stage: mount Jul 1 08:54:26.724990 ignition[929]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:26.724990 ignition[929]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:26.728686 ignition[929]: INFO : mount: mount passed Jul 1 08:54:26.728686 ignition[929]: INFO : Ignition finished successfully Jul 1 08:54:26.728198 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 1 08:54:27.544026 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:29.560023 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:33.588260 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:33.599622 coreos-metadata[814]: Jul 01 08:54:33.599 WARN failed to locate config-drive, using the metadata service API instead Jul 1 08:54:33.659481 coreos-metadata[814]: Jul 01 08:54:33.659 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Jul 1 08:54:33.676104 coreos-metadata[814]: Jul 01 08:54:33.676 INFO Fetch successful Jul 1 08:54:33.677473 coreos-metadata[814]: Jul 01 08:54:33.677 INFO wrote hostname ci-9999-9-9-s-ba07dcb833.novalocal to /sysroot/etc/hostname Jul 1 08:54:33.682739 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Jul 1 08:54:33.684066 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Jul 1 08:54:33.695652 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 1 08:54:33.742138 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 1 08:54:33.779053 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (946) Jul 1 08:54:33.787253 kernel: BTRFS info (device vda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:54:33.787325 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:54:33.791247 kernel: BTRFS info (device vda6): using free-space-tree Jul 1 08:54:33.806913 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 1 08:54:33.868095 ignition[964]: INFO : Ignition 2.21.0 Jul 1 08:54:33.871609 ignition[964]: INFO : Stage: files Jul 1 08:54:33.871609 ignition[964]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:33.871609 ignition[964]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:33.871609 ignition[964]: DEBUG : files: compiled without relabeling support, skipping Jul 1 08:54:33.877718 ignition[964]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 1 08:54:33.877718 ignition[964]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 1 08:54:33.881683 ignition[964]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 1 08:54:33.882424 ignition[964]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 1 08:54:33.883264 ignition[964]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 1 08:54:33.882735 unknown[964]: wrote ssh authorized keys file for user: core Jul 1 08:54:33.888371 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 1 08:54:33.889286 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 1 08:54:33.897667 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 1 08:54:33.898702 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 1 08:54:33.898702 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 1 08:54:33.902993 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 1 08:54:33.902993 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 1 08:54:33.905279 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-x86-64.raw: attempt #1 Jul 1 08:54:34.731537 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 1 08:54:36.910870 ignition[964]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Jul 1 08:54:36.915385 ignition[964]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 1 08:54:36.915385 ignition[964]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 1 08:54:36.915385 ignition[964]: INFO : files: files passed Jul 1 08:54:36.915385 ignition[964]: INFO : Ignition finished successfully Jul 1 08:54:36.914634 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 1 08:54:36.919105 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 1 08:54:36.924093 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 1 08:54:36.940561 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 1 08:54:36.942265 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 1 08:54:36.946000 initrd-setup-root-after-ignition[994]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:54:36.946000 initrd-setup-root-after-ignition[994]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:54:36.950406 initrd-setup-root-after-ignition[998]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:54:36.947491 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 1 08:54:36.949127 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 1 08:54:36.951895 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 1 08:54:37.024591 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 1 08:54:37.024875 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 1 08:54:37.028015 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 1 08:54:37.030483 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 1 08:54:37.033741 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 1 08:54:37.037127 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 1 08:54:37.081778 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 1 08:54:37.087167 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 1 08:54:37.133221 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:54:37.136601 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:54:37.138444 systemd[1]: Stopped target timers.target - Timer Units. Jul 1 08:54:37.142439 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 1 08:54:37.142911 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 1 08:54:37.146706 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 1 08:54:37.148377 systemd[1]: Stopped target basic.target - Basic System. Jul 1 08:54:37.151026 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 1 08:54:37.153478 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 1 08:54:37.156457 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 1 08:54:37.159331 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 1 08:54:37.162296 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 1 08:54:37.165215 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 1 08:54:37.168232 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 1 08:54:37.171018 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 1 08:54:37.174076 systemd[1]: Stopped target swap.target - Swaps. Jul 1 08:54:37.176330 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 1 08:54:37.176745 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 1 08:54:37.179520 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:54:37.181257 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:54:37.184190 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 1 08:54:37.184823 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:54:37.187204 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 1 08:54:37.187672 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 1 08:54:37.190870 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 1 08:54:37.191356 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 1 08:54:37.194715 systemd[1]: ignition-files.service: Deactivated successfully. Jul 1 08:54:37.195097 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 1 08:54:37.200182 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 1 08:54:37.204207 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 1 08:54:37.206744 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 1 08:54:37.208263 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:54:37.212363 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 1 08:54:37.214234 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 1 08:54:37.227869 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 1 08:54:37.227983 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 1 08:54:37.249308 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 1 08:54:37.250846 ignition[1018]: INFO : Ignition 2.21.0 Jul 1 08:54:37.250846 ignition[1018]: INFO : Stage: umount Jul 1 08:54:37.253996 ignition[1018]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:54:37.255271 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 1 08:54:37.255399 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 1 08:54:37.257939 ignition[1018]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Jul 1 08:54:37.260051 ignition[1018]: INFO : umount: umount passed Jul 1 08:54:37.260051 ignition[1018]: INFO : Ignition finished successfully Jul 1 08:54:37.260468 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 1 08:54:37.260583 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 1 08:54:37.262206 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 1 08:54:37.262309 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 1 08:54:37.263267 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 1 08:54:37.263330 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 1 08:54:37.264472 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 1 08:54:37.264555 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 1 08:54:37.266187 systemd[1]: Stopped target network.target - Network. Jul 1 08:54:37.267378 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 1 08:54:37.267448 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 1 08:54:37.268768 systemd[1]: Stopped target paths.target - Path Units. Jul 1 08:54:37.269893 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 1 08:54:37.273379 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:54:37.274354 systemd[1]: Stopped target slices.target - Slice Units. Jul 1 08:54:37.275778 systemd[1]: Stopped target sockets.target - Socket Units. Jul 1 08:54:37.277110 systemd[1]: iscsid.socket: Deactivated successfully. Jul 1 08:54:37.277215 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 1 08:54:37.278267 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 1 08:54:37.278331 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 1 08:54:37.279544 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 1 08:54:37.279637 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 1 08:54:37.280688 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 1 08:54:37.280770 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 1 08:54:37.281675 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 1 08:54:37.281741 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 1 08:54:37.283004 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 1 08:54:37.284322 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 1 08:54:37.294116 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 1 08:54:37.294936 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 1 08:54:37.297476 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 1 08:54:37.298489 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 1 08:54:37.298666 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 1 08:54:37.301780 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 1 08:54:37.302517 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 1 08:54:37.303570 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 1 08:54:37.303617 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:54:37.305606 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 1 08:54:37.307519 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 1 08:54:37.307608 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 1 08:54:37.309468 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 1 08:54:37.309535 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:54:37.312048 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 1 08:54:37.312105 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 1 08:54:37.316488 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 1 08:54:37.316551 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:54:37.318103 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:54:37.320140 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 1 08:54:37.320198 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:54:37.327612 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 1 08:54:37.334276 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:54:37.335278 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 1 08:54:37.335326 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 1 08:54:37.336376 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 1 08:54:37.336404 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:54:37.337502 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 1 08:54:37.337547 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 1 08:54:37.339117 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 1 08:54:37.339163 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 1 08:54:37.340322 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 1 08:54:37.340376 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 1 08:54:37.342105 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 1 08:54:37.343671 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 1 08:54:37.343735 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:54:37.346013 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 1 08:54:37.346100 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:54:37.348609 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 1 08:54:37.348670 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:54:37.349890 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 1 08:54:37.349934 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:54:37.350828 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:54:37.350883 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:37.356367 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 1 08:54:37.356426 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 1 08:54:37.356479 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 1 08:54:37.356532 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:54:37.356912 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 1 08:54:37.357029 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 1 08:54:37.360025 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 1 08:54:37.360113 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 1 08:54:37.362395 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 1 08:54:37.365064 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 1 08:54:37.381864 systemd[1]: Switching root. Jul 1 08:54:37.417943 systemd-journald[213]: Journal stopped Jul 1 08:54:39.271419 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Jul 1 08:54:39.271477 kernel: SELinux: policy capability network_peer_controls=1 Jul 1 08:54:39.271496 kernel: SELinux: policy capability open_perms=1 Jul 1 08:54:39.271509 kernel: SELinux: policy capability extended_socket_class=1 Jul 1 08:54:39.271524 kernel: SELinux: policy capability always_check_network=0 Jul 1 08:54:39.271539 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 1 08:54:39.271552 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 1 08:54:39.271563 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 1 08:54:39.271578 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 1 08:54:39.271592 kernel: SELinux: policy capability userspace_initial_context=0 Jul 1 08:54:39.271604 kernel: audit: type=1403 audit(1751360078.007:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 1 08:54:39.271617 systemd[1]: Successfully loaded SELinux policy in 98.098ms. Jul 1 08:54:39.271637 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.865ms. Jul 1 08:54:39.271654 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jul 1 08:54:39.271668 systemd[1]: Detected virtualization kvm. Jul 1 08:54:39.271680 systemd[1]: Detected architecture x86-64. Jul 1 08:54:39.271694 systemd[1]: Detected first boot. Jul 1 08:54:39.271707 systemd[1]: Hostname set to . Jul 1 08:54:39.271720 systemd[1]: Initializing machine ID from VM UUID. Jul 1 08:54:39.271736 zram_generator::config[1061]: No configuration found. Jul 1 08:54:39.271752 kernel: Guest personality initialized and is inactive Jul 1 08:54:39.271768 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Jul 1 08:54:39.271780 kernel: Initialized host personality Jul 1 08:54:39.271791 kernel: NET: Registered PF_VSOCK protocol family Jul 1 08:54:39.271804 systemd[1]: Populated /etc with preset unit settings. Jul 1 08:54:39.271818 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 1 08:54:39.271831 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 1 08:54:39.271868 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 1 08:54:39.271882 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 1 08:54:39.271895 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 1 08:54:39.271910 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 1 08:54:39.271923 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 1 08:54:39.271936 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 1 08:54:39.274606 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 1 08:54:39.274641 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 1 08:54:39.274656 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 1 08:54:39.274670 systemd[1]: Created slice user.slice - User and Session Slice. Jul 1 08:54:39.274683 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:54:39.274697 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:54:39.274716 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 1 08:54:39.274730 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 1 08:54:39.274743 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 1 08:54:39.274756 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 1 08:54:39.274785 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 1 08:54:39.274802 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:54:39.274816 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:54:39.274828 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 1 08:54:39.274841 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 1 08:54:39.274854 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 1 08:54:39.274867 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 1 08:54:39.274880 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:54:39.274893 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 1 08:54:39.274906 systemd[1]: Reached target slices.target - Slice Units. Jul 1 08:54:39.274919 systemd[1]: Reached target swap.target - Swaps. Jul 1 08:54:39.274934 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 1 08:54:39.274961 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 1 08:54:39.274976 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 1 08:54:39.275010 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:54:39.275024 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 1 08:54:39.275037 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:54:39.275054 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 1 08:54:39.275068 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 1 08:54:39.275080 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 1 08:54:39.275095 systemd[1]: Mounting media.mount - External Media Directory... Jul 1 08:54:39.275120 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:39.275134 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 1 08:54:39.275147 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 1 08:54:39.275160 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 1 08:54:39.275173 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). Jul 1 08:54:39.275187 systemd[1]: Reached target machines.target - Containers. Jul 1 08:54:39.275200 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 1 08:54:39.275215 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:54:39.275228 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 1 08:54:39.275241 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 1 08:54:39.275255 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 1 08:54:39.275267 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 1 08:54:39.275280 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 1 08:54:39.275293 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 1 08:54:39.275306 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 1 08:54:39.275319 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 1 08:54:39.275334 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 1 08:54:39.275347 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 1 08:54:39.275360 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 1 08:54:39.275372 systemd[1]: Stopped systemd-fsck-usr.service. Jul 1 08:54:39.275386 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 1 08:54:39.275410 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 1 08:54:39.275426 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 1 08:54:39.275439 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 1 08:54:39.275452 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 1 08:54:39.275465 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 1 08:54:39.275479 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 1 08:54:39.275495 systemd[1]: verity-setup.service: Deactivated successfully. Jul 1 08:54:39.275508 systemd[1]: Stopped verity-setup.service. Jul 1 08:54:39.275522 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:39.275534 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 1 08:54:39.275548 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 1 08:54:39.275560 systemd[1]: Mounted media.mount - External Media Directory. Jul 1 08:54:39.275573 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 1 08:54:39.275588 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 1 08:54:39.275645 systemd-journald[1144]: Collecting audit messages is disabled. Jul 1 08:54:39.275670 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 1 08:54:39.275685 kernel: fuse: init (API version 7.41) Jul 1 08:54:39.275699 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:54:39.275713 systemd-journald[1144]: Journal started Jul 1 08:54:39.275741 systemd-journald[1144]: Runtime Journal (/run/log/journal/349aa8e7aa044fd1b49ed065651e1eca) is 8M, max 78.5M, 70.5M free. Jul 1 08:54:38.892102 systemd[1]: Queued start job for default target multi-user.target. Jul 1 08:54:38.917450 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 1 08:54:38.917921 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 1 08:54:39.279241 systemd[1]: Started systemd-journald.service - Journal Service. Jul 1 08:54:39.281671 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 1 08:54:39.281973 kernel: loop: module loaded Jul 1 08:54:39.282680 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 1 08:54:39.285409 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 1 08:54:39.285605 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 1 08:54:39.287571 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 1 08:54:39.287763 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 1 08:54:39.289167 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 1 08:54:39.289339 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 1 08:54:39.290309 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 1 08:54:39.290463 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 1 08:54:39.292293 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 1 08:54:39.293266 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:54:39.295250 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 1 08:54:39.308626 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 1 08:54:39.311781 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 1 08:54:39.315038 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 1 08:54:39.318085 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 1 08:54:39.318122 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 1 08:54:39.326135 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 1 08:54:39.332642 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 1 08:54:39.339058 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:54:39.366296 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 1 08:54:39.370378 kernel: ACPI: bus type drm_connector registered Jul 1 08:54:39.369756 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 1 08:54:39.370468 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 1 08:54:39.373113 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 1 08:54:39.373694 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 1 08:54:39.377143 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 1 08:54:39.379118 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 1 08:54:39.384178 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 1 08:54:39.388432 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 1 08:54:39.389842 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 1 08:54:39.390057 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 1 08:54:39.392081 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 1 08:54:39.392963 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 1 08:54:39.399624 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 1 08:54:39.424858 systemd-journald[1144]: Time spent on flushing to /var/log/journal/349aa8e7aa044fd1b49ed065651e1eca is 60.336ms for 958 entries. Jul 1 08:54:39.424858 systemd-journald[1144]: System Journal (/var/log/journal/349aa8e7aa044fd1b49ed065651e1eca) is 8M, max 584.8M, 576.8M free. Jul 1 08:54:39.605188 systemd-journald[1144]: Received client request to flush runtime journal. Jul 1 08:54:39.605228 kernel: loop0: detected capacity change from 0 to 146336 Jul 1 08:54:39.432899 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:54:39.549917 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:54:39.552301 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 1 08:54:39.555855 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 1 08:54:39.562222 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 1 08:54:39.595396 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Jul 1 08:54:39.595413 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Jul 1 08:54:39.601207 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:54:39.608208 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 1 08:54:39.609998 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 1 08:54:39.723909 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 1 08:54:39.741171 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 1 08:54:39.769031 kernel: loop1: detected capacity change from 0 to 8 Jul 1 08:54:39.788526 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 1 08:54:39.795605 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 1 08:54:39.802004 kernel: loop2: detected capacity change from 0 to 221472 Jul 1 08:54:39.831224 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Jul 1 08:54:39.831533 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Jul 1 08:54:39.836859 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:54:39.865046 kernel: loop3: detected capacity change from 0 to 114000 Jul 1 08:54:39.923095 kernel: loop4: detected capacity change from 0 to 146336 Jul 1 08:54:39.922236 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 1 08:54:40.098995 kernel: loop5: detected capacity change from 0 to 8 Jul 1 08:54:40.117282 kernel: loop6: detected capacity change from 0 to 221472 Jul 1 08:54:40.215019 kernel: loop7: detected capacity change from 0 to 114000 Jul 1 08:54:40.264125 (sd-merge)[1226]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-openstack'. Jul 1 08:54:40.266065 (sd-merge)[1226]: Merged extensions into '/usr'. Jul 1 08:54:40.277112 systemd[1]: Reload requested from client PID 1197 ('systemd-sysext') (unit systemd-sysext.service)... Jul 1 08:54:40.277341 systemd[1]: Reloading... Jul 1 08:54:40.478980 zram_generator::config[1252]: No configuration found. Jul 1 08:54:40.745489 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. Jul 1 08:54:40.860257 systemd[1]: Reloading finished in 582 ms. Jul 1 08:54:40.885436 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 1 08:54:40.891405 systemd[1]: Starting ensure-sysext.service... Jul 1 08:54:40.895066 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 1 08:54:40.926224 systemd-tmpfiles[1309]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 1 08:54:40.926272 systemd-tmpfiles[1309]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 1 08:54:40.926530 systemd-tmpfiles[1309]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 1 08:54:40.927037 systemd-tmpfiles[1309]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 1 08:54:40.927866 systemd-tmpfiles[1309]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 1 08:54:40.928217 systemd-tmpfiles[1309]: ACLs are not supported, ignoring. Jul 1 08:54:40.928280 systemd-tmpfiles[1309]: ACLs are not supported, ignoring. Jul 1 08:54:40.951735 systemd-tmpfiles[1309]: Detected autofs mount point /boot during canonicalization of boot. Jul 1 08:54:40.951748 systemd-tmpfiles[1309]: Skipping /boot Jul 1 08:54:40.961189 systemd[1]: Reload requested from client PID 1308 ('systemctl') (unit ensure-sysext.service)... Jul 1 08:54:40.961220 systemd[1]: Reloading... Jul 1 08:54:40.967503 systemd-tmpfiles[1309]: Detected autofs mount point /boot during canonicalization of boot. Jul 1 08:54:40.967668 systemd-tmpfiles[1309]: Skipping /boot Jul 1 08:54:41.074266 zram_generator::config[1344]: No configuration found. Jul 1 08:54:41.106977 ldconfig[1189]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 1 08:54:41.190141 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. Jul 1 08:54:41.293421 systemd[1]: Reloading finished in 331 ms. Jul 1 08:54:41.318389 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 1 08:54:41.319636 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 1 08:54:41.325788 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:54:41.336178 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 1 08:54:41.340326 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 1 08:54:41.348111 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 1 08:54:41.351183 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 1 08:54:41.354690 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:54:41.357672 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 1 08:54:41.366753 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.366990 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:54:41.369758 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 1 08:54:41.375366 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 1 08:54:41.383322 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 1 08:54:41.384128 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:54:41.385313 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 1 08:54:41.385438 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.394492 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 1 08:54:41.397980 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.398166 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:54:41.398337 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:54:41.398435 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 1 08:54:41.398541 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.410855 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.411232 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:54:41.415023 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 1 08:54:41.416509 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:54:41.416715 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Jul 1 08:54:41.417222 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:54:41.424273 systemd[1]: Finished ensure-sysext.service. Jul 1 08:54:41.425131 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 1 08:54:41.437283 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 1 08:54:41.446832 systemd-udevd[1401]: Using default interface naming scheme 'v255'. Jul 1 08:54:41.464047 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 1 08:54:41.466231 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 1 08:54:41.467167 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 1 08:54:41.473279 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 1 08:54:41.476917 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 1 08:54:41.479328 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 1 08:54:41.483689 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 1 08:54:41.485198 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 1 08:54:41.485376 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 1 08:54:41.486222 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 1 08:54:41.487147 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 1 08:54:41.489649 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 1 08:54:41.496801 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:54:41.503153 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 1 08:54:41.519010 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 1 08:54:41.520640 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). Jul 1 08:54:41.545349 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 1 08:54:41.564706 augenrules[1466]: No rules Jul 1 08:54:41.563978 systemd[1]: audit-rules.service: Deactivated successfully. Jul 1 08:54:41.564177 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 1 08:54:41.589338 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 1 08:54:41.657887 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 1 08:54:41.689433 kernel: mousedev: PS/2 mouse device common for all mice Jul 1 08:54:41.751979 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Jul 1 08:54:41.767983 kernel: ACPI: button: Power Button [PWRF] Jul 1 08:54:41.778673 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 1 08:54:41.783716 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 1 08:54:41.813519 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 1 08:54:41.823008 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Jul 1 08:54:41.828973 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Jul 1 08:54:41.829011 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Jul 1 08:54:41.833005 kernel: Console: switching to colour dummy device 80x25 Jul 1 08:54:41.834872 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Jul 1 08:54:41.834919 kernel: [drm] features: -context_init Jul 1 08:54:41.837309 kernel: [drm] number of scanouts: 1 Jul 1 08:54:41.837349 kernel: [drm] number of cap sets: 0 Jul 1 08:54:41.841001 kernel: [drm] Initialized virtio_gpu 0.1.0 for 0000:00:02.0 on minor 0 Jul 1 08:54:41.849988 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Jul 1 08:54:41.879291 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:54:41.902233 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:54:41.903144 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:41.909409 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:54:41.968663 systemd-networkd[1441]: lo: Link UP Jul 1 08:54:41.968674 systemd-networkd[1441]: lo: Gained carrier Jul 1 08:54:41.970140 systemd-networkd[1441]: Enumeration completed Jul 1 08:54:41.970250 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 1 08:54:41.971983 systemd-networkd[1441]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:54:41.971991 systemd-networkd[1441]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 1 08:54:41.972705 systemd-networkd[1441]: eth0: Link UP Jul 1 08:54:41.972836 systemd-networkd[1441]: eth0: Gained carrier Jul 1 08:54:41.972851 systemd-networkd[1441]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:54:41.973304 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 1 08:54:41.978296 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 1 08:54:41.989010 systemd-networkd[1441]: eth0: DHCPv4 address 172.24.4.138/24, gateway 172.24.4.1 acquired from 172.24.4.1 Jul 1 08:54:42.004296 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 1 08:54:42.030683 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 1 08:54:42.030884 systemd[1]: Reached target time-set.target - System Time Set. Jul 1 08:54:42.058268 systemd-resolved[1400]: Positive Trust Anchors: Jul 1 08:54:42.058284 systemd-resolved[1400]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 1 08:54:42.058326 systemd-resolved[1400]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Jul 1 08:54:42.068518 systemd-resolved[1400]: Using system hostname 'ci-9999-9-9-s-ba07dcb833.novalocal'. Jul 1 08:54:42.070119 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 1 08:54:42.070252 systemd[1]: Reached target network.target - Network. Jul 1 08:54:42.070336 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:54:42.073006 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:54:42.073247 systemd[1]: Reached target sysinit.target - System Initialization. Jul 1 08:54:42.073396 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 1 08:54:42.073522 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 1 08:54:42.073610 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 1 08:54:42.073878 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 1 08:54:42.074065 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 1 08:54:42.074142 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 1 08:54:42.074199 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 1 08:54:42.074229 systemd[1]: Reached target paths.target - Path Units. Jul 1 08:54:42.074286 systemd[1]: Reached target timers.target - Timer Units. Jul 1 08:54:42.099982 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 1 08:54:42.102629 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 1 08:54:42.106742 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 1 08:54:42.108197 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 1 08:54:42.108283 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 1 08:54:42.117576 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 1 08:54:42.118005 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 1 08:54:42.119466 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 1 08:54:42.120585 systemd[1]: Reached target sockets.target - Socket Units. Jul 1 08:54:42.121059 systemd[1]: Reached target basic.target - Basic System. Jul 1 08:54:42.121182 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 1 08:54:42.121224 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 1 08:54:42.124219 systemd[1]: Starting containerd.service - containerd container runtime... Jul 1 08:54:42.125603 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 1 08:54:42.126640 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 1 08:54:42.130140 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 1 08:54:42.132151 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 1 08:54:42.139179 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 1 08:54:42.139276 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). Jul 1 08:54:42.143450 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 1 08:54:42.153816 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 1 08:54:42.156062 jq[1526]: false Jul 1 08:54:42.156363 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 1 08:54:42.159066 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 1 08:54:42.168861 extend-filesystems[1527]: Found /dev/vda6 Jul 1 08:54:42.169042 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 1 08:54:42.170344 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 1 08:54:42.170890 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 1 08:54:42.174984 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:42.175046 extend-filesystems[1527]: Found /dev/vda9 Jul 1 08:54:42.175389 systemd[1]: Starting update-engine.service - Update Engine... Jul 1 08:54:42.178657 extend-filesystems[1527]: Checking size of /dev/vda9 Jul 1 08:54:42.181601 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 1 08:54:42.188080 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Refreshing passwd entry cache Jul 1 08:54:42.190978 oslogin_cache_refresh[1528]: Refreshing passwd entry cache Jul 1 08:54:42.192143 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 1 08:54:42.192595 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 1 08:54:42.192766 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 1 08:54:42.193018 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 1 08:54:42.193182 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 1 08:54:42.202588 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Failure getting users, quitting Jul 1 08:54:42.202588 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 1 08:54:42.202588 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Refreshing group entry cache Jul 1 08:54:42.202344 oslogin_cache_refresh[1528]: Failure getting users, quitting Jul 1 08:54:42.202372 oslogin_cache_refresh[1528]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 1 08:54:42.202435 oslogin_cache_refresh[1528]: Refreshing group entry cache Jul 1 08:54:42.211891 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 1 08:54:42.214180 jq[1541]: true Jul 1 08:54:42.214274 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Failure getting groups, quitting Jul 1 08:54:42.214274 google_oslogin_nss_cache[1528]: oslogin_cache_refresh[1528]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 1 08:54:42.208252 oslogin_cache_refresh[1528]: Failure getting groups, quitting Jul 1 08:54:42.212100 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 1 08:54:42.208264 oslogin_cache_refresh[1528]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 1 08:54:42.223593 (ntainerd)[1550]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 1 08:54:42.250999 extend-filesystems[1527]: Resized partition /dev/vda9 Jul 1 08:54:42.251157 jq[1551]: true Jul 1 08:54:42.252969 extend-filesystems[1561]: resize2fs 1.47.2 (1-Jan-2025) Jul 1 08:54:43.357082 systemd-timesyncd[1416]: Contacted time server 23.131.160.7:123 (0.flatcar.pool.ntp.org). Jul 1 08:54:43.357123 systemd-resolved[1400]: Clock change detected. Flushing caches. Jul 1 08:54:43.357193 systemd-timesyncd[1416]: Initial clock synchronization to Tue 2025-07-01 08:54:43.356788 UTC. Jul 1 08:54:43.372093 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 2014203 blocks Jul 1 08:54:43.372169 update_engine[1538]: I20250701 08:54:43.371083 1538 main.cc:92] Flatcar Update Engine starting Jul 1 08:54:43.392612 kernel: EXT4-fs (vda9): resized filesystem to 2014203 Jul 1 08:54:43.405518 systemd[1]: motdgen.service: Deactivated successfully. Jul 1 08:54:43.414418 dbus-daemon[1524]: [system] SELinux support is enabled Jul 1 08:54:43.466318 update_engine[1538]: I20250701 08:54:43.423808 1538 update_check_scheduler.cc:74] Next update check in 10m9s Jul 1 08:54:43.405746 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 1 08:54:43.414863 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 1 08:54:43.419376 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). Jul 1 08:54:43.419412 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 1 08:54:43.421472 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). Jul 1 08:54:43.421492 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 1 08:54:43.439829 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 1 08:54:43.442837 systemd[1]: Started update-engine.service - Update Engine. Jul 1 08:54:43.457856 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 1 08:54:43.468473 extend-filesystems[1561]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 1 08:54:43.468473 extend-filesystems[1561]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 1 08:54:43.468473 extend-filesystems[1561]: The filesystem on /dev/vda9 is now 2014203 (4k) blocks long. Jul 1 08:54:43.470546 extend-filesystems[1527]: Resized filesystem in /dev/vda9 Jul 1 08:54:43.469662 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 1 08:54:43.469920 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 1 08:54:43.481938 systemd-logind[1537]: New seat seat0. Jul 1 08:54:43.484566 systemd-logind[1537]: Watching system buttons on /dev/input/event2 (Power Button) Jul 1 08:54:43.484595 systemd-logind[1537]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 1 08:54:43.488622 systemd[1]: Started systemd-logind.service - User Login Management. Jul 1 08:54:43.506919 bash[1582]: Updated "/home/core/.ssh/authorized_keys" Jul 1 08:54:43.508769 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 1 08:54:43.515885 systemd[1]: Starting sshkeys.service... Jul 1 08:54:43.572100 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Jul 1 08:54:43.576606 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Jul 1 08:54:43.662661 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:43.748441 locksmithd[1586]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 1 08:54:43.982153 containerd[1550]: time="2025-07-01T08:54:43Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Jul 1 08:54:43.983485 containerd[1550]: time="2025-07-01T08:54:43.983113286Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.005674689Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.778µs" Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.005723701Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.005761592Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.006019747Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.006044182Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.006108453Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.006218850Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006425 containerd[1550]: time="2025-07-01T08:54:44.006237725Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006704 containerd[1550]: time="2025-07-01T08:54:44.006556523Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006704 containerd[1550]: time="2025-07-01T08:54:44.006578294Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006704 containerd[1550]: time="2025-07-01T08:54:44.006593212Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006704 containerd[1550]: time="2025-07-01T08:54:44.006604654Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 1 08:54:44.006809 containerd[1550]: time="2025-07-01T08:54:44.006761638Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 1 08:54:44.007082 containerd[1550]: time="2025-07-01T08:54:44.007058164Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 1 08:54:44.007113 containerd[1550]: time="2025-07-01T08:54:44.007095634Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 1 08:54:44.007113 containerd[1550]: time="2025-07-01T08:54:44.007108519Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 1 08:54:44.007419 containerd[1550]: time="2025-07-01T08:54:44.007151539Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 1 08:54:44.007556 containerd[1550]: time="2025-07-01T08:54:44.007532233Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 1 08:54:44.007639 containerd[1550]: time="2025-07-01T08:54:44.007617643Z" level=info msg="metadata content store policy set" policy=shared Jul 1 08:54:44.023295 containerd[1550]: time="2025-07-01T08:54:44.023255743Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 1 08:54:44.023422 containerd[1550]: time="2025-07-01T08:54:44.023331775Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 1 08:54:44.023422 containerd[1550]: time="2025-07-01T08:54:44.023407247Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 1 08:54:44.023469 containerd[1550]: time="2025-07-01T08:54:44.023441732Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 1 08:54:44.023493 containerd[1550]: time="2025-07-01T08:54:44.023465296Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 1 08:54:44.023493 containerd[1550]: time="2025-07-01T08:54:44.023486576Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 1 08:54:44.023578 containerd[1550]: time="2025-07-01T08:54:44.023520930Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 1 08:54:44.023578 containerd[1550]: time="2025-07-01T08:54:44.023545176Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 1 08:54:44.023634 containerd[1550]: time="2025-07-01T08:54:44.023580893Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 1 08:54:44.023634 containerd[1550]: time="2025-07-01T08:54:44.023597333Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 1 08:54:44.023634 containerd[1550]: time="2025-07-01T08:54:44.023621509Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 1 08:54:44.023709 containerd[1550]: time="2025-07-01T08:54:44.023654370Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023838426Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023879142Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023897366Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023915250Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023929256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023940447Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023953902Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023971695Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.023989198Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.024009086Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.024026388Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.024170899Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.024205103Z" level=info msg="Start snapshots syncer" Jul 1 08:54:44.024403 containerd[1550]: time="2025-07-01T08:54:44.024247102Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 1 08:54:44.025004 containerd[1550]: time="2025-07-01T08:54:44.024859250Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Jul 1 08:54:44.025004 containerd[1550]: time="2025-07-01T08:54:44.024940282Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025064525Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025180402Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025210589Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025222612Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025236367Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025249522Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025260453Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 1 08:54:44.025300 containerd[1550]: time="2025-07-01T08:54:44.025278005Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025321758Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025342887Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025379716Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025429029Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025449467Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025459887Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025470677Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025479894Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025490644Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025501535Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025529617Z" level=info msg="runtime interface created" Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025536220Z" level=info msg="created NRI interface" Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025555857Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025570775Z" level=info msg="Connect containerd service" Jul 1 08:54:44.026753 containerd[1550]: time="2025-07-01T08:54:44.025598196Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 1 08:54:44.027121 containerd[1550]: time="2025-07-01T08:54:44.026782377Z" 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" Jul 1 08:54:44.346538 sshd_keygen[1567]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 1 08:54:44.381586 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 1 08:54:44.411831 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 1 08:54:44.415570 systemd[1]: Started sshd@0-172.24.4.138:22-172.24.4.1:56598.service - OpenSSH per-connection server daemon (172.24.4.1:56598). Jul 1 08:54:44.447935 systemd[1]: issuegen.service: Deactivated successfully. Jul 1 08:54:44.448657 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 1 08:54:44.454147 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 1 08:54:44.492579 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 1 08:54:44.496298 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 1 08:54:44.501635 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 1 08:54:44.502090 systemd[1]: Reached target getty.target - Login Prompts. Jul 1 08:54:44.537388 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:44.717391 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:44.818308 systemd-networkd[1441]: eth0: Gained IPv6LL Jul 1 08:54:44.828651 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 1 08:54:44.831429 systemd[1]: Reached target network-online.target - Network is Online. Jul 1 08:54:44.839924 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 1 08:54:44.846148 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 1 08:54:44.901746 containerd[1550]: time="2025-07-01T08:54:44.901673796Z" level=info msg="Start subscribing containerd event" Jul 1 08:54:44.901905 containerd[1550]: time="2025-07-01T08:54:44.901752603Z" level=info msg="Start recovering state" Jul 1 08:54:44.902002 containerd[1550]: time="2025-07-01T08:54:44.901976323Z" level=info msg="Start event monitor" Jul 1 08:54:44.902078 containerd[1550]: time="2025-07-01T08:54:44.902042557Z" level=info msg="Start cni network conf syncer for default" Jul 1 08:54:44.902964 containerd[1550]: time="2025-07-01T08:54:44.902796261Z" level=info msg="Start streaming server" Jul 1 08:54:44.902964 containerd[1550]: time="2025-07-01T08:54:44.902829293Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 1 08:54:44.902964 containerd[1550]: time="2025-07-01T08:54:44.902846335Z" level=info msg="runtime interface starting up..." Jul 1 08:54:44.902964 containerd[1550]: time="2025-07-01T08:54:44.902856634Z" level=info msg="starting plugins..." Jul 1 08:54:44.902964 containerd[1550]: time="2025-07-01T08:54:44.902884476Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 1 08:54:44.905669 containerd[1550]: time="2025-07-01T08:54:44.903607402Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 1 08:54:44.905669 containerd[1550]: time="2025-07-01T08:54:44.903811886Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 1 08:54:44.905669 containerd[1550]: time="2025-07-01T08:54:44.905541720Z" level=info msg="containerd successfully booted in 0.924135s" Jul 1 08:54:44.904249 systemd[1]: Started containerd.service - containerd container runtime. Jul 1 08:54:44.923674 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 1 08:54:45.638801 sshd[1619]: Accepted publickey for core from 172.24.4.1 port 56598 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:54:45.643646 sshd-session[1619]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:54:45.662722 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 1 08:54:45.665065 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 1 08:54:45.682647 systemd-logind[1537]: New session 1 of user core. Jul 1 08:54:45.715417 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 1 08:54:45.723055 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 1 08:54:45.742374 (systemd)[1651]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 1 08:54:45.746016 systemd-logind[1537]: New session c1 of user core. Jul 1 08:54:45.956056 systemd[1651]: Queued start job for default target default.target. Jul 1 08:54:45.964870 systemd[1651]: Created slice app.slice - User Application Slice. Jul 1 08:54:45.965089 systemd[1651]: Reached target paths.target - Paths. Jul 1 08:54:45.965137 systemd[1651]: Reached target timers.target - Timers. Jul 1 08:54:45.968443 systemd[1651]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 1 08:54:45.980763 systemd[1651]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 1 08:54:45.980875 systemd[1651]: Reached target sockets.target - Sockets. Jul 1 08:54:45.980928 systemd[1651]: Reached target basic.target - Basic System. Jul 1 08:54:45.980964 systemd[1651]: Reached target default.target - Main User Target. Jul 1 08:54:45.981004 systemd[1651]: Startup finished in 227ms. Jul 1 08:54:45.981124 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 1 08:54:45.991656 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 1 08:54:46.358729 systemd[1]: Started sshd@1-172.24.4.138:22-172.24.4.1:57456.service - OpenSSH per-connection server daemon (172.24.4.1:57456). Jul 1 08:54:46.556406 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:46.757418 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:47.275790 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:54:47.296049 (kubelet)[1672]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 1 08:54:48.244173 sshd[1662]: Accepted publickey for core from 172.24.4.1 port 57456 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:54:48.247469 sshd-session[1662]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:54:48.260811 systemd-logind[1537]: New session 2 of user core. Jul 1 08:54:48.268719 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 1 08:54:48.893069 kubelet[1672]: E0701 08:54:48.892961 1672 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 1 08:54:48.897585 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 1 08:54:48.898303 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 1 08:54:48.899256 systemd[1]: kubelet.service: Consumed 2.581s CPU time, 266.4M memory peak. Jul 1 08:54:48.937417 sshd[1678]: Connection closed by 172.24.4.1 port 57456 Jul 1 08:54:48.938386 sshd-session[1662]: pam_unix(sshd:session): session closed for user core Jul 1 08:54:48.955952 systemd[1]: sshd@1-172.24.4.138:22-172.24.4.1:57456.service: Deactivated successfully. Jul 1 08:54:48.959869 systemd[1]: session-2.scope: Deactivated successfully. Jul 1 08:54:48.962100 systemd-logind[1537]: Session 2 logged out. Waiting for processes to exit. Jul 1 08:54:48.969930 systemd[1]: Started sshd@2-172.24.4.138:22-172.24.4.1:57468.service - OpenSSH per-connection server daemon (172.24.4.1:57468). Jul 1 08:54:48.973184 systemd-logind[1537]: Removed session 2. Jul 1 08:54:49.565597 login[1629]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 1 08:54:49.581488 login[1630]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 1 08:54:49.593451 systemd-logind[1537]: New session 3 of user core. Jul 1 08:54:49.602954 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 1 08:54:49.612595 systemd-logind[1537]: New session 4 of user core. Jul 1 08:54:49.618906 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 1 08:54:50.398417 sshd[1685]: Accepted publickey for core from 172.24.4.1 port 57468 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:54:50.401093 sshd-session[1685]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:54:50.413578 systemd-logind[1537]: New session 5 of user core. Jul 1 08:54:50.421744 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 1 08:54:50.596439 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:50.613572 coreos-metadata[1523]: Jul 01 08:54:50.613 WARN failed to locate config-drive, using the metadata service API instead Jul 1 08:54:50.762744 coreos-metadata[1523]: Jul 01 08:54:50.762 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Jul 1 08:54:50.778417 kernel: /dev/disk/by-label/config-2: Can't lookup blockdev Jul 1 08:54:50.792779 coreos-metadata[1590]: Jul 01 08:54:50.792 WARN failed to locate config-drive, using the metadata service API instead Jul 1 08:54:50.837828 coreos-metadata[1590]: Jul 01 08:54:50.837 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Jul 1 08:54:50.972267 coreos-metadata[1523]: Jul 01 08:54:50.972 INFO Fetch successful Jul 1 08:54:50.972620 coreos-metadata[1523]: Jul 01 08:54:50.972 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Jul 1 08:54:50.985294 coreos-metadata[1523]: Jul 01 08:54:50.985 INFO Fetch successful Jul 1 08:54:50.985530 coreos-metadata[1523]: Jul 01 08:54:50.985 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Jul 1 08:54:50.992067 sshd[1717]: Connection closed by 172.24.4.1 port 57468 Jul 1 08:54:50.991953 sshd-session[1685]: pam_unix(sshd:session): session closed for user core Jul 1 08:54:50.996011 systemd[1]: sshd@2-172.24.4.138:22-172.24.4.1:57468.service: Deactivated successfully. Jul 1 08:54:50.997763 coreos-metadata[1523]: Jul 01 08:54:50.997 INFO Fetch successful Jul 1 08:54:50.997763 coreos-metadata[1523]: Jul 01 08:54:50.997 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Jul 1 08:54:50.999078 systemd[1]: session-5.scope: Deactivated successfully. Jul 1 08:54:51.001242 systemd-logind[1537]: Session 5 logged out. Waiting for processes to exit. Jul 1 08:54:51.003617 systemd-logind[1537]: Removed session 5. Jul 1 08:54:51.013735 coreos-metadata[1523]: Jul 01 08:54:51.013 INFO Fetch successful Jul 1 08:54:51.013735 coreos-metadata[1523]: Jul 01 08:54:51.013 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Jul 1 08:54:51.021435 coreos-metadata[1590]: Jul 01 08:54:51.021 INFO Fetch successful Jul 1 08:54:51.021551 coreos-metadata[1590]: Jul 01 08:54:51.021 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Jul 1 08:54:51.030046 coreos-metadata[1523]: Jul 01 08:54:51.029 INFO Fetch successful Jul 1 08:54:51.030046 coreos-metadata[1523]: Jul 01 08:54:51.029 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Jul 1 08:54:51.036831 coreos-metadata[1590]: Jul 01 08:54:51.036 INFO Fetch successful Jul 1 08:54:51.043650 coreos-metadata[1523]: Jul 01 08:54:51.043 INFO Fetch successful Jul 1 08:54:51.085348 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 1 08:54:51.087771 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 1 08:54:51.099119 unknown[1590]: wrote ssh authorized keys file for user: core Jul 1 08:54:51.170262 update-ssh-keys[1732]: Updated "/home/core/.ssh/authorized_keys" Jul 1 08:54:51.171168 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Jul 1 08:54:51.174993 systemd[1]: Finished sshkeys.service. Jul 1 08:54:51.176721 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 1 08:54:51.178446 systemd[1]: Startup finished in 4.746s (kernel) + 16.239s (initrd) + 12.165s (userspace) = 33.151s. Jul 1 08:54:59.030709 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Jul 1 08:54:59.035490 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 1 08:54:59.463797 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:54:59.476243 (kubelet)[1743]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 1 08:54:59.560734 kubelet[1743]: E0701 08:54:59.560687 1743 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 1 08:54:59.564753 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 1 08:54:59.565028 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 1 08:54:59.565909 systemd[1]: kubelet.service: Consumed 349ms CPU time, 108.6M memory peak. Jul 1 08:55:01.013880 systemd[1]: Started sshd@3-172.24.4.138:22-172.24.4.1:52478.service - OpenSSH per-connection server daemon (172.24.4.1:52478). Jul 1 08:55:02.276387 sshd[1751]: Accepted publickey for core from 172.24.4.1 port 52478 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:55:02.279229 sshd-session[1751]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:55:02.292472 systemd-logind[1537]: New session 6 of user core. Jul 1 08:55:02.298717 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 1 08:55:02.991532 sshd[1754]: Connection closed by 172.24.4.1 port 52478 Jul 1 08:55:02.994757 sshd-session[1751]: pam_unix(sshd:session): session closed for user core Jul 1 08:55:03.020060 systemd[1]: sshd@3-172.24.4.138:22-172.24.4.1:52478.service: Deactivated successfully. Jul 1 08:55:03.026488 systemd[1]: session-6.scope: Deactivated successfully. Jul 1 08:55:03.030836 systemd-logind[1537]: Session 6 logged out. Waiting for processes to exit. Jul 1 08:55:03.037326 systemd[1]: Started sshd@4-172.24.4.138:22-172.24.4.1:52490.service - OpenSSH per-connection server daemon (172.24.4.1:52490). Jul 1 08:55:03.039660 systemd-logind[1537]: Removed session 6. Jul 1 08:55:04.316993 sshd[1760]: Accepted publickey for core from 172.24.4.1 port 52490 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:55:04.320225 sshd-session[1760]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:55:04.333463 systemd-logind[1537]: New session 7 of user core. Jul 1 08:55:04.344775 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 1 08:55:05.036649 sshd[1763]: Connection closed by 172.24.4.1 port 52490 Jul 1 08:55:05.037699 sshd-session[1760]: pam_unix(sshd:session): session closed for user core Jul 1 08:55:05.053692 systemd[1]: sshd@4-172.24.4.138:22-172.24.4.1:52490.service: Deactivated successfully. Jul 1 08:55:05.057945 systemd[1]: session-7.scope: Deactivated successfully. Jul 1 08:55:05.062777 systemd-logind[1537]: Session 7 logged out. Waiting for processes to exit. Jul 1 08:55:05.070100 systemd[1]: Started sshd@5-172.24.4.138:22-172.24.4.1:41314.service - OpenSSH per-connection server daemon (172.24.4.1:41314). Jul 1 08:55:05.073034 systemd-logind[1537]: Removed session 7. Jul 1 08:55:06.547158 sshd[1769]: Accepted publickey for core from 172.24.4.1 port 41314 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:55:06.550648 sshd-session[1769]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:55:06.565500 systemd-logind[1537]: New session 8 of user core. Jul 1 08:55:06.575699 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 1 08:55:07.169407 sshd[1772]: Connection closed by 172.24.4.1 port 41314 Jul 1 08:55:07.170761 sshd-session[1769]: pam_unix(sshd:session): session closed for user core Jul 1 08:55:07.188623 systemd[1]: sshd@5-172.24.4.138:22-172.24.4.1:41314.service: Deactivated successfully. Jul 1 08:55:07.194490 systemd[1]: session-8.scope: Deactivated successfully. Jul 1 08:55:07.197285 systemd-logind[1537]: Session 8 logged out. Waiting for processes to exit. Jul 1 08:55:07.205247 systemd[1]: Started sshd@6-172.24.4.138:22-172.24.4.1:41320.service - OpenSSH per-connection server daemon (172.24.4.1:41320). Jul 1 08:55:07.208491 systemd-logind[1537]: Removed session 8. Jul 1 08:55:08.271871 sshd[1778]: Accepted publickey for core from 172.24.4.1 port 41320 ssh2: RSA SHA256:OtMLMno53upG6UEgyPzObkH0Sb4RVcHs4vv+qZKdqJo Jul 1 08:55:08.274474 sshd-session[1778]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:55:08.288475 systemd-logind[1537]: New session 9 of user core. Jul 1 08:55:08.295848 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 1 08:55:08.717084 sudo[1782]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 1 08:55:08.718757 sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 1 08:55:09.780066 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Jul 1 08:55:09.783305 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 1 08:55:10.474874 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:55:10.494777 (kubelet)[1815]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 1 08:55:10.599644 kubelet[1815]: E0701 08:55:10.599513 1815 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Jul 1 08:55:10.605424 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 1 08:55:10.605723 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 1 08:55:10.606661 systemd[1]: kubelet.service: Consumed 390ms CPU time, 110.2M memory peak. Jul 1 08:55:10.695105 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:55:10.698198 systemd[1]: kubelet.service: Consumed 390ms CPU time, 110.2M memory peak. Jul 1 08:55:10.708393 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 1 08:55:10.771287 systemd[1]: Reload requested from client PID 1831 ('systemctl') (unit session-9.scope)... Jul 1 08:55:10.771346 systemd[1]: Reloading... Jul 1 08:55:10.956584 zram_generator::config[1875]: No configuration found. Jul 1 08:55:11.245550 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. Jul 1 08:55:11.397427 systemd[1]: Reloading finished in 623 ms. Jul 1 08:55:11.475766 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jul 1 08:55:11.475850 systemd[1]: kubelet.service: Failed with result 'signal'. Jul 1 08:55:11.476156 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:55:11.476211 systemd[1]: kubelet.service: Consumed 184ms CPU time, 98.3M memory peak. Jul 1 08:55:11.478402 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 1 08:55:11.655221 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 1 08:55:11.668293 (kubelet)[1942]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 1 08:55:11.964414 kubelet[1942]: 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. Jul 1 08:55:11.964414 kubelet[1942]: 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. Jul 1 08:55:11.964414 kubelet[1942]: 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. Jul 1 08:55:11.964414 kubelet[1942]: I0701 08:55:11.964274 1942 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 1 08:55:12.540563 kubelet[1942]: I0701 08:55:12.540488 1942 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Jul 1 08:55:12.540849 kubelet[1942]: I0701 08:55:12.540828 1942 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 1 08:55:12.541577 kubelet[1942]: I0701 08:55:12.541560 1942 server.go:934] "Client rotation is on, will bootstrap in background" Jul 1 08:55:12.589123 kubelet[1942]: I0701 08:55:12.589080 1942 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 1 08:55:12.622205 kubelet[1942]: I0701 08:55:12.622001 1942 server.go:1431] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Jul 1 08:55:12.635612 kubelet[1942]: I0701 08:55:12.635561 1942 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 1 08:55:12.636012 kubelet[1942]: I0701 08:55:12.635992 1942 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jul 1 08:55:12.636372 kubelet[1942]: I0701 08:55:12.636290 1942 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 1 08:55:12.636758 kubelet[1942]: I0701 08:55:12.636453 1942 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.24.4.138","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"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,"CgroupVersion":2} Jul 1 08:55:12.637923 kubelet[1942]: I0701 08:55:12.637579 1942 topology_manager.go:138] "Creating topology manager with none policy" Jul 1 08:55:12.637923 kubelet[1942]: I0701 08:55:12.637598 1942 container_manager_linux.go:300] "Creating device plugin manager" Jul 1 08:55:12.637923 kubelet[1942]: I0701 08:55:12.637744 1942 state_mem.go:36] "Initialized new in-memory state store" Jul 1 08:55:12.642698 kubelet[1942]: I0701 08:55:12.642392 1942 kubelet.go:408] "Attempting to sync node with API server" Jul 1 08:55:12.642698 kubelet[1942]: I0701 08:55:12.642422 1942 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 1 08:55:12.642698 kubelet[1942]: I0701 08:55:12.642463 1942 kubelet.go:314] "Adding apiserver pod source" Jul 1 08:55:12.642698 kubelet[1942]: I0701 08:55:12.642500 1942 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 1 08:55:12.645617 kubelet[1942]: E0701 08:55:12.645309 1942 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:12.645617 kubelet[1942]: E0701 08:55:12.645558 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:12.646446 kubelet[1942]: I0701 08:55:12.646429 1942 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Jul 1 08:55:12.647218 kubelet[1942]: I0701 08:55:12.647201 1942 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 1 08:55:12.647438 kubelet[1942]: W0701 08:55:12.647424 1942 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 1 08:55:12.650431 kubelet[1942]: I0701 08:55:12.650417 1942 server.go:1274] "Started kubelet" Jul 1 08:55:12.652316 kubelet[1942]: I0701 08:55:12.651984 1942 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jul 1 08:55:12.655056 kubelet[1942]: I0701 08:55:12.654902 1942 server.go:449] "Adding debug handlers to kubelet server" Jul 1 08:55:12.663228 kubelet[1942]: I0701 08:55:12.663168 1942 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 1 08:55:12.664036 kubelet[1942]: I0701 08:55:12.663960 1942 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 1 08:55:12.677164 kubelet[1942]: E0701 08:55:12.674506 1942 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.24.4.138.184e14be4c507895 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.24.4.138,UID:172.24.4.138,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.24.4.138,},FirstTimestamp:2025-07-01 08:55:12.650385557 +0000 UTC m=+0.977049306,LastTimestamp:2025-07-01 08:55:12.650385557 +0000 UTC m=+0.977049306,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.24.4.138,}" Jul 1 08:55:12.687033 kubelet[1942]: I0701 08:55:12.687000 1942 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 1 08:55:12.687905 kubelet[1942]: I0701 08:55:12.687837 1942 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 1 08:55:12.699585 kubelet[1942]: I0701 08:55:12.699516 1942 volume_manager.go:289] "Starting Kubelet Volume Manager" Jul 1 08:55:12.701386 kubelet[1942]: E0701 08:55:12.700013 1942 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.24.4.138\" not found" Jul 1 08:55:12.701690 kubelet[1942]: I0701 08:55:12.701662 1942 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Jul 1 08:55:12.701917 kubelet[1942]: I0701 08:55:12.701881 1942 reconciler.go:26] "Reconciler: start to sync state" Jul 1 08:55:12.718196 kubelet[1942]: I0701 08:55:12.718105 1942 factory.go:221] Registration of the systemd container factory successfully Jul 1 08:55:12.720595 kubelet[1942]: I0701 08:55:12.718409 1942 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jul 1 08:55:12.725593 kubelet[1942]: E0701 08:55:12.724897 1942 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.24.4.138\" not found" node="172.24.4.138" Jul 1 08:55:12.754753 kubelet[1942]: I0701 08:55:12.754699 1942 factory.go:221] Registration of the containerd container factory successfully Jul 1 08:55:12.756665 kubelet[1942]: E0701 08:55:12.756602 1942 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 1 08:55:12.802208 kubelet[1942]: E0701 08:55:12.800669 1942 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.24.4.138\" not found" Jul 1 08:55:12.811245 kubelet[1942]: I0701 08:55:12.811208 1942 cpu_manager.go:214] "Starting CPU manager" policy="none" Jul 1 08:55:12.811245 kubelet[1942]: I0701 08:55:12.811233 1942 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jul 1 08:55:12.811245 kubelet[1942]: I0701 08:55:12.811260 1942 state_mem.go:36] "Initialized new in-memory state store" Jul 1 08:55:12.818653 kubelet[1942]: I0701 08:55:12.818595 1942 policy_none.go:49] "None policy: Start" Jul 1 08:55:12.820037 kubelet[1942]: I0701 08:55:12.819989 1942 memory_manager.go:170] "Starting memorymanager" policy="None" Jul 1 08:55:12.820037 kubelet[1942]: I0701 08:55:12.820023 1942 state_mem.go:35] "Initializing new in-memory state store" Jul 1 08:55:12.838598 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 1 08:55:12.852915 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 1 08:55:12.859479 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 1 08:55:12.870529 kubelet[1942]: I0701 08:55:12.870484 1942 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 1 08:55:12.872164 kubelet[1942]: I0701 08:55:12.871767 1942 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 1 08:55:12.872164 kubelet[1942]: I0701 08:55:12.871805 1942 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 1 08:55:12.872794 kubelet[1942]: I0701 08:55:12.872777 1942 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 1 08:55:12.877724 kubelet[1942]: E0701 08:55:12.877690 1942 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.24.4.138\" not found" Jul 1 08:55:12.878834 kubelet[1942]: I0701 08:55:12.878788 1942 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 1 08:55:12.881970 kubelet[1942]: I0701 08:55:12.881928 1942 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 1 08:55:12.882401 kubelet[1942]: I0701 08:55:12.882124 1942 status_manager.go:217] "Starting to sync pod status with apiserver" Jul 1 08:55:12.882401 kubelet[1942]: I0701 08:55:12.882178 1942 kubelet.go:2321] "Starting kubelet main sync loop" Jul 1 08:55:12.882401 kubelet[1942]: E0701 08:55:12.882231 1942 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jul 1 08:55:12.975200 kubelet[1942]: I0701 08:55:12.975125 1942 kubelet_node_status.go:72] "Attempting to register node" node="172.24.4.138" Jul 1 08:55:12.998656 kubelet[1942]: I0701 08:55:12.998566 1942 kubelet_node_status.go:75] "Successfully registered node" node="172.24.4.138" Jul 1 08:55:13.124727 kubelet[1942]: I0701 08:55:13.123819 1942 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 1 08:55:13.127239 containerd[1550]: time="2025-07-01T08:55:13.125599090Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 1 08:55:13.134521 kubelet[1942]: I0701 08:55:13.127954 1942 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 1 08:55:13.246198 sudo[1782]: pam_unix(sudo:session): session closed for user root Jul 1 08:55:13.430748 sshd[1781]: Connection closed by 172.24.4.1 port 41320 Jul 1 08:55:13.436313 sshd-session[1778]: pam_unix(sshd:session): session closed for user core Jul 1 08:55:13.458095 systemd[1]: sshd@6-172.24.4.138:22-172.24.4.1:41320.service: Deactivated successfully. Jul 1 08:55:13.474020 systemd[1]: session-9.scope: Deactivated successfully. Jul 1 08:55:13.475548 systemd[1]: session-9.scope: Consumed 1.155s CPU time, 72.8M memory peak. Jul 1 08:55:13.486628 systemd-logind[1537]: Session 9 logged out. Waiting for processes to exit. Jul 1 08:55:13.492087 systemd-logind[1537]: Removed session 9. Jul 1 08:55:13.550817 kubelet[1942]: I0701 08:55:13.550554 1942 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 1 08:55:13.553227 kubelet[1942]: W0701 08:55:13.552810 1942 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 1 08:55:13.553573 kubelet[1942]: W0701 08:55:13.552829 1942 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 1 08:55:13.553713 kubelet[1942]: W0701 08:55:13.553587 1942 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 1 08:55:13.646724 kubelet[1942]: I0701 08:55:13.646485 1942 apiserver.go:52] "Watching apiserver" Jul 1 08:55:13.646724 kubelet[1942]: E0701 08:55:13.646561 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:13.786604 kubelet[1942]: I0701 08:55:13.786447 1942 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Jul 1 08:55:13.796906 kubelet[1942]: I0701 08:55:13.796830 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0fdd3052-569a-4f95-98ca-e4c75a442c8b-lib-modules\") pod \"kube-proxy-rx4m6\" (UID: \"0fdd3052-569a-4f95-98ca-e4c75a442c8b\") " pod="kube-system/kube-proxy-rx4m6" Jul 1 08:55:13.797092 kubelet[1942]: I0701 08:55:13.796908 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/aec2101d-8fb4-4b66-a339-d33f43d39861-cni\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.797092 kubelet[1942]: I0701 08:55:13.796945 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/aec2101d-8fb4-4b66-a339-d33f43d39861-xtables-lock\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.797092 kubelet[1942]: I0701 08:55:13.796981 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0fdd3052-569a-4f95-98ca-e4c75a442c8b-kube-proxy\") pod \"kube-proxy-rx4m6\" (UID: \"0fdd3052-569a-4f95-98ca-e4c75a442c8b\") " pod="kube-system/kube-proxy-rx4m6" Jul 1 08:55:13.797092 kubelet[1942]: I0701 08:55:13.797044 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0fdd3052-569a-4f95-98ca-e4c75a442c8b-xtables-lock\") pod \"kube-proxy-rx4m6\" (UID: \"0fdd3052-569a-4f95-98ca-e4c75a442c8b\") " pod="kube-system/kube-proxy-rx4m6" Jul 1 08:55:13.797298 kubelet[1942]: I0701 08:55:13.797114 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gqp8x\" (UniqueName: \"kubernetes.io/projected/0fdd3052-569a-4f95-98ca-e4c75a442c8b-kube-api-access-gqp8x\") pod \"kube-proxy-rx4m6\" (UID: \"0fdd3052-569a-4f95-98ca-e4c75a442c8b\") " pod="kube-system/kube-proxy-rx4m6" Jul 1 08:55:13.797298 kubelet[1942]: I0701 08:55:13.797175 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/aec2101d-8fb4-4b66-a339-d33f43d39861-run\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.797298 kubelet[1942]: I0701 08:55:13.797210 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/aec2101d-8fb4-4b66-a339-d33f43d39861-cni-plugin\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.797298 kubelet[1942]: I0701 08:55:13.797243 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/aec2101d-8fb4-4b66-a339-d33f43d39861-flannel-cfg\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.797298 kubelet[1942]: I0701 08:55:13.797262 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7glnk\" (UniqueName: \"kubernetes.io/projected/aec2101d-8fb4-4b66-a339-d33f43d39861-kube-api-access-7glnk\") pod \"kube-flannel-ds-9rbqm\" (UID: \"aec2101d-8fb4-4b66-a339-d33f43d39861\") " pod="kube-flannel/kube-flannel-ds-9rbqm" Jul 1 08:55:13.808246 systemd[1]: Created slice kubepods-burstable-podaec2101d_8fb4_4b66_a339_d33f43d39861.slice - libcontainer container kubepods-burstable-podaec2101d_8fb4_4b66_a339_d33f43d39861.slice. Jul 1 08:55:13.833401 systemd[1]: Created slice kubepods-besteffort-pod0fdd3052_569a_4f95_98ca_e4c75a442c8b.slice - libcontainer container kubepods-besteffort-pod0fdd3052_569a_4f95_98ca_e4c75a442c8b.slice. Jul 1 08:55:14.134008 containerd[1550]: time="2025-07-01T08:55:14.133773040Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9rbqm,Uid:aec2101d-8fb4-4b66-a339-d33f43d39861,Namespace:kube-flannel,Attempt:0,}" Jul 1 08:55:14.150084 containerd[1550]: time="2025-07-01T08:55:14.149960329Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rx4m6,Uid:0fdd3052-569a-4f95-98ca-e4c75a442c8b,Namespace:kube-system,Attempt:0,}" Jul 1 08:55:14.648730 kubelet[1942]: E0701 08:55:14.648071 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:15.130898 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3422712817.mount: Deactivated successfully. Jul 1 08:55:15.255457 containerd[1550]: time="2025-07-01T08:55:15.254701171Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 1 08:55:15.259156 containerd[1550]: time="2025-07-01T08:55:15.258789538Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 1 08:55:15.262154 containerd[1550]: time="2025-07-01T08:55:15.262060964Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321146" Jul 1 08:55:15.264345 containerd[1550]: time="2025-07-01T08:55:15.263828679Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 1 08:55:15.266123 containerd[1550]: time="2025-07-01T08:55:15.266056237Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Jul 1 08:55:15.270962 containerd[1550]: time="2025-07-01T08:55:15.270265492Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 1 08:55:15.276439 containerd[1550]: time="2025-07-01T08:55:15.275984317Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 1.112174964s" Jul 1 08:55:15.287783 containerd[1550]: time="2025-07-01T08:55:15.287639817Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 1.121417017s" Jul 1 08:55:15.407339 containerd[1550]: time="2025-07-01T08:55:15.407031927Z" level=info msg="connecting to shim 21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c" address="unix:///run/containerd/s/6dd17447abd0d4739d335c72683d5db84c56baef4e33b6c6919e6e23c190b066" namespace=k8s.io protocol=ttrpc version=3 Jul 1 08:55:15.408428 containerd[1550]: time="2025-07-01T08:55:15.407917979Z" level=info msg="connecting to shim b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60" address="unix:///run/containerd/s/fd81d010eaf2ad3d576ef5914f81c7818b27fadd7a5e5360e9569dadd2b1a11d" namespace=k8s.io protocol=ttrpc version=3 Jul 1 08:55:15.507995 systemd[1]: Started cri-containerd-21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c.scope - libcontainer container 21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c. Jul 1 08:55:15.513838 systemd[1]: Started cri-containerd-b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60.scope - libcontainer container b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60. Jul 1 08:55:15.595734 containerd[1550]: time="2025-07-01T08:55:15.595621096Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rx4m6,Uid:0fdd3052-569a-4f95-98ca-e4c75a442c8b,Namespace:kube-system,Attempt:0,} returns sandbox id \"b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60\"" Jul 1 08:55:15.605827 containerd[1550]: time="2025-07-01T08:55:15.605760833Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\"" Jul 1 08:55:15.623080 containerd[1550]: time="2025-07-01T08:55:15.623031023Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9rbqm,Uid:aec2101d-8fb4-4b66-a339-d33f43d39861,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\"" Jul 1 08:55:15.648462 kubelet[1942]: E0701 08:55:15.648406 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:16.699381 kubelet[1942]: E0701 08:55:16.699240 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:17.321424 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1611733443.mount: Deactivated successfully. Jul 1 08:55:17.751098 kubelet[1942]: E0701 08:55:17.699672 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:18.468629 containerd[1550]: time="2025-07-01T08:55:18.468463272Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:18.469861 containerd[1550]: time="2025-07-01T08:55:18.469764194Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.10: active requests=0, bytes read=30383951" Jul 1 08:55:18.471377 containerd[1550]: time="2025-07-01T08:55:18.470916342Z" level=info msg="ImageCreate event name:\"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:18.475668 containerd[1550]: time="2025-07-01T08:55:18.475628543Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:18.476574 containerd[1550]: time="2025-07-01T08:55:18.476512874Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.10\" with image id \"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\", repo tag \"registry.k8s.io/kube-proxy:v1.31.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:bcbb293812bdf587b28ea98369a8c347ca84884160046296761acdf12b27029d\", size \"30382962\" in 2.870413225s" Jul 1 08:55:18.476658 containerd[1550]: time="2025-07-01T08:55:18.476594335Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.10\" returns image reference \"sha256:3ed600862d3e69931e0f9f4dbf5c2b46343af40aa079772434f13de771bdc30c\"" Jul 1 08:55:18.481900 containerd[1550]: time="2025-07-01T08:55:18.481862467Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 1 08:55:18.496146 containerd[1550]: time="2025-07-01T08:55:18.494887238Z" level=info msg="CreateContainer within sandbox \"b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 1 08:55:18.522218 containerd[1550]: time="2025-07-01T08:55:18.522150952Z" level=info msg="Container d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:18.528284 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2984555801.mount: Deactivated successfully. Jul 1 08:55:18.544523 containerd[1550]: time="2025-07-01T08:55:18.544421374Z" level=info msg="CreateContainer within sandbox \"b6184aca69e4462f27af7837e584eb518f1f7ac1bc9e0361abbf00c0f587cb60\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70\"" Jul 1 08:55:18.546778 containerd[1550]: time="2025-07-01T08:55:18.546732731Z" level=info msg="StartContainer for \"d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70\"" Jul 1 08:55:18.549846 containerd[1550]: time="2025-07-01T08:55:18.549768773Z" level=info msg="connecting to shim d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70" address="unix:///run/containerd/s/fd81d010eaf2ad3d576ef5914f81c7818b27fadd7a5e5360e9569dadd2b1a11d" protocol=ttrpc version=3 Jul 1 08:55:18.629675 systemd[1]: Started cri-containerd-d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70.scope - libcontainer container d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70. Jul 1 08:55:18.700639 kubelet[1942]: E0701 08:55:18.700554 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:18.705850 containerd[1550]: time="2025-07-01T08:55:18.705739720Z" level=info msg="StartContainer for \"d6b2a0026538f6cc50d454aa9de8ada579555cf69f956ecbe58aad31e353ee70\" returns successfully" Jul 1 08:55:18.963340 kubelet[1942]: I0701 08:55:18.963072 1942 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-rx4m6" podStartSLOduration=3.085106323 podStartE2EDuration="5.963019845s" podCreationTimestamp="2025-07-01 08:55:13 +0000 UTC" firstStartedPulling="2025-07-01 08:55:15.603515131 +0000 UTC m=+3.930178880" lastFinishedPulling="2025-07-01 08:55:18.481428633 +0000 UTC m=+6.808092402" observedRunningTime="2025-07-01 08:55:18.959600152 +0000 UTC m=+7.286263921" watchObservedRunningTime="2025-07-01 08:55:18.963019845 +0000 UTC m=+7.289683584" Jul 1 08:55:19.701840 kubelet[1942]: E0701 08:55:19.701758 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:20.702392 kubelet[1942]: E0701 08:55:20.702247 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:21.048630 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1215946914.mount: Deactivated successfully. Jul 1 08:55:21.253446 containerd[1550]: time="2025-07-01T08:55:21.253270820Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:21.256401 containerd[1550]: time="2025-07-01T08:55:21.256116138Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Jul 1 08:55:21.258857 containerd[1550]: time="2025-07-01T08:55:21.258775130Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:21.265411 containerd[1550]: time="2025-07-01T08:55:21.264245067Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:21.266906 containerd[1550]: time="2025-07-01T08:55:21.266813501Z" 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.784749199s" Jul 1 08:55:21.267277 containerd[1550]: time="2025-07-01T08:55:21.267218984Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Jul 1 08:55:21.274292 containerd[1550]: time="2025-07-01T08:55:21.274213585Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 1 08:55:21.297412 containerd[1550]: time="2025-07-01T08:55:21.294672861Z" level=info msg="Container b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:21.321808 containerd[1550]: time="2025-07-01T08:55:21.321543432Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\"" Jul 1 08:55:21.324405 containerd[1550]: time="2025-07-01T08:55:21.323773206Z" level=info msg="StartContainer for \"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\"" Jul 1 08:55:21.327290 containerd[1550]: time="2025-07-01T08:55:21.327220364Z" level=info msg="connecting to shim b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e" address="unix:///run/containerd/s/6dd17447abd0d4739d335c72683d5db84c56baef4e33b6c6919e6e23c190b066" protocol=ttrpc version=3 Jul 1 08:55:21.372632 systemd[1]: Started cri-containerd-b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e.scope - libcontainer container b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e. Jul 1 08:55:21.424996 systemd[1]: cri-containerd-b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e.scope: Deactivated successfully. Jul 1 08:55:21.428584 containerd[1550]: time="2025-07-01T08:55:21.428532524Z" level=info msg="StartContainer for \"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\" returns successfully" Jul 1 08:55:21.429323 containerd[1550]: time="2025-07-01T08:55:21.429268090Z" level=info msg="TaskExit event in podsandbox handler container_id:\"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\" id:\"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\" pid:2274 exited_at:{seconds:1751360121 nanos:428258945}" Jul 1 08:55:21.430382 containerd[1550]: time="2025-07-01T08:55:21.429824375Z" level=info msg="received exit event container_id:\"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\" id:\"b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e\" pid:2274 exited_at:{seconds:1751360121 nanos:428258945}" Jul 1 08:55:21.703733 kubelet[1942]: E0701 08:55:21.703527 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:21.899484 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-b4dde66cd993d2d0439c0247c097c31fc44841b9257e215ab19cabdc9945b66e-rootfs.mount: Deactivated successfully. Jul 1 08:55:21.950465 containerd[1550]: time="2025-07-01T08:55:21.950126374Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 1 08:55:22.705440 kubelet[1942]: E0701 08:55:22.705281 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:23.706100 kubelet[1942]: E0701 08:55:23.705990 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:24.333327 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2968864610.mount: Deactivated successfully. Jul 1 08:55:24.707397 kubelet[1942]: E0701 08:55:24.707114 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:25.231393 containerd[1550]: time="2025-07-01T08:55:25.230688022Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:25.232579 containerd[1550]: time="2025-07-01T08:55:25.232523971Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Jul 1 08:55:25.234001 containerd[1550]: time="2025-07-01T08:55:25.233866471Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:25.238115 containerd[1550]: time="2025-07-01T08:55:25.237991624Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:25.239804 containerd[1550]: time="2025-07-01T08:55:25.239074381Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.288857788s" Jul 1 08:55:25.239804 containerd[1550]: time="2025-07-01T08:55:25.239123271Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Jul 1 08:55:25.243439 containerd[1550]: time="2025-07-01T08:55:25.243391812Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 1 08:55:25.256790 containerd[1550]: time="2025-07-01T08:55:25.256100051Z" level=info msg="Container 0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:25.260483 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount333631435.mount: Deactivated successfully. Jul 1 08:55:25.272956 containerd[1550]: time="2025-07-01T08:55:25.272896804Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\"" Jul 1 08:55:25.273829 containerd[1550]: time="2025-07-01T08:55:25.273715608Z" level=info msg="StartContainer for \"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\"" Jul 1 08:55:25.275457 containerd[1550]: time="2025-07-01T08:55:25.275407880Z" level=info msg="connecting to shim 0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f" address="unix:///run/containerd/s/6dd17447abd0d4739d335c72683d5db84c56baef4e33b6c6919e6e23c190b066" protocol=ttrpc version=3 Jul 1 08:55:25.307594 systemd[1]: Started cri-containerd-0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f.scope - libcontainer container 0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f. Jul 1 08:55:25.348190 systemd[1]: cri-containerd-0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f.scope: Deactivated successfully. Jul 1 08:55:25.351341 containerd[1550]: time="2025-07-01T08:55:25.351264380Z" level=info msg="TaskExit event in podsandbox handler container_id:\"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\" id:\"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\" pid:2347 exited_at:{seconds:1751360125 nanos:350860377}" Jul 1 08:55:25.352258 containerd[1550]: time="2025-07-01T08:55:25.352214008Z" level=info msg="received exit event container_id:\"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\" id:\"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\" pid:2347 exited_at:{seconds:1751360125 nanos:350860377}" Jul 1 08:55:25.354067 containerd[1550]: time="2025-07-01T08:55:25.354012979Z" level=info msg="StartContainer for \"0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f\" returns successfully" Jul 1 08:55:25.372724 kubelet[1942]: I0701 08:55:25.372669 1942 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jul 1 08:55:25.386278 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-0c5f39c6d992fbc03762b6300c8429707896272bed815dc8dba92e1198882b0f-rootfs.mount: Deactivated successfully. Jul 1 08:55:25.708345 kubelet[1942]: E0701 08:55:25.708235 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:25.979562 containerd[1550]: time="2025-07-01T08:55:25.978903689Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 1 08:55:26.001418 containerd[1550]: time="2025-07-01T08:55:26.000852768Z" level=info msg="Container 1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:26.022918 containerd[1550]: time="2025-07-01T08:55:26.022805672Z" level=info msg="CreateContainer within sandbox \"21f4a56e0cf2c06ab6edcca0757b3edba90064b2ef50122ed4d4b95a7c89dc4c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490\"" Jul 1 08:55:26.024932 containerd[1550]: time="2025-07-01T08:55:26.024684742Z" level=info msg="StartContainer for \"1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490\"" Jul 1 08:55:26.028978 containerd[1550]: time="2025-07-01T08:55:26.028797065Z" level=info msg="connecting to shim 1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490" address="unix:///run/containerd/s/6dd17447abd0d4739d335c72683d5db84c56baef4e33b6c6919e6e23c190b066" protocol=ttrpc version=3 Jul 1 08:55:26.075727 systemd[1]: Started cri-containerd-1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490.scope - libcontainer container 1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490. Jul 1 08:55:26.137300 containerd[1550]: time="2025-07-01T08:55:26.137254671Z" level=info msg="StartContainer for \"1156525610fe635baa409f38eb228771437ae4b96220f632b82f389d2cc26490\" returns successfully" Jul 1 08:55:26.709499 kubelet[1942]: E0701 08:55:26.709393 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:27.243114 systemd-networkd[1441]: flannel.1: Link UP Jul 1 08:55:27.243133 systemd-networkd[1441]: flannel.1: Gained carrier Jul 1 08:55:27.710722 kubelet[1942]: E0701 08:55:27.710335 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:28.595027 systemd-networkd[1441]: flannel.1: Gained IPv6LL Jul 1 08:55:28.710962 kubelet[1942]: E0701 08:55:28.710839 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:29.030737 update_engine[1538]: I20250701 08:55:29.030073 1538 update_attempter.cc:509] Updating boot flags... Jul 1 08:55:29.712140 kubelet[1942]: E0701 08:55:29.712027 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:30.712466 kubelet[1942]: E0701 08:55:30.712276 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:31.713591 kubelet[1942]: E0701 08:55:31.713498 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:32.441433 kubelet[1942]: I0701 08:55:32.440661 1942 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-9rbqm" podStartSLOduration=9.825525818 podStartE2EDuration="19.44061692s" podCreationTimestamp="2025-07-01 08:55:13 +0000 UTC" firstStartedPulling="2025-07-01 08:55:15.626316575 +0000 UTC m=+3.952980314" lastFinishedPulling="2025-07-01 08:55:25.241407667 +0000 UTC m=+13.568071416" observedRunningTime="2025-07-01 08:55:27.016648107 +0000 UTC m=+15.343311936" watchObservedRunningTime="2025-07-01 08:55:32.44061692 +0000 UTC m=+20.767280699" Jul 1 08:55:32.464515 systemd[1]: Created slice kubepods-besteffort-pod3e3c05d7_14bc_4156_a460_624fa7c2a664.slice - libcontainer container kubepods-besteffort-pod3e3c05d7_14bc_4156_a460_624fa7c2a664.slice. Jul 1 08:55:32.484981 kubelet[1942]: I0701 08:55:32.484923 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qdr9s\" (UniqueName: \"kubernetes.io/projected/3e3c05d7-14bc-4156-a460-624fa7c2a664-kube-api-access-qdr9s\") pod \"nginx-deployment-8587fbcb89-ql9vz\" (UID: \"3e3c05d7-14bc-4156-a460-624fa7c2a664\") " pod="default/nginx-deployment-8587fbcb89-ql9vz" Jul 1 08:55:32.643535 kubelet[1942]: E0701 08:55:32.643473 1942 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:32.714783 kubelet[1942]: E0701 08:55:32.714498 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:32.776126 containerd[1550]: time="2025-07-01T08:55:32.775937779Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-ql9vz,Uid:3e3c05d7-14bc-4156-a460-624fa7c2a664,Namespace:default,Attempt:0,}" Jul 1 08:55:32.826653 systemd-networkd[1441]: cni0: Link UP Jul 1 08:55:32.827643 systemd-networkd[1441]: cni0: Gained carrier Jul 1 08:55:32.843802 systemd-networkd[1441]: cni0: Lost carrier Jul 1 08:55:32.858016 systemd-networkd[1441]: veth56eb7096: Link UP Jul 1 08:55:32.865291 kernel: cni0: port 1(veth56eb7096) entered blocking state Jul 1 08:55:32.865679 kernel: cni0: port 1(veth56eb7096) entered disabled state Jul 1 08:55:32.873056 kernel: veth56eb7096: entered allmulticast mode Jul 1 08:55:32.873325 kernel: veth56eb7096: entered promiscuous mode Jul 1 08:55:32.893684 kernel: cni0: port 1(veth56eb7096) entered blocking state Jul 1 08:55:32.893777 kernel: cni0: port 1(veth56eb7096) entered forwarding state Jul 1 08:55:32.893962 systemd-networkd[1441]: veth56eb7096: Gained carrier Jul 1 08:55:32.894254 systemd-networkd[1441]: cni0: Gained carrier Jul 1 08:55:32.897829 containerd[1550]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001c938), "name":"cbr0", "type":"bridge"} Jul 1 08:55:32.897829 containerd[1550]: delegateAdd: netconf sent to delegate plugin: Jul 1 08:55:32.947465 containerd[1550]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-01T08:55:32.947393291Z" level=info msg="connecting to shim 3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6" address="unix:///run/containerd/s/2876dfca072f356bdb7d12c4d72be0a51eb2a4d1a1260e0f9e41ce5c5a607017" namespace=k8s.io protocol=ttrpc version=3 Jul 1 08:55:32.977530 systemd[1]: Started cri-containerd-3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6.scope - libcontainer container 3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6. Jul 1 08:55:33.036944 containerd[1550]: time="2025-07-01T08:55:33.036899064Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-ql9vz,Uid:3e3c05d7-14bc-4156-a460-624fa7c2a664,Namespace:default,Attempt:0,} returns sandbox id \"3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6\"" Jul 1 08:55:33.039627 containerd[1550]: time="2025-07-01T08:55:33.039586052Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 1 08:55:33.716248 kubelet[1942]: E0701 08:55:33.716131 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:34.610135 systemd-networkd[1441]: cni0: Gained IPv6LL Jul 1 08:55:34.717861 kubelet[1942]: E0701 08:55:34.717561 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:34.801713 systemd-networkd[1441]: veth56eb7096: Gained IPv6LL Jul 1 08:55:35.718385 kubelet[1942]: E0701 08:55:35.718250 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:36.718661 kubelet[1942]: E0701 08:55:36.718618 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:37.241553 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3462878605.mount: Deactivated successfully. Jul 1 08:55:37.720531 kubelet[1942]: E0701 08:55:37.720340 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:38.721510 kubelet[1942]: E0701 08:55:38.720969 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:39.391979 containerd[1550]: time="2025-07-01T08:55:39.391853311Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:39.395330 containerd[1550]: time="2025-07-01T08:55:39.395247037Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73313230" Jul 1 08:55:39.397928 containerd[1550]: time="2025-07-01T08:55:39.397858932Z" level=info msg="ImageCreate event name:\"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:39.405614 containerd[1550]: time="2025-07-01T08:55:39.405501887Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:39.407509 containerd[1550]: time="2025-07-01T08:55:39.407452594Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 6.367797584s" Jul 1 08:55:39.407702 containerd[1550]: time="2025-07-01T08:55:39.407672275Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 1 08:55:39.420827 containerd[1550]: time="2025-07-01T08:55:39.420784839Z" level=info msg="CreateContainer within sandbox \"3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 1 08:55:39.442439 containerd[1550]: time="2025-07-01T08:55:39.441640854Z" level=info msg="Container 17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:39.456736 containerd[1550]: time="2025-07-01T08:55:39.456681674Z" level=info msg="CreateContainer within sandbox \"3306ccf59cf8872481ed9e0e70885eea5441416d36720e870616dfe043cca2b6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f\"" Jul 1 08:55:39.459010 containerd[1550]: time="2025-07-01T08:55:39.458723572Z" level=info msg="StartContainer for \"17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f\"" Jul 1 08:55:39.460476 containerd[1550]: time="2025-07-01T08:55:39.460368528Z" level=info msg="connecting to shim 17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f" address="unix:///run/containerd/s/2876dfca072f356bdb7d12c4d72be0a51eb2a4d1a1260e0f9e41ce5c5a607017" protocol=ttrpc version=3 Jul 1 08:55:39.520774 systemd[1]: Started cri-containerd-17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f.scope - libcontainer container 17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f. Jul 1 08:55:39.581727 containerd[1550]: time="2025-07-01T08:55:39.581340441Z" level=info msg="StartContainer for \"17df07cba8d54be49362645cd63112e55078d1e88ece6a57c2c69625350bd07f\" returns successfully" Jul 1 08:55:39.722091 kubelet[1942]: E0701 08:55:39.721925 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:40.082675 kubelet[1942]: I0701 08:55:40.082401 1942 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-ql9vz" podStartSLOduration=1.7086582369999999 podStartE2EDuration="8.08224745s" podCreationTimestamp="2025-07-01 08:55:32 +0000 UTC" firstStartedPulling="2025-07-01 08:55:33.039042357 +0000 UTC m=+21.365706096" lastFinishedPulling="2025-07-01 08:55:39.41263152 +0000 UTC m=+27.739295309" observedRunningTime="2025-07-01 08:55:40.080851159 +0000 UTC m=+28.407514948" watchObservedRunningTime="2025-07-01 08:55:40.08224745 +0000 UTC m=+28.408911290" Jul 1 08:55:40.722775 kubelet[1942]: E0701 08:55:40.722646 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:41.724168 kubelet[1942]: E0701 08:55:41.724000 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:42.725250 kubelet[1942]: E0701 08:55:42.725121 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:43.726497 kubelet[1942]: E0701 08:55:43.726321 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:44.727088 kubelet[1942]: E0701 08:55:44.726949 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:45.727901 kubelet[1942]: E0701 08:55:45.727784 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:46.729079 kubelet[1942]: E0701 08:55:46.728942 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:47.730143 kubelet[1942]: E0701 08:55:47.729968 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:48.731344 kubelet[1942]: E0701 08:55:48.731235 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:49.054148 systemd[1]: Created slice kubepods-besteffort-pod442bef44_4ac1_4afd_bced_1499b342f5ac.slice - libcontainer container kubepods-besteffort-pod442bef44_4ac1_4afd_bced_1499b342f5ac.slice. Jul 1 08:55:49.076961 kubelet[1942]: I0701 08:55:49.076555 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/442bef44-4ac1-4afd-bced-1499b342f5ac-data\") pod \"nfs-server-provisioner-0\" (UID: \"442bef44-4ac1-4afd-bced-1499b342f5ac\") " pod="default/nfs-server-provisioner-0" Jul 1 08:55:49.076961 kubelet[1942]: I0701 08:55:49.076708 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fpklc\" (UniqueName: \"kubernetes.io/projected/442bef44-4ac1-4afd-bced-1499b342f5ac-kube-api-access-fpklc\") pod \"nfs-server-provisioner-0\" (UID: \"442bef44-4ac1-4afd-bced-1499b342f5ac\") " pod="default/nfs-server-provisioner-0" Jul 1 08:55:49.364981 containerd[1550]: time="2025-07-01T08:55:49.364403495Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:442bef44-4ac1-4afd-bced-1499b342f5ac,Namespace:default,Attempt:0,}" Jul 1 08:55:49.411456 systemd-networkd[1441]: veth7ff4a6f9: Link UP Jul 1 08:55:49.417316 kernel: cni0: port 2(veth7ff4a6f9) entered blocking state Jul 1 08:55:49.417561 kernel: cni0: port 2(veth7ff4a6f9) entered disabled state Jul 1 08:55:49.423459 kernel: veth7ff4a6f9: entered allmulticast mode Jul 1 08:55:49.423630 kernel: veth7ff4a6f9: entered promiscuous mode Jul 1 08:55:49.454643 kernel: cni0: port 2(veth7ff4a6f9) entered blocking state Jul 1 08:55:49.454838 kernel: cni0: port 2(veth7ff4a6f9) entered forwarding state Jul 1 08:55:49.455235 systemd-networkd[1441]: veth7ff4a6f9: Gained carrier Jul 1 08:55:49.460322 containerd[1550]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Jul 1 08:55:49.460322 containerd[1550]: delegateAdd: netconf sent to delegate plugin: Jul 1 08:55:49.512066 containerd[1550]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-01T08:55:49.511870189Z" level=info msg="connecting to shim 2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138" address="unix:///run/containerd/s/f0114e3f37131fdd6a36d9eb234fe09b08cf4b717a330a14575099f30602665d" namespace=k8s.io protocol=ttrpc version=3 Jul 1 08:55:49.563081 systemd[1]: Started cri-containerd-2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138.scope - libcontainer container 2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138. Jul 1 08:55:49.640842 containerd[1550]: time="2025-07-01T08:55:49.640268494Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:442bef44-4ac1-4afd-bced-1499b342f5ac,Namespace:default,Attempt:0,} returns sandbox id \"2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138\"" Jul 1 08:55:49.642986 containerd[1550]: time="2025-07-01T08:55:49.642940859Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 1 08:55:49.732214 kubelet[1942]: E0701 08:55:49.732111 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:50.733719 kubelet[1942]: E0701 08:55:50.733480 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:51.508529 systemd-networkd[1441]: veth7ff4a6f9: Gained IPv6LL Jul 1 08:55:51.739028 kubelet[1942]: E0701 08:55:51.736776 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:52.642848 kubelet[1942]: E0701 08:55:52.642797 1942 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:52.738246 kubelet[1942]: E0701 08:55:52.738192 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:53.432790 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1728898631.mount: Deactivated successfully. Jul 1 08:55:53.739555 kubelet[1942]: E0701 08:55:53.738883 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:54.740393 kubelet[1942]: E0701 08:55:54.740247 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:55.740935 kubelet[1942]: E0701 08:55:55.740664 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:56.106410 containerd[1550]: time="2025-07-01T08:55:56.105984496Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:56.110961 containerd[1550]: time="2025-07-01T08:55:56.110851595Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039414" Jul 1 08:55:56.131939 containerd[1550]: time="2025-07-01T08:55:56.131753670Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:56.141157 containerd[1550]: time="2025-07-01T08:55:56.141049007Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:55:56.144964 containerd[1550]: time="2025-07-01T08:55:56.144196725Z" 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.501176988s" Jul 1 08:55:56.144964 containerd[1550]: time="2025-07-01T08:55:56.144402431Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Jul 1 08:55:56.158446 containerd[1550]: time="2025-07-01T08:55:56.158299460Z" level=info msg="CreateContainer within sandbox \"2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 1 08:55:56.186992 containerd[1550]: time="2025-07-01T08:55:56.186697390Z" level=info msg="Container ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:55:56.214325 containerd[1550]: time="2025-07-01T08:55:56.214084727Z" level=info msg="CreateContainer within sandbox \"2071e3fa2ce5a76455e456d1701a7f4892e6ae8fe679a1449de94e38c1ae9138\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040\"" Jul 1 08:55:56.218305 containerd[1550]: time="2025-07-01T08:55:56.217415478Z" level=info msg="StartContainer for \"ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040\"" Jul 1 08:55:56.225020 containerd[1550]: time="2025-07-01T08:55:56.224875305Z" level=info msg="connecting to shim ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040" address="unix:///run/containerd/s/f0114e3f37131fdd6a36d9eb234fe09b08cf4b717a330a14575099f30602665d" protocol=ttrpc version=3 Jul 1 08:55:56.293638 systemd[1]: Started cri-containerd-ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040.scope - libcontainer container ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040. Jul 1 08:55:56.357442 containerd[1550]: time="2025-07-01T08:55:56.357277341Z" level=info msg="StartContainer for \"ba3be25faaaf7fca01d05100338d9b421b70761621607387383ef98bd41f4040\" returns successfully" Jul 1 08:55:56.741509 kubelet[1942]: E0701 08:55:56.741175 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:57.270148 kubelet[1942]: I0701 08:55:57.269208 1942 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.762729835 podStartE2EDuration="8.269125722s" podCreationTimestamp="2025-07-01 08:55:49 +0000 UTC" firstStartedPulling="2025-07-01 08:55:49.642477711 +0000 UTC m=+37.969141450" lastFinishedPulling="2025-07-01 08:55:56.148873548 +0000 UTC m=+44.475537337" observedRunningTime="2025-07-01 08:55:57.26496245 +0000 UTC m=+45.591626310" watchObservedRunningTime="2025-07-01 08:55:57.269125722 +0000 UTC m=+45.595789511" Jul 1 08:55:57.741758 kubelet[1942]: E0701 08:55:57.741558 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:58.742119 kubelet[1942]: E0701 08:55:58.742011 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:55:59.742857 kubelet[1942]: E0701 08:55:59.742758 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:00.743751 kubelet[1942]: E0701 08:56:00.743641 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:01.744702 kubelet[1942]: E0701 08:56:01.744489 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:02.746161 kubelet[1942]: E0701 08:56:02.745859 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:03.747301 kubelet[1942]: E0701 08:56:03.747200 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:04.748018 kubelet[1942]: E0701 08:56:04.747898 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:05.749067 kubelet[1942]: E0701 08:56:05.748962 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:05.837874 systemd[1]: Created slice kubepods-besteffort-poddfd816fd_a13d_4349_b39e_ecaf1a811d07.slice - libcontainer container kubepods-besteffort-poddfd816fd_a13d_4349_b39e_ecaf1a811d07.slice. Jul 1 08:56:05.912670 kubelet[1942]: I0701 08:56:05.912561 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2r7s5\" (UniqueName: \"kubernetes.io/projected/dfd816fd-a13d-4349-b39e-ecaf1a811d07-kube-api-access-2r7s5\") pod \"test-pod-1\" (UID: \"dfd816fd-a13d-4349-b39e-ecaf1a811d07\") " pod="default/test-pod-1" Jul 1 08:56:05.913023 kubelet[1942]: I0701 08:56:05.912767 1942 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c89f8bd5-3eec-4cd6-9ccc-79e0171b7ae6\" (UniqueName: \"kubernetes.io/nfs/dfd816fd-a13d-4349-b39e-ecaf1a811d07-pvc-c89f8bd5-3eec-4cd6-9ccc-79e0171b7ae6\") pod \"test-pod-1\" (UID: \"dfd816fd-a13d-4349-b39e-ecaf1a811d07\") " pod="default/test-pod-1" Jul 1 08:56:06.112508 kernel: netfs: FS-Cache loaded Jul 1 08:56:06.204723 kernel: RPC: Registered named UNIX socket transport module. Jul 1 08:56:06.205112 kernel: RPC: Registered udp transport module. Jul 1 08:56:06.205182 kernel: RPC: Registered tcp transport module. Jul 1 08:56:06.205315 kernel: RPC: Registered tcp-with-tls transport module. Jul 1 08:56:06.206054 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 1 08:56:06.523886 kernel: NFS: Registering the id_resolver key type Jul 1 08:56:06.524228 kernel: Key type id_resolver registered Jul 1 08:56:06.524338 kernel: Key type id_legacy registered Jul 1 08:56:06.579858 nfsidmap[3020]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'novalocal' Jul 1 08:56:06.589299 nfsidmap[3021]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'novalocal' Jul 1 08:56:06.610597 nfsrahead[3023]: setting /var/lib/kubelet/pods/dfd816fd-a13d-4349-b39e-ecaf1a811d07/volumes/kubernetes.io~nfs/pvc-c89f8bd5-3eec-4cd6-9ccc-79e0171b7ae6 readahead to 128 Jul 1 08:56:06.748709 containerd[1550]: time="2025-07-01T08:56:06.748245144Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:dfd816fd-a13d-4349-b39e-ecaf1a811d07,Namespace:default,Attempt:0,}" Jul 1 08:56:06.750592 kubelet[1942]: E0701 08:56:06.750076 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:06.800920 systemd-networkd[1441]: vethdf9ff689: Link UP Jul 1 08:56:06.808593 kernel: cni0: port 3(vethdf9ff689) entered blocking state Jul 1 08:56:06.808766 kernel: cni0: port 3(vethdf9ff689) entered disabled state Jul 1 08:56:06.810668 kernel: vethdf9ff689: entered allmulticast mode Jul 1 08:56:06.812835 kernel: vethdf9ff689: entered promiscuous mode Jul 1 08:56:06.842391 kernel: cni0: port 3(vethdf9ff689) entered blocking state Jul 1 08:56:06.842501 kernel: cni0: port 3(vethdf9ff689) entered forwarding state Jul 1 08:56:06.844584 systemd-networkd[1441]: vethdf9ff689: Gained carrier Jul 1 08:56:06.845914 containerd[1550]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009c8e8), "name":"cbr0", "type":"bridge"} Jul 1 08:56:06.845914 containerd[1550]: delegateAdd: netconf sent to delegate plugin: Jul 1 08:56:06.905720 containerd[1550]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-07-01T08:56:06.905479892Z" level=info msg="connecting to shim 2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1" address="unix:///run/containerd/s/c0929512c32aeae5500b239f9c9edcc916965dd4057cb84a4ecee048dc149777" namespace=k8s.io protocol=ttrpc version=3 Jul 1 08:56:06.954598 systemd[1]: Started cri-containerd-2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1.scope - libcontainer container 2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1. Jul 1 08:56:07.013142 containerd[1550]: time="2025-07-01T08:56:07.012989578Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:dfd816fd-a13d-4349-b39e-ecaf1a811d07,Namespace:default,Attempt:0,} returns sandbox id \"2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1\"" Jul 1 08:56:07.016247 containerd[1550]: time="2025-07-01T08:56:07.016197501Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 1 08:56:07.750647 kubelet[1942]: E0701 08:56:07.750544 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:07.821264 containerd[1550]: time="2025-07-01T08:56:07.821096992Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 1 08:56:07.824887 containerd[1550]: time="2025-07-01T08:56:07.824801677Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 1 08:56:07.837559 containerd[1550]: time="2025-07-01T08:56:07.837442644Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"73313108\" in 821.156647ms" Jul 1 08:56:07.837855 containerd[1550]: time="2025-07-01T08:56:07.837618995Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:601c94998c5615a5f36a1babb9bcc2b1d9f112c02c19d68701b29f3fd6b2feb8\"" Jul 1 08:56:07.844998 containerd[1550]: time="2025-07-01T08:56:07.844886098Z" level=info msg="CreateContainer within sandbox \"2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 1 08:56:07.879929 containerd[1550]: time="2025-07-01T08:56:07.879817732Z" level=info msg="Container 1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575: CDI devices from CRI Config.CDIDevices: []" Jul 1 08:56:07.902843 containerd[1550]: time="2025-07-01T08:56:07.902716418Z" level=info msg="CreateContainer within sandbox \"2e175a5e8f4e86d0f7f3c7eff4e9678df4d2b28b51534e836155778c257b3ca1\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575\"" Jul 1 08:56:07.904927 containerd[1550]: time="2025-07-01T08:56:07.904820723Z" level=info msg="StartContainer for \"1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575\"" Jul 1 08:56:07.921743 containerd[1550]: time="2025-07-01T08:56:07.921415783Z" level=info msg="connecting to shim 1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575" address="unix:///run/containerd/s/c0929512c32aeae5500b239f9c9edcc916965dd4057cb84a4ecee048dc149777" protocol=ttrpc version=3 Jul 1 08:56:07.965535 systemd[1]: Started cri-containerd-1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575.scope - libcontainer container 1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575. Jul 1 08:56:08.005093 containerd[1550]: time="2025-07-01T08:56:08.004915153Z" level=info msg="StartContainer for \"1037d909eebef2362f075672f956734bb8037398f1c827d97f9fe28b9819f575\" returns successfully" Jul 1 08:56:08.330858 kubelet[1942]: I0701 08:56:08.330148 1942 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.505610068 podStartE2EDuration="17.33004988s" podCreationTimestamp="2025-07-01 08:55:51 +0000 UTC" firstStartedPulling="2025-07-01 08:56:07.015495035 +0000 UTC m=+55.342158774" lastFinishedPulling="2025-07-01 08:56:07.839934796 +0000 UTC m=+56.166598586" observedRunningTime="2025-07-01 08:56:08.329176633 +0000 UTC m=+56.655840482" watchObservedRunningTime="2025-07-01 08:56:08.33004988 +0000 UTC m=+56.656713669" Jul 1 08:56:08.337864 systemd-networkd[1441]: vethdf9ff689: Gained IPv6LL Jul 1 08:56:08.751533 kubelet[1942]: E0701 08:56:08.751222 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:09.751705 kubelet[1942]: E0701 08:56:09.751599 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:10.752473 kubelet[1942]: E0701 08:56:10.752115 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:11.753277 kubelet[1942]: E0701 08:56:11.753091 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:12.643246 kubelet[1942]: E0701 08:56:12.643109 1942 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:12.754197 kubelet[1942]: E0701 08:56:12.754117 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:13.755121 kubelet[1942]: E0701 08:56:13.754957 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:14.756205 kubelet[1942]: E0701 08:56:14.756074 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:15.757320 kubelet[1942]: E0701 08:56:15.757191 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:16.757865 kubelet[1942]: E0701 08:56:16.757727 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:17.758617 kubelet[1942]: E0701 08:56:17.758407 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:18.759613 kubelet[1942]: E0701 08:56:18.759533 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:19.759962 kubelet[1942]: E0701 08:56:19.759802 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:20.760598 kubelet[1942]: E0701 08:56:20.760496 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:21.761795 kubelet[1942]: E0701 08:56:21.761679 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:22.762766 kubelet[1942]: E0701 08:56:22.762479 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:23.763446 kubelet[1942]: E0701 08:56:23.763298 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:24.764275 kubelet[1942]: E0701 08:56:24.764184 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:25.765085 kubelet[1942]: E0701 08:56:25.764959 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:26.766538 kubelet[1942]: E0701 08:56:26.766446 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:27.767417 kubelet[1942]: E0701 08:56:27.767232 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:28.768463 kubelet[1942]: E0701 08:56:28.768272 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:29.768796 kubelet[1942]: E0701 08:56:29.768681 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:30.771198 kubelet[1942]: E0701 08:56:30.770643 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:31.771286 kubelet[1942]: E0701 08:56:31.771166 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:32.642799 kubelet[1942]: E0701 08:56:32.642674 1942 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:32.772532 kubelet[1942]: E0701 08:56:32.772435 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:33.773734 kubelet[1942]: E0701 08:56:33.773635 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:34.774487 kubelet[1942]: E0701 08:56:34.774338 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:35.774812 kubelet[1942]: E0701 08:56:35.774707 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:36.775634 kubelet[1942]: E0701 08:56:36.775493 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:37.776575 kubelet[1942]: E0701 08:56:37.776281 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:38.777476 kubelet[1942]: E0701 08:56:38.777317 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:39.778660 kubelet[1942]: E0701 08:56:39.778535 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:40.779561 kubelet[1942]: E0701 08:56:40.779416 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:41.780703 kubelet[1942]: E0701 08:56:41.780561 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:42.781579 kubelet[1942]: E0701 08:56:42.781429 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:43.783024 kubelet[1942]: E0701 08:56:43.782824 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:44.783272 kubelet[1942]: E0701 08:56:44.783141 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:45.783666 kubelet[1942]: E0701 08:56:45.783586 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:46.784716 kubelet[1942]: E0701 08:56:46.784613 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:47.785215 kubelet[1942]: E0701 08:56:47.785109 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 1 08:56:48.785962 kubelet[1942]: E0701 08:56:48.785789 1942 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"