Jul 9 23:50:01.831414 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 9 23:50:01.831437 kernel: Linux version 6.12.36-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Wed Jul 9 22:11:32 -00 2025 Jul 9 23:50:01.831448 kernel: KASLR enabled Jul 9 23:50:01.831454 kernel: efi: EFI v2.7 by EDK II Jul 9 23:50:01.831459 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Jul 9 23:50:01.831465 kernel: random: crng init done Jul 9 23:50:01.831472 kernel: secureboot: Secure boot disabled Jul 9 23:50:01.831478 kernel: ACPI: Early table checksum verification disabled Jul 9 23:50:01.831484 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Jul 9 23:50:01.831491 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 9 23:50:01.831497 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831503 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831509 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831515 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831522 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831530 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831537 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831543 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831549 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 9 23:50:01.831555 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 9 23:50:01.831561 kernel: ACPI: Use ACPI SPCR as default console: Yes Jul 9 23:50:01.831567 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 9 23:50:01.831574 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Jul 9 23:50:01.831580 kernel: Zone ranges: Jul 9 23:50:01.831586 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 9 23:50:01.831593 kernel: DMA32 empty Jul 9 23:50:01.831600 kernel: Normal empty Jul 9 23:50:01.831606 kernel: Device empty Jul 9 23:50:01.831612 kernel: Movable zone start for each node Jul 9 23:50:01.831618 kernel: Early memory node ranges Jul 9 23:50:01.831624 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Jul 9 23:50:01.831630 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Jul 9 23:50:01.831637 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Jul 9 23:50:01.831643 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Jul 9 23:50:01.831650 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Jul 9 23:50:01.831656 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Jul 9 23:50:01.831662 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Jul 9 23:50:01.831669 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Jul 9 23:50:01.831675 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Jul 9 23:50:01.831682 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Jul 9 23:50:01.831690 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Jul 9 23:50:01.831697 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Jul 9 23:50:01.831704 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jul 9 23:50:01.831712 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 9 23:50:01.831718 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 9 23:50:01.831725 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Jul 9 23:50:01.831732 kernel: psci: probing for conduit method from ACPI. Jul 9 23:50:01.831738 kernel: psci: PSCIv1.1 detected in firmware. Jul 9 23:50:01.831745 kernel: psci: Using standard PSCI v0.2 function IDs Jul 9 23:50:01.831752 kernel: psci: Trusted OS migration not required Jul 9 23:50:01.831758 kernel: psci: SMC Calling Convention v1.1 Jul 9 23:50:01.831765 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 9 23:50:01.831772 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jul 9 23:50:01.831780 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jul 9 23:50:01.831791 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 9 23:50:01.831798 kernel: Detected PIPT I-cache on CPU0 Jul 9 23:50:01.831805 kernel: CPU features: detected: GIC system register CPU interface Jul 9 23:50:01.831811 kernel: CPU features: detected: Spectre-v4 Jul 9 23:50:01.831818 kernel: CPU features: detected: Spectre-BHB Jul 9 23:50:01.831825 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 9 23:50:01.831831 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 9 23:50:01.831838 kernel: CPU features: detected: ARM erratum 1418040 Jul 9 23:50:01.831845 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 9 23:50:01.831851 kernel: alternatives: applying boot alternatives Jul 9 23:50:01.831859 kernel: 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,115200 flatcar.first_boot=detected acpi=force verity.usrhash=2184a17430c4a9b9510959efd0f91503c55f7c3f9309ab8cc7c91e6049bb51e4 Jul 9 23:50:01.831867 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 9 23:50:01.831874 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 9 23:50:01.831880 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 9 23:50:01.831887 kernel: Fallback order for Node 0: 0 Jul 9 23:50:01.831893 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Jul 9 23:50:01.831899 kernel: Policy zone: DMA Jul 9 23:50:01.831906 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 9 23:50:01.831917 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Jul 9 23:50:01.831923 kernel: software IO TLB: area num 4. Jul 9 23:50:01.831930 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Jul 9 23:50:01.831936 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Jul 9 23:50:01.831945 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 9 23:50:01.831951 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 9 23:50:01.831958 kernel: rcu: RCU event tracing is enabled. Jul 9 23:50:01.831965 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 9 23:50:01.831972 kernel: Trampoline variant of Tasks RCU enabled. Jul 9 23:50:01.831978 kernel: Tracing variant of Tasks RCU enabled. Jul 9 23:50:01.831985 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 9 23:50:01.831991 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 9 23:50:01.831998 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 9 23:50:01.832004 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 9 23:50:01.832010 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 9 23:50:01.832018 kernel: GICv3: 256 SPIs implemented Jul 9 23:50:01.832025 kernel: GICv3: 0 Extended SPIs implemented Jul 9 23:50:01.832031 kernel: Root IRQ handler: gic_handle_irq Jul 9 23:50:01.832038 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jul 9 23:50:01.832044 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Jul 9 23:50:01.832050 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 9 23:50:01.832056 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 9 23:50:01.832063 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Jul 9 23:50:01.832070 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Jul 9 23:50:01.832076 kernel: GICv3: using LPI property table @0x0000000040130000 Jul 9 23:50:01.832083 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Jul 9 23:50:01.832096 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 9 23:50:01.832105 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 9 23:50:01.832112 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 9 23:50:01.832121 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 9 23:50:01.832128 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 9 23:50:01.832134 kernel: arm-pv: using stolen time PV Jul 9 23:50:01.832141 kernel: Console: colour dummy device 80x25 Jul 9 23:50:01.832148 kernel: ACPI: Core revision 20240827 Jul 9 23:50:01.832155 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 9 23:50:01.832162 kernel: pid_max: default: 32768 minimum: 301 Jul 9 23:50:01.832172 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 9 23:50:01.832180 kernel: landlock: Up and running. Jul 9 23:50:01.832186 kernel: SELinux: Initializing. Jul 9 23:50:01.832193 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 9 23:50:01.832200 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 9 23:50:01.832207 kernel: rcu: Hierarchical SRCU implementation. Jul 9 23:50:01.832213 kernel: rcu: Max phase no-delay instances is 400. Jul 9 23:50:01.832221 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 9 23:50:01.832227 kernel: Remapping and enabling EFI services. Jul 9 23:50:01.832235 kernel: smp: Bringing up secondary CPUs ... Jul 9 23:50:01.832256 kernel: Detected PIPT I-cache on CPU1 Jul 9 23:50:01.832264 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 9 23:50:01.832271 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Jul 9 23:50:01.832279 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 9 23:50:01.832286 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 9 23:50:01.832293 kernel: Detected PIPT I-cache on CPU2 Jul 9 23:50:01.832300 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 9 23:50:01.832307 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Jul 9 23:50:01.832316 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 9 23:50:01.832322 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 9 23:50:01.832329 kernel: Detected PIPT I-cache on CPU3 Jul 9 23:50:01.832336 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 9 23:50:01.832344 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Jul 9 23:50:01.832351 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 9 23:50:01.832358 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 9 23:50:01.832365 kernel: smp: Brought up 1 node, 4 CPUs Jul 9 23:50:01.832372 kernel: SMP: Total of 4 processors activated. Jul 9 23:50:01.832381 kernel: CPU: All CPU(s) started at EL1 Jul 9 23:50:01.832388 kernel: CPU features: detected: 32-bit EL0 Support Jul 9 23:50:01.832395 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 9 23:50:01.832403 kernel: CPU features: detected: Common not Private translations Jul 9 23:50:01.832410 kernel: CPU features: detected: CRC32 instructions Jul 9 23:50:01.832418 kernel: CPU features: detected: Enhanced Virtualization Traps Jul 9 23:50:01.832425 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 9 23:50:01.832432 kernel: CPU features: detected: LSE atomic instructions Jul 9 23:50:01.832439 kernel: CPU features: detected: Privileged Access Never Jul 9 23:50:01.832448 kernel: CPU features: detected: RAS Extension Support Jul 9 23:50:01.832455 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 9 23:50:01.832462 kernel: alternatives: applying system-wide alternatives Jul 9 23:50:01.832469 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jul 9 23:50:01.832476 kernel: Memory: 2423968K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 39488K init, 1038K bss, 125984K reserved, 16384K cma-reserved) Jul 9 23:50:01.832499 kernel: devtmpfs: initialized Jul 9 23:50:01.832506 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 9 23:50:01.832513 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 9 23:50:01.832520 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 9 23:50:01.832529 kernel: 0 pages in range for non-PLT usage Jul 9 23:50:01.832535 kernel: 508432 pages in range for PLT usage Jul 9 23:50:01.832542 kernel: pinctrl core: initialized pinctrl subsystem Jul 9 23:50:01.832549 kernel: SMBIOS 3.0.0 present. Jul 9 23:50:01.832556 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Jul 9 23:50:01.832563 kernel: DMI: Memory slots populated: 1/1 Jul 9 23:50:01.832570 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 9 23:50:01.832577 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 9 23:50:01.832585 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 9 23:50:01.832594 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 9 23:50:01.832601 kernel: audit: initializing netlink subsys (disabled) Jul 9 23:50:01.832608 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Jul 9 23:50:01.832615 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 9 23:50:01.832622 kernel: cpuidle: using governor menu Jul 9 23:50:01.832629 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 9 23:50:01.832636 kernel: ASID allocator initialised with 32768 entries Jul 9 23:50:01.832643 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 9 23:50:01.832651 kernel: Serial: AMBA PL011 UART driver Jul 9 23:50:01.832659 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 9 23:50:01.832668 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jul 9 23:50:01.832678 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jul 9 23:50:01.832687 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jul 9 23:50:01.832698 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 9 23:50:01.832705 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jul 9 23:50:01.832713 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jul 9 23:50:01.832720 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jul 9 23:50:01.832727 kernel: ACPI: Added _OSI(Module Device) Jul 9 23:50:01.832736 kernel: ACPI: Added _OSI(Processor Device) Jul 9 23:50:01.832743 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 9 23:50:01.832750 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 9 23:50:01.832757 kernel: ACPI: Interpreter enabled Jul 9 23:50:01.832764 kernel: ACPI: Using GIC for interrupt routing Jul 9 23:50:01.832771 kernel: ACPI: MCFG table detected, 1 entries Jul 9 23:50:01.832778 kernel: ACPI: CPU0 has been hot-added Jul 9 23:50:01.832785 kernel: ACPI: CPU1 has been hot-added Jul 9 23:50:01.832792 kernel: ACPI: CPU2 has been hot-added Jul 9 23:50:01.832799 kernel: ACPI: CPU3 has been hot-added Jul 9 23:50:01.832808 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 9 23:50:01.832814 kernel: printk: legacy console [ttyAMA0] enabled Jul 9 23:50:01.832822 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 9 23:50:01.832986 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 9 23:50:01.833057 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 9 23:50:01.833134 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 9 23:50:01.833213 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 9 23:50:01.833361 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 9 23:50:01.833373 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 9 23:50:01.833380 kernel: PCI host bridge to bus 0000:00 Jul 9 23:50:01.833449 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 9 23:50:01.833515 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 9 23:50:01.833570 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 9 23:50:01.833624 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 9 23:50:01.833709 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Jul 9 23:50:01.833788 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jul 9 23:50:01.833850 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Jul 9 23:50:01.833917 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Jul 9 23:50:01.833990 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Jul 9 23:50:01.834059 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Jul 9 23:50:01.834135 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Jul 9 23:50:01.834204 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Jul 9 23:50:01.834292 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 9 23:50:01.834349 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 9 23:50:01.834403 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 9 23:50:01.834412 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 9 23:50:01.834419 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 9 23:50:01.834426 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 9 23:50:01.834437 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 9 23:50:01.834444 kernel: iommu: Default domain type: Translated Jul 9 23:50:01.834451 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 9 23:50:01.834458 kernel: efivars: Registered efivars operations Jul 9 23:50:01.834465 kernel: vgaarb: loaded Jul 9 23:50:01.834472 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 9 23:50:01.834480 kernel: VFS: Disk quotas dquot_6.6.0 Jul 9 23:50:01.834487 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 9 23:50:01.834494 kernel: pnp: PnP ACPI init Jul 9 23:50:01.834572 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 9 23:50:01.834583 kernel: pnp: PnP ACPI: found 1 devices Jul 9 23:50:01.834590 kernel: NET: Registered PF_INET protocol family Jul 9 23:50:01.834597 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 9 23:50:01.834604 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 9 23:50:01.834611 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 9 23:50:01.834618 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 9 23:50:01.834626 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 9 23:50:01.834635 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 9 23:50:01.834642 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 9 23:50:01.834649 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 9 23:50:01.834656 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 9 23:50:01.834664 kernel: PCI: CLS 0 bytes, default 64 Jul 9 23:50:01.834671 kernel: kvm [1]: HYP mode not available Jul 9 23:50:01.834678 kernel: Initialise system trusted keyrings Jul 9 23:50:01.834686 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 9 23:50:01.834693 kernel: Key type asymmetric registered Jul 9 23:50:01.834701 kernel: Asymmetric key parser 'x509' registered Jul 9 23:50:01.834708 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 9 23:50:01.834715 kernel: io scheduler mq-deadline registered Jul 9 23:50:01.834722 kernel: io scheduler kyber registered Jul 9 23:50:01.834730 kernel: io scheduler bfq registered Jul 9 23:50:01.834737 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 9 23:50:01.834744 kernel: ACPI: button: Power Button [PWRB] Jul 9 23:50:01.834751 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 9 23:50:01.834814 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 9 23:50:01.834825 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 9 23:50:01.834833 kernel: thunder_xcv, ver 1.0 Jul 9 23:50:01.834840 kernel: thunder_bgx, ver 1.0 Jul 9 23:50:01.834847 kernel: nicpf, ver 1.0 Jul 9 23:50:01.834853 kernel: nicvf, ver 1.0 Jul 9 23:50:01.834921 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 9 23:50:01.834979 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-09T23:50:01 UTC (1752105001) Jul 9 23:50:01.834989 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 9 23:50:01.834997 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jul 9 23:50:01.835006 kernel: watchdog: NMI not fully supported Jul 9 23:50:01.835013 kernel: watchdog: Hard watchdog permanently disabled Jul 9 23:50:01.835020 kernel: NET: Registered PF_INET6 protocol family Jul 9 23:50:01.835027 kernel: Segment Routing with IPv6 Jul 9 23:50:01.835034 kernel: In-situ OAM (IOAM) with IPv6 Jul 9 23:50:01.835042 kernel: NET: Registered PF_PACKET protocol family Jul 9 23:50:01.835049 kernel: Key type dns_resolver registered Jul 9 23:50:01.835056 kernel: registered taskstats version 1 Jul 9 23:50:01.835063 kernel: Loading compiled-in X.509 certificates Jul 9 23:50:01.835072 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.36-flatcar: 9d00c38f16c218623ac8c16dbd316c6a1c18f022' Jul 9 23:50:01.835079 kernel: Demotion targets for Node 0: null Jul 9 23:50:01.835086 kernel: Key type .fscrypt registered Jul 9 23:50:01.835104 kernel: Key type fscrypt-provisioning registered Jul 9 23:50:01.835112 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 9 23:50:01.835119 kernel: ima: Allocated hash algorithm: sha1 Jul 9 23:50:01.835126 kernel: ima: No architecture policies found Jul 9 23:50:01.835133 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 9 23:50:01.835147 kernel: clk: Disabling unused clocks Jul 9 23:50:01.835155 kernel: PM: genpd: Disabling unused power domains Jul 9 23:50:01.835162 kernel: Warning: unable to open an initial console. Jul 9 23:50:01.835170 kernel: Freeing unused kernel memory: 39488K Jul 9 23:50:01.835177 kernel: Run /init as init process Jul 9 23:50:01.835184 kernel: with arguments: Jul 9 23:50:01.835191 kernel: /init Jul 9 23:50:01.835198 kernel: with environment: Jul 9 23:50:01.835205 kernel: HOME=/ Jul 9 23:50:01.835212 kernel: TERM=linux Jul 9 23:50:01.835221 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 9 23:50:01.835229 systemd[1]: Successfully made /usr/ read-only. Jul 9 23:50:01.835239 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 9 23:50:01.835263 systemd[1]: Detected virtualization kvm. Jul 9 23:50:01.835272 systemd[1]: Detected architecture arm64. Jul 9 23:50:01.835279 systemd[1]: Running in initrd. Jul 9 23:50:01.835287 systemd[1]: No hostname configured, using default hostname. Jul 9 23:50:01.835298 systemd[1]: Hostname set to . Jul 9 23:50:01.835305 systemd[1]: Initializing machine ID from VM UUID. Jul 9 23:50:01.835313 systemd[1]: Queued start job for default target initrd.target. Jul 9 23:50:01.835320 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 9 23:50:01.835328 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 9 23:50:01.835336 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 9 23:50:01.835344 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 9 23:50:01.835352 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 9 23:50:01.835362 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 9 23:50:01.835370 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 9 23:50:01.835378 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 9 23:50:01.835386 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 9 23:50:01.835393 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 9 23:50:01.835401 systemd[1]: Reached target paths.target - Path Units. Jul 9 23:50:01.835409 systemd[1]: Reached target slices.target - Slice Units. Jul 9 23:50:01.835417 systemd[1]: Reached target swap.target - Swaps. Jul 9 23:50:01.835425 systemd[1]: Reached target timers.target - Timer Units. Jul 9 23:50:01.835433 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 9 23:50:01.835441 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 9 23:50:01.835449 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 9 23:50:01.835457 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 9 23:50:01.835464 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 9 23:50:01.835472 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 9 23:50:01.835481 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 9 23:50:01.835489 systemd[1]: Reached target sockets.target - Socket Units. Jul 9 23:50:01.835496 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 9 23:50:01.835504 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 9 23:50:01.835512 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 9 23:50:01.835525 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 9 23:50:01.835543 systemd[1]: Starting systemd-fsck-usr.service... Jul 9 23:50:01.835551 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 9 23:50:01.835559 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 9 23:50:01.835568 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 9 23:50:01.835576 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 9 23:50:01.835585 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 9 23:50:01.835592 systemd[1]: Finished systemd-fsck-usr.service. Jul 9 23:50:01.835628 systemd-journald[245]: Collecting audit messages is disabled. Jul 9 23:50:01.835651 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 9 23:50:01.835661 systemd-journald[245]: Journal started Jul 9 23:50:01.835682 systemd-journald[245]: Runtime Journal (/run/log/journal/4b879aef9bce4adaa0d78b7e497a350d) is 6M, max 48.5M, 42.4M free. Jul 9 23:50:01.828980 systemd-modules-load[247]: Inserted module 'overlay' Jul 9 23:50:01.837676 systemd[1]: Started systemd-journald.service - Journal Service. Jul 9 23:50:01.846402 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 9 23:50:01.850157 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 9 23:50:01.852280 kernel: Bridge firewalling registered Jul 9 23:50:01.851438 systemd-modules-load[247]: Inserted module 'br_netfilter' Jul 9 23:50:01.851641 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 9 23:50:01.853523 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 9 23:50:01.856277 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 9 23:50:01.860457 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 9 23:50:01.864171 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 9 23:50:01.866850 systemd-tmpfiles[265]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 9 23:50:01.867381 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 9 23:50:01.873077 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 9 23:50:01.879600 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 9 23:50:01.882934 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 9 23:50:01.884437 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 9 23:50:01.886881 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 9 23:50:01.895981 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 9 23:50:01.913774 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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,115200 flatcar.first_boot=detected acpi=force verity.usrhash=2184a17430c4a9b9510959efd0f91503c55f7c3f9309ab8cc7c91e6049bb51e4 Jul 9 23:50:01.924039 systemd-resolved[287]: Positive Trust Anchors: Jul 9 23:50:01.924059 systemd-resolved[287]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 9 23:50:01.924099 systemd-resolved[287]: 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 9 23:50:01.931995 systemd-resolved[287]: Defaulting to hostname 'linux'. Jul 9 23:50:01.933059 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 9 23:50:01.934700 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 9 23:50:02.017278 kernel: SCSI subsystem initialized Jul 9 23:50:02.029263 kernel: Loading iSCSI transport class v2.0-870. Jul 9 23:50:02.037273 kernel: iscsi: registered transport (tcp) Jul 9 23:50:02.053481 kernel: iscsi: registered transport (qla4xxx) Jul 9 23:50:02.053507 kernel: QLogic iSCSI HBA Driver Jul 9 23:50:02.076031 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 9 23:50:02.099191 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 9 23:50:02.102220 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 9 23:50:02.161947 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 9 23:50:02.165966 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 9 23:50:02.237288 kernel: raid6: neonx8 gen() 15461 MB/s Jul 9 23:50:02.254272 kernel: raid6: neonx4 gen() 15785 MB/s Jul 9 23:50:02.271272 kernel: raid6: neonx2 gen() 13236 MB/s Jul 9 23:50:02.288263 kernel: raid6: neonx1 gen() 10296 MB/s Jul 9 23:50:02.305266 kernel: raid6: int64x8 gen() 6883 MB/s Jul 9 23:50:02.322261 kernel: raid6: int64x4 gen() 7334 MB/s Jul 9 23:50:02.339275 kernel: raid6: int64x2 gen() 6096 MB/s Jul 9 23:50:02.356558 kernel: raid6: int64x1 gen() 4995 MB/s Jul 9 23:50:02.356600 kernel: raid6: using algorithm neonx4 gen() 15785 MB/s Jul 9 23:50:02.374474 kernel: raid6: .... xor() 12321 MB/s, rmw enabled Jul 9 23:50:02.374513 kernel: raid6: using neon recovery algorithm Jul 9 23:50:02.380274 kernel: xor: measuring software checksum speed Jul 9 23:50:02.380310 kernel: 8regs : 21567 MB/sec Jul 9 23:50:02.381574 kernel: 32regs : 18934 MB/sec Jul 9 23:50:02.381588 kernel: arm64_neon : 27955 MB/sec Jul 9 23:50:02.381597 kernel: xor: using function: arm64_neon (27955 MB/sec) Jul 9 23:50:02.440275 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 9 23:50:02.448691 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 9 23:50:02.451476 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 9 23:50:02.487193 systemd-udevd[498]: Using default interface naming scheme 'v255'. Jul 9 23:50:02.491422 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 9 23:50:02.494322 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 9 23:50:02.517020 dracut-pre-trigger[500]: rd.md=0: removing MD RAID activation Jul 9 23:50:02.544769 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 9 23:50:02.547476 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 9 23:50:02.606363 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 9 23:50:02.611309 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 9 23:50:02.669312 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jul 9 23:50:02.670503 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 9 23:50:02.671123 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 9 23:50:02.679053 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 9 23:50:02.679076 kernel: GPT:9289727 != 19775487 Jul 9 23:50:02.679094 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 9 23:50:02.671270 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 9 23:50:02.685184 kernel: GPT:9289727 != 19775487 Jul 9 23:50:02.685208 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 9 23:50:02.685220 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 9 23:50:02.685142 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 9 23:50:02.687469 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 9 23:50:02.714226 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 9 23:50:02.715840 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 9 23:50:02.724984 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 9 23:50:02.726651 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 9 23:50:02.741481 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 9 23:50:02.742788 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 9 23:50:02.752360 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 9 23:50:02.753764 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 9 23:50:02.755976 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 9 23:50:02.758357 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 9 23:50:02.760291 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 9 23:50:02.762360 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 9 23:50:02.781300 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 9 23:50:02.782476 disk-uuid[591]: Primary Header is updated. Jul 9 23:50:02.782476 disk-uuid[591]: Secondary Entries is updated. Jul 9 23:50:02.782476 disk-uuid[591]: Secondary Header is updated. Jul 9 23:50:02.782833 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 9 23:50:03.799011 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 9 23:50:03.799071 disk-uuid[598]: The operation has completed successfully. Jul 9 23:50:03.837862 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 9 23:50:03.839232 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 9 23:50:03.870548 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 9 23:50:03.892474 sh[611]: Success Jul 9 23:50:03.907873 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 9 23:50:03.907945 kernel: device-mapper: uevent: version 1.0.3 Jul 9 23:50:03.909272 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 9 23:50:03.924334 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jul 9 23:50:03.952883 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 9 23:50:03.955347 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 9 23:50:03.964823 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 9 23:50:03.973879 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 9 23:50:03.973928 kernel: BTRFS: device fsid 8bb7ac42-12f8-4d13-85ca-3e3493ea30a3 devid 1 transid 40 /dev/mapper/usr (253:0) scanned by mount (623) Jul 9 23:50:03.976753 kernel: BTRFS info (device dm-0): first mount of filesystem 8bb7ac42-12f8-4d13-85ca-3e3493ea30a3 Jul 9 23:50:03.976776 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jul 9 23:50:03.977707 kernel: BTRFS info (device dm-0): using free-space-tree Jul 9 23:50:03.982947 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 9 23:50:03.985531 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 9 23:50:03.987991 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 9 23:50:03.990713 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 9 23:50:03.992406 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 9 23:50:04.018290 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (652) Jul 9 23:50:04.021024 kernel: BTRFS info (device vda6): first mount of filesystem 3ed2368b-8cea-4555-8c38-901e822c2dee Jul 9 23:50:04.021074 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 9 23:50:04.021092 kernel: BTRFS info (device vda6): using free-space-tree Jul 9 23:50:04.030282 kernel: BTRFS info (device vda6): last unmount of filesystem 3ed2368b-8cea-4555-8c38-901e822c2dee Jul 9 23:50:04.033094 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 9 23:50:04.037556 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 9 23:50:04.121231 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 9 23:50:04.128221 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 9 23:50:04.173537 systemd-networkd[803]: lo: Link UP Jul 9 23:50:04.173549 systemd-networkd[803]: lo: Gained carrier Jul 9 23:50:04.174291 systemd-networkd[803]: Enumeration completed Jul 9 23:50:04.174542 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 9 23:50:04.174679 systemd-networkd[803]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 9 23:50:04.174682 systemd-networkd[803]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 9 23:50:04.175716 systemd[1]: Reached target network.target - Network. Jul 9 23:50:04.176516 systemd-networkd[803]: eth0: Link UP Jul 9 23:50:04.176520 systemd-networkd[803]: eth0: Gained carrier Jul 9 23:50:04.176529 systemd-networkd[803]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 9 23:50:04.191018 ignition[697]: Ignition 2.21.0 Jul 9 23:50:04.191031 ignition[697]: Stage: fetch-offline Jul 9 23:50:04.191065 ignition[697]: no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:04.191074 ignition[697]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:04.191314 ignition[697]: parsed url from cmdline: "" Jul 9 23:50:04.191318 ignition[697]: no config URL provided Jul 9 23:50:04.191326 ignition[697]: reading system config file "/usr/lib/ignition/user.ign" Jul 9 23:50:04.191333 ignition[697]: no config at "/usr/lib/ignition/user.ign" Jul 9 23:50:04.191360 ignition[697]: op(1): [started] loading QEMU firmware config module Jul 9 23:50:04.191364 ignition[697]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 9 23:50:04.199337 systemd-networkd[803]: eth0: DHCPv4 address 10.0.0.81/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 9 23:50:04.204374 ignition[697]: op(1): [finished] loading QEMU firmware config module Jul 9 23:50:04.210722 ignition[697]: parsing config with SHA512: 1a1f7df670e3f5ffdd8d39736ab0c37bd79315daf9dab8c1da8b60bc79ee6efc345e47743b98b77c05202da7457182c0cb51541dbd8744ffeb1e8fce7aee9adc Jul 9 23:50:04.213937 unknown[697]: fetched base config from "system" Jul 9 23:50:04.213949 unknown[697]: fetched user config from "qemu" Jul 9 23:50:04.214221 ignition[697]: fetch-offline: fetch-offline passed Jul 9 23:50:04.216361 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 9 23:50:04.214419 ignition[697]: Ignition finished successfully Jul 9 23:50:04.218003 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 9 23:50:04.218851 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 9 23:50:04.254304 ignition[813]: Ignition 2.21.0 Jul 9 23:50:04.254319 ignition[813]: Stage: kargs Jul 9 23:50:04.254501 ignition[813]: no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:04.254510 ignition[813]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:04.255302 ignition[813]: kargs: kargs passed Jul 9 23:50:04.258934 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 9 23:50:04.255354 ignition[813]: Ignition finished successfully Jul 9 23:50:04.261509 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 9 23:50:04.288597 ignition[821]: Ignition 2.21.0 Jul 9 23:50:04.288615 ignition[821]: Stage: disks Jul 9 23:50:04.288755 ignition[821]: no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:04.288764 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:04.291129 ignition[821]: disks: disks passed Jul 9 23:50:04.293308 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 9 23:50:04.291202 ignition[821]: Ignition finished successfully Jul 9 23:50:04.294942 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 9 23:50:04.296759 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 9 23:50:04.298857 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 9 23:50:04.300896 systemd[1]: Reached target sysinit.target - System Initialization. Jul 9 23:50:04.302924 systemd[1]: Reached target basic.target - Basic System. Jul 9 23:50:04.305704 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 9 23:50:04.343713 systemd-fsck[830]: ROOT: clean, 15/553520 files, 52789/553472 blocks Jul 9 23:50:04.348099 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 9 23:50:04.350781 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 9 23:50:04.433266 kernel: EXT4-fs (vda9): mounted filesystem 8a2ecd5f-ae22-4776-8ac3-47f189208292 r/w with ordered data mode. Quota mode: none. Jul 9 23:50:04.434180 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 9 23:50:04.435614 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 9 23:50:04.438687 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 9 23:50:04.441315 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 9 23:50:04.442393 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 9 23:50:04.442457 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 9 23:50:04.442494 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 9 23:50:04.455598 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 9 23:50:04.457960 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 9 23:50:04.467445 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (839) Jul 9 23:50:04.467493 kernel: BTRFS info (device vda6): first mount of filesystem 3ed2368b-8cea-4555-8c38-901e822c2dee Jul 9 23:50:04.469963 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 9 23:50:04.469990 kernel: BTRFS info (device vda6): using free-space-tree Jul 9 23:50:04.474654 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 9 23:50:04.536628 initrd-setup-root[863]: cut: /sysroot/etc/passwd: No such file or directory Jul 9 23:50:04.540982 initrd-setup-root[870]: cut: /sysroot/etc/group: No such file or directory Jul 9 23:50:04.544814 initrd-setup-root[877]: cut: /sysroot/etc/shadow: No such file or directory Jul 9 23:50:04.548397 initrd-setup-root[884]: cut: /sysroot/etc/gshadow: No such file or directory Jul 9 23:50:04.637764 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 9 23:50:04.639833 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 9 23:50:04.641495 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 9 23:50:04.666275 kernel: BTRFS info (device vda6): last unmount of filesystem 3ed2368b-8cea-4555-8c38-901e822c2dee Jul 9 23:50:04.698325 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 9 23:50:04.705747 ignition[952]: INFO : Ignition 2.21.0 Jul 9 23:50:04.706823 ignition[952]: INFO : Stage: mount Jul 9 23:50:04.706823 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:04.706823 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:04.710458 ignition[952]: INFO : mount: mount passed Jul 9 23:50:04.710458 ignition[952]: INFO : Ignition finished successfully Jul 9 23:50:04.709173 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 9 23:50:04.712636 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 9 23:50:04.972150 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 9 23:50:04.973708 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 9 23:50:05.008263 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (965) Jul 9 23:50:05.010831 kernel: BTRFS info (device vda6): first mount of filesystem 3ed2368b-8cea-4555-8c38-901e822c2dee Jul 9 23:50:05.010877 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 9 23:50:05.010888 kernel: BTRFS info (device vda6): using free-space-tree Jul 9 23:50:05.014371 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 9 23:50:05.057896 ignition[982]: INFO : Ignition 2.21.0 Jul 9 23:50:05.057896 ignition[982]: INFO : Stage: files Jul 9 23:50:05.060111 ignition[982]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:05.060111 ignition[982]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:05.060111 ignition[982]: DEBUG : files: compiled without relabeling support, skipping Jul 9 23:50:05.064017 ignition[982]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 9 23:50:05.064017 ignition[982]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 9 23:50:05.064017 ignition[982]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 9 23:50:05.064017 ignition[982]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 9 23:50:05.064017 ignition[982]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 9 23:50:05.063659 unknown[982]: wrote ssh authorized keys file for user: core Jul 9 23:50:05.073962 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 9 23:50:05.073962 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 9 23:50:05.073962 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 9 23:50:05.080427 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 9 23:50:05.080427 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 9 23:50:05.080427 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 9 23:50:05.080427 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 9 23:50:05.080427 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Jul 9 23:50:05.582383 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 9 23:50:06.083391 systemd-networkd[803]: eth0: Gained IPv6LL Jul 9 23:50:06.143733 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 9 23:50:06.143733 ignition[982]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 9 23:50:06.147867 ignition[982]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 9 23:50:06.147867 ignition[982]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 9 23:50:06.147867 ignition[982]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 9 23:50:06.147867 ignition[982]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 9 23:50:06.173652 ignition[982]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 9 23:50:06.177171 ignition[982]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 9 23:50:06.179830 ignition[982]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 9 23:50:06.179830 ignition[982]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 9 23:50:06.179830 ignition[982]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 9 23:50:06.179830 ignition[982]: INFO : files: files passed Jul 9 23:50:06.179830 ignition[982]: INFO : Ignition finished successfully Jul 9 23:50:06.180430 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 9 23:50:06.183227 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 9 23:50:06.185372 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 9 23:50:06.209093 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 9 23:50:06.209216 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 9 23:50:06.213159 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory Jul 9 23:50:06.214744 initrd-setup-root-after-ignition[1012]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 9 23:50:06.214744 initrd-setup-root-after-ignition[1012]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 9 23:50:06.218361 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 9 23:50:06.215874 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 9 23:50:06.221370 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 9 23:50:06.225492 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 9 23:50:06.296109 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 9 23:50:06.296218 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 9 23:50:06.298449 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 9 23:50:06.299621 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 9 23:50:06.301745 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 9 23:50:06.302891 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 9 23:50:06.336384 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 9 23:50:06.339142 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 9 23:50:06.360657 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 9 23:50:06.361949 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 9 23:50:06.363977 systemd[1]: Stopped target timers.target - Timer Units. Jul 9 23:50:06.369455 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 9 23:50:06.369594 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 9 23:50:06.372462 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 9 23:50:06.374536 systemd[1]: Stopped target basic.target - Basic System. Jul 9 23:50:06.376153 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 9 23:50:06.377878 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 9 23:50:06.379895 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 9 23:50:06.381916 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 9 23:50:06.383894 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 9 23:50:06.385741 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 9 23:50:06.387852 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 9 23:50:06.389879 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 9 23:50:06.391613 systemd[1]: Stopped target swap.target - Swaps. Jul 9 23:50:06.393100 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 9 23:50:06.393257 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 9 23:50:06.396098 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 9 23:50:06.398204 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 9 23:50:06.400197 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 9 23:50:06.401329 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 9 23:50:06.403347 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 9 23:50:06.403493 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 9 23:50:06.406495 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 9 23:50:06.406631 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 9 23:50:06.408734 systemd[1]: Stopped target paths.target - Path Units. Jul 9 23:50:06.410332 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 9 23:50:06.410427 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 9 23:50:06.412585 systemd[1]: Stopped target slices.target - Slice Units. Jul 9 23:50:06.414189 systemd[1]: Stopped target sockets.target - Socket Units. Jul 9 23:50:06.416023 systemd[1]: iscsid.socket: Deactivated successfully. Jul 9 23:50:06.416134 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 9 23:50:06.418302 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 9 23:50:06.418390 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 9 23:50:06.420022 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 9 23:50:06.420158 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 9 23:50:06.421980 systemd[1]: ignition-files.service: Deactivated successfully. Jul 9 23:50:06.422104 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 9 23:50:06.424540 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 9 23:50:06.427003 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 9 23:50:06.428945 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 9 23:50:06.429100 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 9 23:50:06.431337 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 9 23:50:06.431458 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 9 23:50:06.437724 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 9 23:50:06.437809 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 9 23:50:06.452276 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 9 23:50:06.455014 ignition[1038]: INFO : Ignition 2.21.0 Jul 9 23:50:06.455014 ignition[1038]: INFO : Stage: umount Jul 9 23:50:06.456995 ignition[1038]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 9 23:50:06.456995 ignition[1038]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 9 23:50:06.456995 ignition[1038]: INFO : umount: umount passed Jul 9 23:50:06.456995 ignition[1038]: INFO : Ignition finished successfully Jul 9 23:50:06.457857 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 9 23:50:06.457970 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 9 23:50:06.459218 systemd[1]: Stopped target network.target - Network. Jul 9 23:50:06.464569 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 9 23:50:06.464656 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 9 23:50:06.466284 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 9 23:50:06.466331 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 9 23:50:06.468093 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 9 23:50:06.468146 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 9 23:50:06.469889 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 9 23:50:06.469989 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 9 23:50:06.471784 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 9 23:50:06.473459 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 9 23:50:06.480155 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 9 23:50:06.480368 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 9 23:50:06.482492 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 9 23:50:06.482561 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 9 23:50:06.486035 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 9 23:50:06.486138 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 9 23:50:06.491642 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 9 23:50:06.491908 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 9 23:50:06.493299 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 9 23:50:06.496908 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 9 23:50:06.498024 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 9 23:50:06.499672 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 9 23:50:06.499716 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 9 23:50:06.502914 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 9 23:50:06.504688 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 9 23:50:06.504756 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 9 23:50:06.506723 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 9 23:50:06.506771 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 9 23:50:06.509496 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 9 23:50:06.509555 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 9 23:50:06.511542 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 9 23:50:06.511589 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 9 23:50:06.514681 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 9 23:50:06.519942 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 9 23:50:06.520007 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 9 23:50:06.535851 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 9 23:50:06.537406 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 9 23:50:06.538958 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 9 23:50:06.538994 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 9 23:50:06.540782 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 9 23:50:06.540812 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 9 23:50:06.542436 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 9 23:50:06.542485 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 9 23:50:06.545004 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 9 23:50:06.545051 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 9 23:50:06.551827 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 9 23:50:06.551882 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 9 23:50:06.555352 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 9 23:50:06.556382 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 9 23:50:06.556437 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 9 23:50:06.559744 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 9 23:50:06.559791 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 9 23:50:06.563820 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 9 23:50:06.563864 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 9 23:50:06.566226 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 9 23:50:06.566286 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 9 23:50:06.568518 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 9 23:50:06.568564 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 9 23:50:06.574343 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 9 23:50:06.574394 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 9 23:50:06.574423 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 9 23:50:06.574453 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 9 23:50:06.574705 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 9 23:50:06.581373 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 9 23:50:06.586817 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 9 23:50:06.586935 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 9 23:50:06.589299 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 9 23:50:06.591059 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 9 23:50:06.613426 systemd[1]: Switching root. Jul 9 23:50:06.664436 systemd-journald[245]: Journal stopped Jul 9 23:50:07.600851 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Jul 9 23:50:07.600898 kernel: SELinux: policy capability network_peer_controls=1 Jul 9 23:50:07.600915 kernel: SELinux: policy capability open_perms=1 Jul 9 23:50:07.600925 kernel: SELinux: policy capability extended_socket_class=1 Jul 9 23:50:07.600935 kernel: SELinux: policy capability always_check_network=0 Jul 9 23:50:07.600946 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 9 23:50:07.600958 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 9 23:50:07.600967 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 9 23:50:07.600978 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 9 23:50:07.600987 kernel: SELinux: policy capability userspace_initial_context=0 Jul 9 23:50:07.600996 kernel: audit: type=1403 audit(1752105006.859:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 9 23:50:07.601006 systemd[1]: Successfully loaded SELinux policy in 43.499ms. Jul 9 23:50:07.601025 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.647ms. Jul 9 23:50:07.601038 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 9 23:50:07.601052 systemd[1]: Detected virtualization kvm. Jul 9 23:50:07.601063 systemd[1]: Detected architecture arm64. Jul 9 23:50:07.601083 systemd[1]: Detected first boot. Jul 9 23:50:07.601095 systemd[1]: Initializing machine ID from VM UUID. Jul 9 23:50:07.601105 zram_generator::config[1083]: No configuration found. Jul 9 23:50:07.601116 kernel: NET: Registered PF_VSOCK protocol family Jul 9 23:50:07.601126 systemd[1]: Populated /etc with preset unit settings. Jul 9 23:50:07.601139 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 9 23:50:07.601150 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 9 23:50:07.601160 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 9 23:50:07.601170 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 9 23:50:07.601180 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 9 23:50:07.601194 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 9 23:50:07.601204 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 9 23:50:07.601215 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 9 23:50:07.601225 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 9 23:50:07.601238 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 9 23:50:07.601266 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 9 23:50:07.601277 systemd[1]: Created slice user.slice - User and Session Slice. Jul 9 23:50:07.601287 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 9 23:50:07.601298 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 9 23:50:07.601308 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 9 23:50:07.601318 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 9 23:50:07.601332 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 9 23:50:07.601345 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 9 23:50:07.601356 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jul 9 23:50:07.601366 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 9 23:50:07.601377 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 9 23:50:07.601388 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 9 23:50:07.601398 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 9 23:50:07.601408 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 9 23:50:07.601419 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 9 23:50:07.601431 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 9 23:50:07.601441 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 9 23:50:07.601451 systemd[1]: Reached target slices.target - Slice Units. Jul 9 23:50:07.601461 systemd[1]: Reached target swap.target - Swaps. Jul 9 23:50:07.601472 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 9 23:50:07.601482 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 9 23:50:07.601492 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 9 23:50:07.601503 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 9 23:50:07.601513 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 9 23:50:07.601525 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 9 23:50:07.601535 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 9 23:50:07.601545 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 9 23:50:07.601555 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 9 23:50:07.601565 systemd[1]: Mounting media.mount - External Media Directory... Jul 9 23:50:07.601575 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 9 23:50:07.601586 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 9 23:50:07.601596 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 9 23:50:07.601609 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 9 23:50:07.601620 systemd[1]: Reached target machines.target - Containers. Jul 9 23:50:07.601630 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 9 23:50:07.601641 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 9 23:50:07.601651 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 9 23:50:07.601661 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 9 23:50:07.601672 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 9 23:50:07.601682 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 9 23:50:07.601692 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 9 23:50:07.601703 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 9 23:50:07.601714 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 9 23:50:07.601725 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 9 23:50:07.601735 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 9 23:50:07.601745 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 9 23:50:07.601756 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 9 23:50:07.601767 systemd[1]: Stopped systemd-fsck-usr.service. Jul 9 23:50:07.601777 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 9 23:50:07.601788 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 9 23:50:07.601800 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 9 23:50:07.601810 kernel: loop: module loaded Jul 9 23:50:07.601820 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 9 23:50:07.601830 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 9 23:50:07.601840 kernel: fuse: init (API version 7.41) Jul 9 23:50:07.601850 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 9 23:50:07.601860 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 9 23:50:07.601870 systemd[1]: verity-setup.service: Deactivated successfully. Jul 9 23:50:07.601881 systemd[1]: Stopped verity-setup.service. Jul 9 23:50:07.601892 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 9 23:50:07.601902 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 9 23:50:07.601912 kernel: ACPI: bus type drm_connector registered Jul 9 23:50:07.601923 systemd[1]: Mounted media.mount - External Media Directory. Jul 9 23:50:07.601933 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 9 23:50:07.601943 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 9 23:50:07.601954 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 9 23:50:07.601965 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 9 23:50:07.601976 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 9 23:50:07.601986 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 9 23:50:07.601998 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 9 23:50:07.602009 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 9 23:50:07.602020 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 9 23:50:07.602031 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 9 23:50:07.602042 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 9 23:50:07.602052 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 9 23:50:07.602062 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 9 23:50:07.602079 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 9 23:50:07.602089 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 9 23:50:07.602101 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 9 23:50:07.602112 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 9 23:50:07.602122 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 9 23:50:07.602156 systemd-journald[1144]: Collecting audit messages is disabled. Jul 9 23:50:07.602179 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 9 23:50:07.602191 systemd-journald[1144]: Journal started Jul 9 23:50:07.602214 systemd-journald[1144]: Runtime Journal (/run/log/journal/4b879aef9bce4adaa0d78b7e497a350d) is 6M, max 48.5M, 42.4M free. Jul 9 23:50:07.285185 systemd[1]: Queued start job for default target multi-user.target. Jul 9 23:50:07.307783 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 9 23:50:07.308192 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 9 23:50:07.604495 systemd[1]: Started systemd-journald.service - Journal Service. Jul 9 23:50:07.607359 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 9 23:50:07.608900 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 9 23:50:07.621097 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 9 23:50:07.623916 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 9 23:50:07.626191 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 9 23:50:07.627281 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 9 23:50:07.627330 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 9 23:50:07.629397 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 9 23:50:07.635174 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 9 23:50:07.636691 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 9 23:50:07.637957 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 9 23:50:07.640046 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 9 23:50:07.642188 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 9 23:50:07.644499 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 9 23:50:07.647375 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 9 23:50:07.648981 systemd-journald[1144]: Time spent on flushing to /var/log/journal/4b879aef9bce4adaa0d78b7e497a350d is 19.504ms for 869 entries. Jul 9 23:50:07.648981 systemd-journald[1144]: System Journal (/var/log/journal/4b879aef9bce4adaa0d78b7e497a350d) is 8M, max 195.6M, 187.6M free. Jul 9 23:50:07.682528 systemd-journald[1144]: Received client request to flush runtime journal. Jul 9 23:50:07.682596 kernel: loop0: detected capacity change from 0 to 107312 Jul 9 23:50:07.649142 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 9 23:50:07.656451 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 9 23:50:07.660685 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 9 23:50:07.664401 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 9 23:50:07.666778 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 9 23:50:07.668233 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 9 23:50:07.669951 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 9 23:50:07.675612 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 9 23:50:07.682269 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 9 23:50:07.684961 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 9 23:50:07.692063 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 9 23:50:07.701276 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 9 23:50:07.703513 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Jul 9 23:50:07.703523 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Jul 9 23:50:07.708785 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 9 23:50:07.711956 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 9 23:50:07.721276 kernel: loop1: detected capacity change from 0 to 207008 Jul 9 23:50:07.725509 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 9 23:50:07.749934 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 9 23:50:07.750260 kernel: loop2: detected capacity change from 0 to 138376 Jul 9 23:50:07.760511 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 9 23:50:07.780264 kernel: loop3: detected capacity change from 0 to 107312 Jul 9 23:50:07.791306 kernel: loop4: detected capacity change from 0 to 207008 Jul 9 23:50:07.794332 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Jul 9 23:50:07.794349 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. Jul 9 23:50:07.802344 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 9 23:50:07.814429 kernel: loop5: detected capacity change from 0 to 138376 Jul 9 23:50:07.826080 (sd-merge)[1224]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Jul 9 23:50:07.826500 (sd-merge)[1224]: Merged extensions into '/usr'. Jul 9 23:50:07.832456 systemd[1]: Reload requested from client PID 1199 ('systemd-sysext') (unit systemd-sysext.service)... Jul 9 23:50:07.832479 systemd[1]: Reloading... Jul 9 23:50:07.900282 zram_generator::config[1250]: No configuration found. Jul 9 23:50:07.988730 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 9 23:50:07.989096 ldconfig[1194]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 9 23:50:08.053816 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 9 23:50:08.054158 systemd[1]: Reloading finished in 221 ms. Jul 9 23:50:08.071888 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 9 23:50:08.073504 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 9 23:50:08.084715 systemd[1]: Starting ensure-sysext.service... Jul 9 23:50:08.086644 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 9 23:50:08.099933 systemd[1]: Reload requested from client PID 1285 ('systemctl') (unit ensure-sysext.service)... Jul 9 23:50:08.099952 systemd[1]: Reloading... Jul 9 23:50:08.105759 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 9 23:50:08.105798 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 9 23:50:08.106365 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 9 23:50:08.106565 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 9 23:50:08.107195 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 9 23:50:08.107533 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. Jul 9 23:50:08.107587 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. Jul 9 23:50:08.109906 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. Jul 9 23:50:08.109918 systemd-tmpfiles[1286]: Skipping /boot Jul 9 23:50:08.119476 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. Jul 9 23:50:08.119491 systemd-tmpfiles[1286]: Skipping /boot Jul 9 23:50:08.158267 zram_generator::config[1314]: No configuration found. Jul 9 23:50:08.233928 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 9 23:50:08.296369 systemd[1]: Reloading finished in 196 ms. Jul 9 23:50:08.306971 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 9 23:50:08.325238 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 9 23:50:08.334459 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 9 23:50:08.337747 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 9 23:50:08.351226 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 9 23:50:08.354829 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 9 23:50:08.359530 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 9 23:50:08.362220 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 9 23:50:08.366009 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 9 23:50:08.367261 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 9 23:50:08.383461 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 9 23:50:08.386580 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 9 23:50:08.388287 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 9 23:50:08.388426 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 9 23:50:08.393565 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 9 23:50:08.395782 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 9 23:50:08.396028 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 9 23:50:08.400451 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 9 23:50:08.402687 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 9 23:50:08.402842 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 9 23:50:08.404893 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 9 23:50:08.405162 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 9 23:50:08.414091 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 9 23:50:08.419932 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 9 23:50:08.424678 systemd-udevd[1354]: Using default interface naming scheme 'v255'. Jul 9 23:50:08.425824 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 9 23:50:08.429462 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 9 23:50:08.430925 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 9 23:50:08.431128 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 9 23:50:08.441853 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 9 23:50:08.446283 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 9 23:50:08.448366 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 9 23:50:08.449590 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 9 23:50:08.451949 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 9 23:50:08.452112 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 9 23:50:08.453903 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 9 23:50:08.454474 augenrules[1386]: No rules Jul 9 23:50:08.456589 systemd[1]: audit-rules.service: Deactivated successfully. Jul 9 23:50:08.456791 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 9 23:50:08.458559 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 9 23:50:08.458741 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 9 23:50:08.463767 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 9 23:50:08.466078 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 9 23:50:08.484325 systemd[1]: Finished ensure-sysext.service. Jul 9 23:50:08.485464 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 9 23:50:08.499506 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 9 23:50:08.501441 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 9 23:50:08.517496 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 9 23:50:08.522019 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 9 23:50:08.525386 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 9 23:50:08.529239 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 9 23:50:08.531538 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 9 23:50:08.531592 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 9 23:50:08.533527 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 9 23:50:08.537239 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 9 23:50:08.538852 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 9 23:50:08.552403 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jul 9 23:50:08.554899 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 9 23:50:08.557185 augenrules[1429]: /sbin/augenrules: No change Jul 9 23:50:08.565579 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 9 23:50:08.571750 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 9 23:50:08.571973 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 9 23:50:08.574045 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 9 23:50:08.575036 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 9 23:50:08.578225 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 9 23:50:08.578418 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 9 23:50:08.587816 augenrules[1462]: No rules Jul 9 23:50:08.589223 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 9 23:50:08.589295 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 9 23:50:08.589561 systemd[1]: audit-rules.service: Deactivated successfully. Jul 9 23:50:08.591445 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 9 23:50:08.606173 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 9 23:50:08.611053 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 9 23:50:08.645615 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 9 23:50:08.667728 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 9 23:50:08.669557 systemd[1]: Reached target time-set.target - System Time Set. Jul 9 23:50:08.683694 systemd-networkd[1444]: lo: Link UP Jul 9 23:50:08.683703 systemd-networkd[1444]: lo: Gained carrier Jul 9 23:50:08.684606 systemd-networkd[1444]: Enumeration completed Jul 9 23:50:08.684819 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 9 23:50:08.685156 systemd-networkd[1444]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 9 23:50:08.685159 systemd-networkd[1444]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 9 23:50:08.686425 systemd-networkd[1444]: eth0: Link UP Jul 9 23:50:08.686538 systemd-networkd[1444]: eth0: Gained carrier Jul 9 23:50:08.686553 systemd-networkd[1444]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 9 23:50:08.688337 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 9 23:50:08.689362 systemd-resolved[1352]: Positive Trust Anchors: Jul 9 23:50:08.689381 systemd-resolved[1352]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 9 23:50:08.689412 systemd-resolved[1352]: 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 9 23:50:08.691480 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 9 23:50:08.706328 systemd-networkd[1444]: eth0: DHCPv4 address 10.0.0.81/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 9 23:50:08.706873 systemd-timesyncd[1445]: Network configuration changed, trying to establish connection. Jul 9 23:50:08.710397 systemd-timesyncd[1445]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 9 23:50:08.710463 systemd-timesyncd[1445]: Initial clock synchronization to Wed 2025-07-09 23:50:08.464327 UTC. Jul 9 23:50:08.710712 systemd-resolved[1352]: Defaulting to hostname 'linux'. Jul 9 23:50:08.714335 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 9 23:50:08.715920 systemd[1]: Reached target network.target - Network. Jul 9 23:50:08.717633 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 9 23:50:08.718895 systemd[1]: Reached target sysinit.target - System Initialization. Jul 9 23:50:08.721131 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 9 23:50:08.722601 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 9 23:50:08.724854 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 9 23:50:08.726399 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 9 23:50:08.728397 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 9 23:50:08.730143 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 9 23:50:08.730180 systemd[1]: Reached target paths.target - Path Units. Jul 9 23:50:08.731318 systemd[1]: Reached target timers.target - Timer Units. Jul 9 23:50:08.736144 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 9 23:50:08.739629 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 9 23:50:08.745229 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 9 23:50:08.748563 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 9 23:50:08.751345 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 9 23:50:08.763030 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 9 23:50:08.764921 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 9 23:50:08.767559 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 9 23:50:08.768985 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 9 23:50:08.777776 systemd[1]: Reached target sockets.target - Socket Units. Jul 9 23:50:08.778759 systemd[1]: Reached target basic.target - Basic System. Jul 9 23:50:08.779747 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 9 23:50:08.779786 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 9 23:50:08.781136 systemd[1]: Starting containerd.service - containerd container runtime... Jul 9 23:50:08.783212 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 9 23:50:08.785126 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 9 23:50:08.793040 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 9 23:50:08.794991 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 9 23:50:08.796025 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 9 23:50:08.796926 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 9 23:50:08.800368 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 9 23:50:08.800823 jq[1500]: false Jul 9 23:50:08.803413 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 9 23:50:08.810148 extend-filesystems[1501]: Found /dev/vda6 Jul 9 23:50:08.810568 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 9 23:50:08.814562 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 9 23:50:08.817260 extend-filesystems[1501]: Found /dev/vda9 Jul 9 23:50:08.816951 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 9 23:50:08.818178 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 9 23:50:08.818798 systemd[1]: Starting update-engine.service - Update Engine... Jul 9 23:50:08.821954 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 9 23:50:08.823197 extend-filesystems[1501]: Checking size of /dev/vda9 Jul 9 23:50:08.828655 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 9 23:50:08.830715 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 9 23:50:08.832499 jq[1521]: true Jul 9 23:50:08.830916 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 9 23:50:08.831169 systemd[1]: motdgen.service: Deactivated successfully. Jul 9 23:50:08.831354 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 9 23:50:08.832667 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 9 23:50:08.832820 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 9 23:50:08.841395 (ntainerd)[1527]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 9 23:50:08.847925 jq[1526]: true Jul 9 23:50:08.849817 extend-filesystems[1501]: Resized partition /dev/vda9 Jul 9 23:50:08.860419 extend-filesystems[1539]: resize2fs 1.47.2 (1-Jan-2025) Jul 9 23:50:08.884312 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 9 23:50:08.892734 update_engine[1518]: I20250709 23:50:08.892537 1518 main.cc:92] Flatcar Update Engine starting Jul 9 23:50:08.968534 dbus-daemon[1498]: [system] SELinux support is enabled Jul 9 23:50:08.969094 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 9 23:50:08.973809 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 9 23:50:08.973845 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 9 23:50:08.974629 update_engine[1518]: I20250709 23:50:08.974431 1518 update_check_scheduler.cc:74] Next update check in 5m22s Jul 9 23:50:08.979639 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 9 23:50:08.979972 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 9 23:50:08.982879 systemd[1]: Started update-engine.service - Update Engine. Jul 9 23:50:08.992586 systemd-logind[1510]: Watching system buttons on /dev/input/event0 (Power Button) Jul 9 23:50:08.993472 systemd-logind[1510]: New seat seat0. Jul 9 23:50:08.998005 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 9 23:50:08.999309 systemd[1]: Started systemd-logind.service - User Login Management. Jul 9 23:50:09.002278 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 9 23:50:09.015439 extend-filesystems[1539]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 9 23:50:09.015439 extend-filesystems[1539]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 9 23:50:09.015439 extend-filesystems[1539]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 9 23:50:09.018852 bash[1556]: Updated "/home/core/.ssh/authorized_keys" Jul 9 23:50:09.019034 extend-filesystems[1501]: Resized filesystem in /dev/vda9 Jul 9 23:50:09.019446 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 9 23:50:09.021941 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 9 23:50:09.025091 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 9 23:50:09.026883 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 9 23:50:09.030040 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 9 23:50:09.069509 locksmithd[1557]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 9 23:50:09.160545 containerd[1527]: time="2025-07-09T23:50:09Z" 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 9 23:50:09.161180 containerd[1527]: time="2025-07-09T23:50:09.161133975Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 9 23:50:09.171762 containerd[1527]: time="2025-07-09T23:50:09.171716700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.469µs" Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.171880038Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.171916327Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172073346Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172090986Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172113201Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172159493Z" 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 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172170349Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172425378Z" 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 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172443910Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172455115Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172462791Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 9 23:50:09.172859 containerd[1527]: time="2025-07-09T23:50:09.172544015Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 9 23:50:09.173117 containerd[1527]: time="2025-07-09T23:50:09.172756514Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 9 23:50:09.173117 containerd[1527]: time="2025-07-09T23:50:09.172787220Z" 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 9 23:50:09.173117 containerd[1527]: time="2025-07-09T23:50:09.172797688Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 9 23:50:09.173117 containerd[1527]: time="2025-07-09T23:50:09.172834674Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 9 23:50:09.173117 containerd[1527]: time="2025-07-09T23:50:09.173102265Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 9 23:50:09.173196 containerd[1527]: time="2025-07-09T23:50:09.173172982Z" level=info msg="metadata content store policy set" policy=shared Jul 9 23:50:09.177016 containerd[1527]: time="2025-07-09T23:50:09.176982493Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 9 23:50:09.177080 containerd[1527]: time="2025-07-09T23:50:09.177034329Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 9 23:50:09.177080 containerd[1527]: time="2025-07-09T23:50:09.177049410Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 9 23:50:09.177080 containerd[1527]: time="2025-07-09T23:50:09.177061080Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 9 23:50:09.177080 containerd[1527]: time="2025-07-09T23:50:09.177073797Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 9 23:50:09.177161 containerd[1527]: time="2025-07-09T23:50:09.177097912Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 9 23:50:09.177161 containerd[1527]: time="2025-07-09T23:50:09.177109814Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 9 23:50:09.177161 containerd[1527]: time="2025-07-09T23:50:09.177121794Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 9 23:50:09.177161 containerd[1527]: time="2025-07-09T23:50:09.177144281Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 9 23:50:09.177161 containerd[1527]: time="2025-07-09T23:50:09.177154904Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 9 23:50:09.177255 containerd[1527]: time="2025-07-09T23:50:09.177165023Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 9 23:50:09.177255 containerd[1527]: time="2025-07-09T23:50:09.177178554Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 9 23:50:09.177348 containerd[1527]: time="2025-07-09T23:50:09.177317506Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 9 23:50:09.177348 containerd[1527]: time="2025-07-09T23:50:09.177344722Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 9 23:50:09.177388 containerd[1527]: time="2025-07-09T23:50:09.177372869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 9 23:50:09.177388 containerd[1527]: time="2025-07-09T23:50:09.177385004Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 9 23:50:09.177437 containerd[1527]: time="2025-07-09T23:50:09.177394968Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 9 23:50:09.177437 containerd[1527]: time="2025-07-09T23:50:09.177405087Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 9 23:50:09.177437 containerd[1527]: time="2025-07-09T23:50:09.177429706Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 9 23:50:09.177500 containerd[1527]: time="2025-07-09T23:50:09.177439438Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 9 23:50:09.177500 containerd[1527]: time="2025-07-09T23:50:09.177466267Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 9 23:50:09.177500 containerd[1527]: time="2025-07-09T23:50:09.177480767Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 9 23:50:09.177500 containerd[1527]: time="2025-07-09T23:50:09.177491894Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 9 23:50:09.177924 containerd[1527]: time="2025-07-09T23:50:09.177894754Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 9 23:50:09.177924 containerd[1527]: time="2025-07-09T23:50:09.177915690Z" level=info msg="Start snapshots syncer" Jul 9 23:50:09.177992 containerd[1527]: time="2025-07-09T23:50:09.177949381Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 9 23:50:09.178261 containerd[1527]: time="2025-07-09T23:50:09.178170215Z" 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 9 23:50:09.178261 containerd[1527]: time="2025-07-09T23:50:09.178234574Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 9 23:50:09.178384 containerd[1527]: time="2025-07-09T23:50:09.178328901Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 9 23:50:09.178459 containerd[1527]: time="2025-07-09T23:50:09.178436217Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 9 23:50:09.178487 containerd[1527]: time="2025-07-09T23:50:09.178465566Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 9 23:50:09.178487 containerd[1527]: time="2025-07-09T23:50:09.178476887Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 9 23:50:09.178520 containerd[1527]: time="2025-07-09T23:50:09.178501351Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 9 23:50:09.178520 containerd[1527]: time="2025-07-09T23:50:09.178513408Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 9 23:50:09.178559 containerd[1527]: time="2025-07-09T23:50:09.178532328Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 9 23:50:09.178559 containerd[1527]: time="2025-07-09T23:50:09.178542912Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 9 23:50:09.178591 containerd[1527]: time="2025-07-09T23:50:09.178571990Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 9 23:50:09.178591 containerd[1527]: time="2025-07-09T23:50:09.178583117Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 9 23:50:09.178628 containerd[1527]: time="2025-07-09T23:50:09.178592848Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 9 23:50:09.178662 containerd[1527]: time="2025-07-09T23:50:09.178645924Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 9 23:50:09.178690 containerd[1527]: time="2025-07-09T23:50:09.178664960Z" 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 9 23:50:09.178690 containerd[1527]: time="2025-07-09T23:50:09.178673955Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 9 23:50:09.178690 containerd[1527]: time="2025-07-09T23:50:09.178682872Z" 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 9 23:50:09.178738 containerd[1527]: time="2025-07-09T23:50:09.178690277Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 9 23:50:09.178738 containerd[1527]: time="2025-07-09T23:50:09.178708034Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 9 23:50:09.178738 containerd[1527]: time="2025-07-09T23:50:09.178718308Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 9 23:50:09.178955 containerd[1527]: time="2025-07-09T23:50:09.178910375Z" level=info msg="runtime interface created" Jul 9 23:50:09.178955 containerd[1527]: time="2025-07-09T23:50:09.178918711Z" level=info msg="created NRI interface" Jul 9 23:50:09.178955 containerd[1527]: time="2025-07-09T23:50:09.178927550Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 9 23:50:09.178955 containerd[1527]: time="2025-07-09T23:50:09.178937980Z" level=info msg="Connect containerd service" Jul 9 23:50:09.179048 containerd[1527]: time="2025-07-09T23:50:09.178974811Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 9 23:50:09.179812 containerd[1527]: time="2025-07-09T23:50:09.179784796Z" 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 9 23:50:09.285628 containerd[1527]: time="2025-07-09T23:50:09.285543306Z" level=info msg="Start subscribing containerd event" Jul 9 23:50:09.285748 containerd[1527]: time="2025-07-09T23:50:09.285655429Z" level=info msg="Start recovering state" Jul 9 23:50:09.285769 containerd[1527]: time="2025-07-09T23:50:09.285759682Z" level=info msg="Start event monitor" Jul 9 23:50:09.285786 containerd[1527]: time="2025-07-09T23:50:09.285773949Z" level=info msg="Start cni network conf syncer for default" Jul 9 23:50:09.285804 containerd[1527]: time="2025-07-09T23:50:09.285780928Z" level=info msg="Start streaming server" Jul 9 23:50:09.285804 containerd[1527]: time="2025-07-09T23:50:09.285794109Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 9 23:50:09.285804 containerd[1527]: time="2025-07-09T23:50:09.285802290Z" level=info msg="runtime interface starting up..." Jul 9 23:50:09.285849 containerd[1527]: time="2025-07-09T23:50:09.285808067Z" level=info msg="starting plugins..." Jul 9 23:50:09.285849 containerd[1527]: time="2025-07-09T23:50:09.285823769Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 9 23:50:09.286205 containerd[1527]: time="2025-07-09T23:50:09.286178011Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 9 23:50:09.286734 containerd[1527]: time="2025-07-09T23:50:09.286552104Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 9 23:50:09.287164 containerd[1527]: time="2025-07-09T23:50:09.287084650Z" level=info msg="containerd successfully booted in 0.127106s" Jul 9 23:50:09.287278 systemd[1]: Started containerd.service - containerd container runtime. Jul 9 23:50:09.638944 sshd_keygen[1520]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 9 23:50:09.657731 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 9 23:50:09.660450 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 9 23:50:09.677671 systemd[1]: issuegen.service: Deactivated successfully. Jul 9 23:50:09.677895 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 9 23:50:09.680786 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 9 23:50:09.704323 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 9 23:50:09.707154 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 9 23:50:09.710750 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jul 9 23:50:09.712118 systemd[1]: Reached target getty.target - Login Prompts. Jul 9 23:50:10.115380 systemd-networkd[1444]: eth0: Gained IPv6LL Jul 9 23:50:10.118045 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 9 23:50:10.120195 systemd[1]: Reached target network-online.target - Network is Online. Jul 9 23:50:10.123099 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 9 23:50:10.125867 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 9 23:50:10.135512 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 9 23:50:10.153678 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 9 23:50:10.153925 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 9 23:50:10.159477 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 9 23:50:10.169115 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 9 23:50:10.778128 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 9 23:50:10.779891 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 9 23:50:10.782380 systemd[1]: Startup finished in 2.179s (kernel) + 5.233s (initrd) + 3.972s (userspace) = 11.386s. Jul 9 23:50:10.792699 (kubelet)[1630]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 9 23:50:11.206838 kubelet[1630]: E0709 23:50:11.206718 1630 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 9 23:50:11.209042 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 9 23:50:11.209186 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 9 23:50:11.209527 systemd[1]: kubelet.service: Consumed 821ms CPU time, 256.8M memory peak. Jul 9 23:50:14.733607 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 9 23:50:14.734830 systemd[1]: Started sshd@0-10.0.0.81:22-10.0.0.1:35186.service - OpenSSH per-connection server daemon (10.0.0.1:35186). Jul 9 23:50:14.814783 sshd[1643]: Accepted publickey for core from 10.0.0.1 port 35186 ssh2: RSA SHA256:qx3fDtg3iUWbMGSTv+BT6Vz/B/VbR64D9cV4WxMqUEI Jul 9 23:50:14.816604 sshd-session[1643]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 9 23:50:14.824398 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 9 23:50:14.825517 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 9 23:50:14.831487 systemd-logind[1510]: New session 1 of user core. Jul 9 23:50:14.851281 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 9 23:50:14.853962 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 9 23:50:14.882150 (systemd)[1647]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 9 23:50:14.884368 systemd-logind[1510]: New session c1 of user core. Jul 9 23:50:15.003157 systemd[1647]: Queued start job for default target default.target. Jul 9 23:50:15.022335 systemd[1647]: Created slice app.slice - User Application Slice. Jul 9 23:50:15.022365 systemd[1647]: Reached target paths.target - Paths. Jul 9 23:50:15.022403 systemd[1647]: Reached target timers.target - Timers. Jul 9 23:50:15.023679 systemd[1647]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 9 23:50:15.033906 systemd[1647]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 9 23:50:15.033974 systemd[1647]: Reached target sockets.target - Sockets. Jul 9 23:50:15.034015 systemd[1647]: Reached target basic.target - Basic System. Jul 9 23:50:15.034043 systemd[1647]: Reached target default.target - Main User Target. Jul 9 23:50:15.034076 systemd[1647]: Startup finished in 143ms. Jul 9 23:50:15.034334 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 9 23:50:15.036324 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 9 23:50:15.105999 systemd[1]: Started sshd@1-10.0.0.81:22-10.0.0.1:35198.service - OpenSSH per-connection server daemon (10.0.0.1:35198). Jul 9 23:50:15.172205 sshd[1658]: Accepted publickey for core from 10.0.0.1 port 35198 ssh2: RSA SHA256:qx3fDtg3iUWbMGSTv+BT6Vz/B/VbR64D9cV4WxMqUEI Jul 9 23:50:15.173695 sshd-session[1658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 9 23:50:15.177709 systemd-logind[1510]: New session 2 of user core. Jul 9 23:50:15.189470 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 9 23:50:15.249288 sshd[1660]: Connection closed by 10.0.0.1 port 35198 Jul 9 23:50:15.250034 sshd-session[1658]: pam_unix(sshd:session): session closed for user core Jul 9 23:50:15.260283 systemd[1]: sshd@1-10.0.0.81:22-10.0.0.1:35198.service: Deactivated successfully. Jul 9 23:50:15.262813 systemd[1]: session-2.scope: Deactivated successfully. Jul 9 23:50:15.263804 systemd-logind[1510]: Session 2 logged out. Waiting for processes to exit. Jul 9 23:50:15.267692 systemd[1]: Started sshd@2-10.0.0.81:22-10.0.0.1:35212.service - OpenSSH per-connection server daemon (10.0.0.1:35212). Jul 9 23:50:15.268312 systemd-logind[1510]: Removed session 2. Jul 9 23:50:15.329266 sshd[1666]: Accepted publickey for core from 10.0.0.1 port 35212 ssh2: RSA SHA256:qx3fDtg3iUWbMGSTv+BT6Vz/B/VbR64D9cV4WxMqUEI Jul 9 23:50:15.330635 sshd-session[1666]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 9 23:50:15.337323 systemd-logind[1510]: New session 3 of user core. Jul 9 23:50:15.351468 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 9 23:50:15.401906 sshd[1668]: Connection closed by 10.0.0.1 port 35212 Jul 9 23:50:15.402524 sshd-session[1666]: pam_unix(sshd:session): session closed for user core Jul 9 23:50:15.415418 systemd[1]: sshd@2-10.0.0.81:22-10.0.0.1:35212.service: Deactivated successfully. Jul 9 23:50:15.418909 systemd[1]: session-3.scope: Deactivated successfully. Jul 9 23:50:15.421504 systemd-logind[1510]: Session 3 logged out. Waiting for processes to exit. Jul 9 23:50:15.427552 systemd[1]: Started sshd@3-10.0.0.81:22-10.0.0.1:35216.service - OpenSSH per-connection server daemon (10.0.0.1:35216). Jul 9 23:50:15.428588 systemd-logind[1510]: Removed session 3. Jul 9 23:50:15.488952 sshd[1674]: Accepted publickey for core from 10.0.0.1 port 35216 ssh2: RSA SHA256:qx3fDtg3iUWbMGSTv+BT6Vz/B/VbR64D9cV4WxMqUEI Jul 9 23:50:15.492923 sshd-session[1674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 9 23:50:15.498296 systemd-logind[1510]: New session 4 of user core. Jul 9 23:50:15.505473 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 9 23:50:15.557366 sshd[1676]: Connection closed by 10.0.0.1 port 35216 Jul 9 23:50:15.557785 sshd-session[1674]: pam_unix(sshd:session): session closed for user core Jul 9 23:50:15.570980 systemd[1]: sshd@3-10.0.0.81:22-10.0.0.1:35216.service: Deactivated successfully. Jul 9 23:50:15.572445 systemd[1]: session-4.scope: Deactivated successfully. Jul 9 23:50:15.573139 systemd-logind[1510]: Session 4 logged out. Waiting for processes to exit. Jul 9 23:50:15.575529 systemd[1]: Started sshd@4-10.0.0.81:22-10.0.0.1:35226.service - OpenSSH per-connection server daemon (10.0.0.1:35226). Jul 9 23:50:15.576193 systemd-logind[1510]: Removed session 4. Jul 9 23:50:15.634955 sshd[1682]: Accepted publickey for core from 10.0.0.1 port 35226 ssh2: RSA SHA256:qx3fDtg3iUWbMGSTv+BT6Vz/B/VbR64D9cV4WxMqUEI Jul 9 23:50:15.637135 sshd-session[1682]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 9 23:50:15.644782 systemd-logind[1510]: New session 5 of user core. Jul 9 23:50:15.658523 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 9 23:50:15.724799 sudo[1685]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 9 23:50:15.725080 sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 9 23:50:15.740443 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 9 23:50:15.789508 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 9 23:50:15.789725 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 9 23:50:16.338296 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 9 23:50:16.338636 systemd[1]: kubelet.service: Consumed 821ms CPU time, 256.8M memory peak. Jul 9 23:50:16.343755 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 9 23:50:16.371181 systemd[1]: Reload requested from client PID 1728 ('systemctl') (unit session-5.scope)... Jul 9 23:50:16.371203 systemd[1]: Reloading... Jul 9 23:50:16.469277 zram_generator::config[1776]: No configuration found. Jul 9 23:50:16.677202 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 9 23:50:16.770300 systemd[1]: Reloading finished in 398 ms. Jul 9 23:50:16.848953 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jul 9 23:50:16.849037 systemd[1]: kubelet.service: Failed with result 'signal'. Jul 9 23:50:16.850331 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 9 23:50:16.850387 systemd[1]: kubelet.service: Consumed 98ms CPU time, 95.1M memory peak. Jul 9 23:50:16.851971 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 9 23:50:17.016534 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 9 23:50:17.024210 (kubelet)[1815]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 9 23:50:17.073370 kubelet[1815]: 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 9 23:50:17.073370 kubelet[1815]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Jul 9 23:50:17.073370 kubelet[1815]: 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 9 23:50:17.073673 kubelet[1815]: I0709 23:50:17.073364 1815 server.go:215] "--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 9 23:50:17.743292 kubelet[1815]: I0709 23:50:17.743044 1815 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Jul 9 23:50:17.743292 kubelet[1815]: I0709 23:50:17.743082 1815 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 9 23:50:17.743441 kubelet[1815]: I0709 23:50:17.743380 1815 server.go:954] "Client rotation is on, will bootstrap in background" Jul 9 23:50:17.795038 kubelet[1815]: I0709 23:50:17.794915 1815 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 9 23:50:17.803958 kubelet[1815]: I0709 23:50:17.803926 1815 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Jul 9 23:50:17.806834 kubelet[1815]: I0709 23:50:17.806784 1815 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 9 23:50:17.808026 kubelet[1815]: I0709 23:50:17.807966 1815 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 9 23:50:17.808219 kubelet[1815]: I0709 23:50:17.808020 1815 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.81","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 9 23:50:17.808332 kubelet[1815]: I0709 23:50:17.808303 1815 topology_manager.go:138] "Creating topology manager with none policy" Jul 9 23:50:17.808332 kubelet[1815]: I0709 23:50:17.808314 1815 container_manager_linux.go:304] "Creating device plugin manager" Jul 9 23:50:17.808541 kubelet[1815]: I0709 23:50:17.808509 1815 state_mem.go:36] "Initialized new in-memory state store" Jul 9 23:50:17.812428 kubelet[1815]: I0709 23:50:17.812394 1815 kubelet.go:446] "Attempting to sync node with API server" Jul 9 23:50:17.812428 kubelet[1815]: I0709 23:50:17.812430 1815 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 9 23:50:17.812479 kubelet[1815]: I0709 23:50:17.812454 1815 kubelet.go:352] "Adding apiserver pod source" Jul 9 23:50:17.812479 kubelet[1815]: I0709 23:50:17.812464 1815 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 9 23:50:17.812947 kubelet[1815]: E0709 23:50:17.812798 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:17.812947 kubelet[1815]: E0709 23:50:17.812852 1815 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:17.819897 kubelet[1815]: I0709 23:50:17.819869 1815 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Jul 9 23:50:17.820722 kubelet[1815]: W0709 23:50:17.820661 1815 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.81" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Jul 9 23:50:17.820722 kubelet[1815]: I0709 23:50:17.820701 1815 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 9 23:50:17.820809 kubelet[1815]: W0709 23:50:17.820716 1815 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Jul 9 23:50:17.820867 kubelet[1815]: E0709 23:50:17.820699 1815 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.81\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 9 23:50:17.820977 kubelet[1815]: E0709 23:50:17.820889 1815 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Jul 9 23:50:17.822973 kubelet[1815]: W0709 23:50:17.822949 1815 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 9 23:50:17.826476 kubelet[1815]: I0709 23:50:17.826434 1815 watchdog_linux.go:99] "Systemd watchdog is not enabled" Jul 9 23:50:17.826476 kubelet[1815]: I0709 23:50:17.826483 1815 server.go:1287] "Started kubelet" Jul 9 23:50:17.826801 kubelet[1815]: I0709 23:50:17.826750 1815 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Jul 9 23:50:17.826896 kubelet[1815]: I0709 23:50:17.826817 1815 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 9 23:50:17.827775 kubelet[1815]: I0709 23:50:17.827749 1815 server.go:479] "Adding debug handlers to kubelet server" Jul 9 23:50:17.827900 kubelet[1815]: I0709 23:50:17.827877 1815 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 9 23:50:17.829553 kubelet[1815]: I0709 23:50:17.829513 1815 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 9 23:50:17.830007 kubelet[1815]: I0709 23:50:17.829976 1815 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 9 23:50:17.831750 kubelet[1815]: E0709 23:50:17.831571 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:17.831750 kubelet[1815]: I0709 23:50:17.831608 1815 volume_manager.go:297] "Starting Kubelet Volume Manager" Jul 9 23:50:17.831854 kubelet[1815]: I0709 23:50:17.831779 1815 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Jul 9 23:50:17.831854 kubelet[1815]: I0709 23:50:17.831840 1815 reconciler.go:26] "Reconciler: start to sync state" Jul 9 23:50:17.832459 kubelet[1815]: E0709 23:50:17.832434 1815 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 9 23:50:17.833013 kubelet[1815]: I0709 23:50:17.832962 1815 factory.go:221] Registration of the systemd container factory successfully Jul 9 23:50:17.833124 kubelet[1815]: I0709 23:50:17.833103 1815 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 9 23:50:17.834346 kubelet[1815]: I0709 23:50:17.834099 1815 factory.go:221] Registration of the containerd container factory successfully Jul 9 23:50:17.845988 kubelet[1815]: I0709 23:50:17.845953 1815 cpu_manager.go:221] "Starting CPU manager" policy="none" Jul 9 23:50:17.845988 kubelet[1815]: I0709 23:50:17.845976 1815 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Jul 9 23:50:17.846106 kubelet[1815]: I0709 23:50:17.846003 1815 state_mem.go:36] "Initialized new in-memory state store" Jul 9 23:50:17.846106 kubelet[1815]: E0709 23:50:17.846035 1815 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.81\" not found" node="10.0.0.81" Jul 9 23:50:17.929967 kubelet[1815]: I0709 23:50:17.929927 1815 policy_none.go:49] "None policy: Start" Jul 9 23:50:17.930051 kubelet[1815]: I0709 23:50:17.929975 1815 memory_manager.go:186] "Starting memorymanager" policy="None" Jul 9 23:50:17.930051 kubelet[1815]: I0709 23:50:17.929990 1815 state_mem.go:35] "Initializing new in-memory state store" Jul 9 23:50:17.932428 kubelet[1815]: E0709 23:50:17.932391 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:17.938874 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 9 23:50:17.949550 kubelet[1815]: I0709 23:50:17.949491 1815 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 9 23:50:17.950572 kubelet[1815]: I0709 23:50:17.950540 1815 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 9 23:50:17.950572 kubelet[1815]: I0709 23:50:17.950572 1815 status_manager.go:227] "Starting to sync pod status with apiserver" Jul 9 23:50:17.950670 kubelet[1815]: I0709 23:50:17.950593 1815 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Jul 9 23:50:17.950670 kubelet[1815]: I0709 23:50:17.950600 1815 kubelet.go:2382] "Starting kubelet main sync loop" Jul 9 23:50:17.954418 kubelet[1815]: E0709 23:50:17.954379 1815 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jul 9 23:50:17.956485 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 9 23:50:17.978974 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 9 23:50:17.981081 kubelet[1815]: I0709 23:50:17.981046 1815 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 9 23:50:17.982054 kubelet[1815]: I0709 23:50:17.981269 1815 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 9 23:50:17.982054 kubelet[1815]: I0709 23:50:17.981293 1815 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 9 23:50:17.982054 kubelet[1815]: I0709 23:50:17.981834 1815 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 9 23:50:17.982535 kubelet[1815]: E0709 23:50:17.982514 1815 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Jul 9 23:50:17.982581 kubelet[1815]: E0709 23:50:17.982567 1815 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.81\" not found" Jul 9 23:50:18.083090 kubelet[1815]: I0709 23:50:18.082975 1815 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.81" Jul 9 23:50:18.090266 kubelet[1815]: I0709 23:50:18.090222 1815 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.81" Jul 9 23:50:18.090544 kubelet[1815]: E0709 23:50:18.090405 1815 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.81\": node \"10.0.0.81\" not found" Jul 9 23:50:18.106151 kubelet[1815]: E0709 23:50:18.106098 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.206509 kubelet[1815]: E0709 23:50:18.206459 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.307033 kubelet[1815]: E0709 23:50:18.306991 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.407899 kubelet[1815]: E0709 23:50:18.407752 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.508355 kubelet[1815]: E0709 23:50:18.508293 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.540519 sudo[1685]: pam_unix(sudo:session): session closed for user root Jul 9 23:50:18.542156 sshd[1684]: Connection closed by 10.0.0.1 port 35226 Jul 9 23:50:18.542513 sshd-session[1682]: pam_unix(sshd:session): session closed for user core Jul 9 23:50:18.545909 systemd[1]: sshd@4-10.0.0.81:22-10.0.0.1:35226.service: Deactivated successfully. Jul 9 23:50:18.547740 systemd[1]: session-5.scope: Deactivated successfully. Jul 9 23:50:18.547994 systemd[1]: session-5.scope: Consumed 492ms CPU time, 72.7M memory peak. Jul 9 23:50:18.549091 systemd-logind[1510]: Session 5 logged out. Waiting for processes to exit. Jul 9 23:50:18.550619 systemd-logind[1510]: Removed session 5. Jul 9 23:50:18.608935 kubelet[1815]: E0709 23:50:18.608878 1815 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.81\" not found" Jul 9 23:50:18.710083 kubelet[1815]: I0709 23:50:18.710030 1815 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 9 23:50:18.710477 containerd[1527]: time="2025-07-09T23:50:18.710443471Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 9 23:50:18.711021 kubelet[1815]: I0709 23:50:18.710998 1815 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 9 23:50:18.746074 kubelet[1815]: I0709 23:50:18.746033 1815 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 9 23:50:18.746321 kubelet[1815]: W0709 23:50:18.746195 1815 reflector.go:492] 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 9 23:50:18.746321 kubelet[1815]: W0709 23:50:18.746208 1815 reflector.go:492] 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 9 23:50:18.746321 kubelet[1815]: W0709 23:50:18.746279 1815 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Node ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 9 23:50:18.812971 kubelet[1815]: I0709 23:50:18.812946 1815 apiserver.go:52] "Watching apiserver" Jul 9 23:50:18.813021 kubelet[1815]: E0709 23:50:18.812986 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:18.832455 kubelet[1815]: I0709 23:50:18.832269 1815 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Jul 9 23:50:18.836099 systemd[1]: Created slice kubepods-besteffort-pod6d5ac5cc_8a49_4649_a063_ff67f118b9e3.slice - libcontainer container kubepods-besteffort-pod6d5ac5cc_8a49_4649_a063_ff67f118b9e3.slice. Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837282 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/df1d97f3-40c9-4393-9854-54f2627b5401-cni-plugin\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837321 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/6d5ac5cc-8a49-4649-a063-ff67f118b9e3-kube-proxy\") pod \"kube-proxy-8bn6x\" (UID: \"6d5ac5cc-8a49-4649-a063-ff67f118b9e3\") " pod="kube-system/kube-proxy-8bn6x" Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837339 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6d5ac5cc-8a49-4649-a063-ff67f118b9e3-xtables-lock\") pod \"kube-proxy-8bn6x\" (UID: \"6d5ac5cc-8a49-4649-a063-ff67f118b9e3\") " pod="kube-system/kube-proxy-8bn6x" Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837356 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/6d5ac5cc-8a49-4649-a063-ff67f118b9e3-lib-modules\") pod \"kube-proxy-8bn6x\" (UID: \"6d5ac5cc-8a49-4649-a063-ff67f118b9e3\") " pod="kube-system/kube-proxy-8bn6x" Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837374 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/df1d97f3-40c9-4393-9854-54f2627b5401-run\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837423 kubelet[1815]: I0709 23:50:18.837402 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/df1d97f3-40c9-4393-9854-54f2627b5401-cni\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837590 kubelet[1815]: I0709 23:50:18.837439 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/df1d97f3-40c9-4393-9854-54f2627b5401-flannel-cfg\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837590 kubelet[1815]: I0709 23:50:18.837475 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/df1d97f3-40c9-4393-9854-54f2627b5401-xtables-lock\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837590 kubelet[1815]: I0709 23:50:18.837496 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jqtnj\" (UniqueName: \"kubernetes.io/projected/df1d97f3-40c9-4393-9854-54f2627b5401-kube-api-access-jqtnj\") pod \"kube-flannel-ds-j7xjl\" (UID: \"df1d97f3-40c9-4393-9854-54f2627b5401\") " pod="kube-flannel/kube-flannel-ds-j7xjl" Jul 9 23:50:18.837590 kubelet[1815]: I0709 23:50:18.837516 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rfkgw\" (UniqueName: \"kubernetes.io/projected/6d5ac5cc-8a49-4649-a063-ff67f118b9e3-kube-api-access-rfkgw\") pod \"kube-proxy-8bn6x\" (UID: \"6d5ac5cc-8a49-4649-a063-ff67f118b9e3\") " pod="kube-system/kube-proxy-8bn6x" Jul 9 23:50:18.858153 systemd[1]: Created slice kubepods-burstable-poddf1d97f3_40c9_4393_9854_54f2627b5401.slice - libcontainer container kubepods-burstable-poddf1d97f3_40c9_4393_9854_54f2627b5401.slice. Jul 9 23:50:19.156417 containerd[1527]: time="2025-07-09T23:50:19.156305218Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8bn6x,Uid:6d5ac5cc-8a49-4649-a063-ff67f118b9e3,Namespace:kube-system,Attempt:0,}" Jul 9 23:50:19.179163 containerd[1527]: time="2025-07-09T23:50:19.178839533Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-j7xjl,Uid:df1d97f3-40c9-4393-9854-54f2627b5401,Namespace:kube-flannel,Attempt:0,}" Jul 9 23:50:19.713392 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount812026671.mount: Deactivated successfully. Jul 9 23:50:19.720507 containerd[1527]: time="2025-07-09T23:50:19.720450714Z" 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 9 23:50:19.721857 containerd[1527]: time="2025-07-09T23:50:19.721809355Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Jul 9 23:50:19.723808 containerd[1527]: time="2025-07-09T23:50:19.723770204Z" 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 9 23:50:19.725033 containerd[1527]: time="2025-07-09T23:50:19.724998071Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 9 23:50:19.725768 containerd[1527]: time="2025-07-09T23:50:19.725725618Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Jul 9 23:50:19.727390 containerd[1527]: time="2025-07-09T23:50:19.727351360Z" 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 9 23:50:19.729782 containerd[1527]: time="2025-07-09T23:50:19.729640016Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 512.598536ms" Jul 9 23:50:19.731535 containerd[1527]: time="2025-07-09T23:50:19.731284446Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 564.451322ms" Jul 9 23:50:19.751885 containerd[1527]: time="2025-07-09T23:50:19.751837558Z" level=info msg="connecting to shim d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54" address="unix:///run/containerd/s/4033a8cedb11b5dedb4689ee573b57e1bda31c3d1c0cfde110a42003e7c56a14" namespace=k8s.io protocol=ttrpc version=3 Jul 9 23:50:19.752380 containerd[1527]: time="2025-07-09T23:50:19.752343869Z" level=info msg="connecting to shim 0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c" address="unix:///run/containerd/s/0bffcf559a3be5a81df7533faa3036a83d1be172024076bc3e01c29c2915e315" namespace=k8s.io protocol=ttrpc version=3 Jul 9 23:50:19.778410 systemd[1]: Started cri-containerd-d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54.scope - libcontainer container d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54. Jul 9 23:50:19.781756 systemd[1]: Started cri-containerd-0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c.scope - libcontainer container 0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c. Jul 9 23:50:19.813199 containerd[1527]: time="2025-07-09T23:50:19.813109452Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8bn6x,Uid:6d5ac5cc-8a49-4649-a063-ff67f118b9e3,Namespace:kube-system,Attempt:0,} returns sandbox id \"0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c\"" Jul 9 23:50:19.813595 kubelet[1815]: E0709 23:50:19.813488 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:19.815857 containerd[1527]: time="2025-07-09T23:50:19.815788803Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\"" Jul 9 23:50:19.818363 containerd[1527]: time="2025-07-09T23:50:19.818326389Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-j7xjl,Uid:df1d97f3-40c9-4393-9854-54f2627b5401,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\"" Jul 9 23:50:20.751356 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1818530870.mount: Deactivated successfully. Jul 9 23:50:20.814220 kubelet[1815]: E0709 23:50:20.814182 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:20.975011 containerd[1527]: time="2025-07-09T23:50:20.974957389Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.6\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:20.976373 containerd[1527]: time="2025-07-09T23:50:20.976341006Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.6: active requests=0, bytes read=27378408" Jul 9 23:50:20.976853 containerd[1527]: time="2025-07-09T23:50:20.976818443Z" level=info msg="ImageCreate event name:\"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:20.979354 containerd[1527]: time="2025-07-09T23:50:20.979294155Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:b13d9da413b983d130bf090b83fce12e1ccc704e95f366da743c18e964d9d7e9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:20.979817 containerd[1527]: time="2025-07-09T23:50:20.979780171Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.6\" with image id \"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\", repo tag \"registry.k8s.io/kube-proxy:v1.32.6\", repo digest \"registry.k8s.io/kube-proxy@sha256:b13d9da413b983d130bf090b83fce12e1ccc704e95f366da743c18e964d9d7e9\", size \"27377425\" in 1.16395311s" Jul 9 23:50:20.979888 containerd[1527]: time="2025-07-09T23:50:20.979817147Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\" returns image reference \"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\"" Jul 9 23:50:20.981522 containerd[1527]: time="2025-07-09T23:50:20.981455310Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 9 23:50:20.983210 containerd[1527]: time="2025-07-09T23:50:20.982724026Z" level=info msg="CreateContainer within sandbox \"0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 9 23:50:20.993267 containerd[1527]: time="2025-07-09T23:50:20.992981071Z" level=info msg="Container 67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:21.000102 containerd[1527]: time="2025-07-09T23:50:21.000044760Z" level=info msg="CreateContainer within sandbox \"0485353dee3090837dfb46caf8134332a1eb53983cf92403cd5dbcf7ed4ad45c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98\"" Jul 9 23:50:21.000794 containerd[1527]: time="2025-07-09T23:50:21.000754897Z" level=info msg="StartContainer for \"67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98\"" Jul 9 23:50:21.002195 containerd[1527]: time="2025-07-09T23:50:21.002104488Z" level=info msg="connecting to shim 67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98" address="unix:///run/containerd/s/0bffcf559a3be5a81df7533faa3036a83d1be172024076bc3e01c29c2915e315" protocol=ttrpc version=3 Jul 9 23:50:21.023485 systemd[1]: Started cri-containerd-67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98.scope - libcontainer container 67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98. Jul 9 23:50:21.062732 containerd[1527]: time="2025-07-09T23:50:21.061103267Z" level=info msg="StartContainer for \"67788b3e0322ab1e821e60b17c94d5f01e6096313b173053811ffe2f96e18f98\" returns successfully" Jul 9 23:50:21.815392 kubelet[1815]: E0709 23:50:21.815336 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:21.973054 kubelet[1815]: I0709 23:50:21.972992 1815 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-8bn6x" podStartSLOduration=2.807302603 podStartE2EDuration="3.972976965s" podCreationTimestamp="2025-07-09 23:50:18 +0000 UTC" firstStartedPulling="2025-07-09 23:50:19.815350854 +0000 UTC m=+2.787157389" lastFinishedPulling="2025-07-09 23:50:20.981025176 +0000 UTC m=+3.952831751" observedRunningTime="2025-07-09 23:50:21.972937889 +0000 UTC m=+4.944744463" watchObservedRunningTime="2025-07-09 23:50:21.972976965 +0000 UTC m=+4.944783540" Jul 9 23:50:22.059403 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount786144257.mount: Deactivated successfully. Jul 9 23:50:22.087314 containerd[1527]: time="2025-07-09T23:50:22.087185764Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:22.088290 containerd[1527]: time="2025-07-09T23:50:22.088260070Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Jul 9 23:50:22.089184 containerd[1527]: time="2025-07-09T23:50:22.089151136Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:22.091275 containerd[1527]: time="2025-07-09T23:50:22.090889305Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:22.091783 containerd[1527]: time="2025-07-09T23:50:22.091758012Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.110270001s" Jul 9 23:50:22.091819 containerd[1527]: time="2025-07-09T23:50:22.091790475Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 9 23:50:22.094012 containerd[1527]: time="2025-07-09T23:50:22.093645090Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 9 23:50:22.103429 containerd[1527]: time="2025-07-09T23:50:22.103396122Z" level=info msg="Container 13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:22.109459 containerd[1527]: time="2025-07-09T23:50:22.109417714Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\"" Jul 9 23:50:22.109956 containerd[1527]: time="2025-07-09T23:50:22.109936408Z" level=info msg="StartContainer for \"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\"" Jul 9 23:50:22.110883 containerd[1527]: time="2025-07-09T23:50:22.110849552Z" level=info msg="connecting to shim 13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e" address="unix:///run/containerd/s/4033a8cedb11b5dedb4689ee573b57e1bda31c3d1c0cfde110a42003e7c56a14" protocol=ttrpc version=3 Jul 9 23:50:22.130418 systemd[1]: Started cri-containerd-13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e.scope - libcontainer container 13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e. Jul 9 23:50:22.161980 containerd[1527]: time="2025-07-09T23:50:22.161936884Z" level=info msg="StartContainer for \"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\" returns successfully" Jul 9 23:50:22.164671 systemd[1]: cri-containerd-13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e.scope: Deactivated successfully. Jul 9 23:50:22.166722 containerd[1527]: time="2025-07-09T23:50:22.166677456Z" level=info msg="received exit event container_id:\"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\" id:\"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\" pid:2145 exited_at:{seconds:1752105022 nanos:166317258}" Jul 9 23:50:22.166819 containerd[1527]: time="2025-07-09T23:50:22.166687640Z" level=info msg="TaskExit event in podsandbox handler container_id:\"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\" id:\"13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e\" pid:2145 exited_at:{seconds:1752105022 nanos:166317258}" Jul 9 23:50:22.817374 kubelet[1815]: E0709 23:50:22.817318 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:22.968598 containerd[1527]: time="2025-07-09T23:50:22.968519282Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 9 23:50:23.001111 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-13a85805854526ccbd83dcd6088c6472d733688d2c11be141c36a4419bdf1d4e-rootfs.mount: Deactivated successfully. Jul 9 23:50:23.818160 kubelet[1815]: E0709 23:50:23.818110 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:24.180383 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3264883726.mount: Deactivated successfully. Jul 9 23:50:24.706907 containerd[1527]: time="2025-07-09T23:50:24.706710677Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:24.707655 containerd[1527]: time="2025-07-09T23:50:24.707575673Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Jul 9 23:50:24.708434 containerd[1527]: time="2025-07-09T23:50:24.708405337Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:24.711486 containerd[1527]: time="2025-07-09T23:50:24.711445949Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:24.712906 containerd[1527]: time="2025-07-09T23:50:24.712858663Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.744282164s" Jul 9 23:50:24.712906 containerd[1527]: time="2025-07-09T23:50:24.712894753Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 9 23:50:24.715024 containerd[1527]: time="2025-07-09T23:50:24.714988311Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 9 23:50:24.723930 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2253284359.mount: Deactivated successfully. Jul 9 23:50:24.726573 containerd[1527]: time="2025-07-09T23:50:24.726513375Z" level=info msg="Container 99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:24.733036 containerd[1527]: time="2025-07-09T23:50:24.732974219Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\"" Jul 9 23:50:24.733806 containerd[1527]: time="2025-07-09T23:50:24.733508632Z" level=info msg="StartContainer for \"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\"" Jul 9 23:50:24.734359 containerd[1527]: time="2025-07-09T23:50:24.734331205Z" level=info msg="connecting to shim 99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486" address="unix:///run/containerd/s/4033a8cedb11b5dedb4689ee573b57e1bda31c3d1c0cfde110a42003e7c56a14" protocol=ttrpc version=3 Jul 9 23:50:24.759440 systemd[1]: Started cri-containerd-99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486.scope - libcontainer container 99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486. Jul 9 23:50:24.783645 containerd[1527]: time="2025-07-09T23:50:24.783613686Z" level=info msg="StartContainer for \"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\" returns successfully" Jul 9 23:50:24.789971 systemd[1]: cri-containerd-99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486.scope: Deactivated successfully. Jul 9 23:50:24.792007 containerd[1527]: time="2025-07-09T23:50:24.791885186Z" level=info msg="received exit event container_id:\"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\" id:\"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\" pid:2220 exited_at:{seconds:1752105024 nanos:791076634}" Jul 9 23:50:24.792007 containerd[1527]: time="2025-07-09T23:50:24.791974693Z" level=info msg="TaskExit event in podsandbox handler container_id:\"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\" id:\"99ad3857c9a1c9e1ae1e34d4caad14c7c18c699b4e54eb47439bfe129252d486\" pid:2220 exited_at:{seconds:1752105024 nanos:791076634}" Jul 9 23:50:24.818947 kubelet[1815]: E0709 23:50:24.818894 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:24.831540 kubelet[1815]: I0709 23:50:24.831507 1815 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Jul 9 23:50:24.974167 containerd[1527]: time="2025-07-09T23:50:24.974063998Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 9 23:50:24.981511 containerd[1527]: time="2025-07-09T23:50:24.981463212Z" level=info msg="Container 6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:24.988727 containerd[1527]: time="2025-07-09T23:50:24.988687395Z" level=info msg="CreateContainer within sandbox \"d9c6c93a4e2d03972cf02ca56a65fd13bc72da463a2c59a35b84d7a4de1a6b54\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f\"" Jul 9 23:50:24.989177 containerd[1527]: time="2025-07-09T23:50:24.989138237Z" level=info msg="StartContainer for \"6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f\"" Jul 9 23:50:24.990008 containerd[1527]: time="2025-07-09T23:50:24.989970011Z" level=info msg="connecting to shim 6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f" address="unix:///run/containerd/s/4033a8cedb11b5dedb4689ee573b57e1bda31c3d1c0cfde110a42003e7c56a14" protocol=ttrpc version=3 Jul 9 23:50:25.007378 systemd[1]: Started cri-containerd-6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f.scope - libcontainer container 6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f. Jul 9 23:50:25.031922 containerd[1527]: time="2025-07-09T23:50:25.031887832Z" level=info msg="StartContainer for \"6f1207bbe50ea725f35f15599615280242c4f484d431b1861d18b461d63c3e2f\" returns successfully" Jul 9 23:50:25.109668 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1711929573.mount: Deactivated successfully. Jul 9 23:50:25.819325 kubelet[1815]: E0709 23:50:25.819275 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:25.990278 kubelet[1815]: I0709 23:50:25.990032 1815 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-j7xjl" podStartSLOduration=3.095968294 podStartE2EDuration="7.990016373s" podCreationTimestamp="2025-07-09 23:50:18 +0000 UTC" firstStartedPulling="2025-07-09 23:50:19.8195366 +0000 UTC m=+2.791343174" lastFinishedPulling="2025-07-09 23:50:24.713584718 +0000 UTC m=+7.685391253" observedRunningTime="2025-07-09 23:50:25.989762976 +0000 UTC m=+8.961569551" watchObservedRunningTime="2025-07-09 23:50:25.990016373 +0000 UTC m=+8.961822947" Jul 9 23:50:26.113187 systemd-networkd[1444]: flannel.1: Link UP Jul 9 23:50:26.113197 systemd-networkd[1444]: flannel.1: Gained carrier Jul 9 23:50:26.821854 kubelet[1815]: E0709 23:50:26.821801 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:27.331386 systemd-networkd[1444]: flannel.1: Gained IPv6LL Jul 9 23:50:27.822113 kubelet[1815]: E0709 23:50:27.822063 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:28.822861 kubelet[1815]: E0709 23:50:28.822814 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:29.448969 systemd[1]: Created slice kubepods-besteffort-pod05fa0051_92e3_4193_9330_65db71654d9a.slice - libcontainer container kubepods-besteffort-pod05fa0051_92e3_4193_9330_65db71654d9a.slice. Jul 9 23:50:29.501109 kubelet[1815]: I0709 23:50:29.501065 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qdpc6\" (UniqueName: \"kubernetes.io/projected/05fa0051-92e3-4193-9330-65db71654d9a-kube-api-access-qdpc6\") pod \"nginx-deployment-7fcdb87857-klxql\" (UID: \"05fa0051-92e3-4193-9330-65db71654d9a\") " pod="default/nginx-deployment-7fcdb87857-klxql" Jul 9 23:50:29.752339 containerd[1527]: time="2025-07-09T23:50:29.752278688Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-klxql,Uid:05fa0051-92e3-4193-9330-65db71654d9a,Namespace:default,Attempt:0,}" Jul 9 23:50:29.781966 systemd-networkd[1444]: cni0: Link UP Jul 9 23:50:29.781977 systemd-networkd[1444]: cni0: Gained carrier Jul 9 23:50:29.786068 systemd-networkd[1444]: cni0: Lost carrier Jul 9 23:50:29.791499 systemd-networkd[1444]: vethcf8f4cf6: Link UP Jul 9 23:50:29.793826 kernel: cni0: port 1(vethcf8f4cf6) entered blocking state Jul 9 23:50:29.793922 kernel: cni0: port 1(vethcf8f4cf6) entered disabled state Jul 9 23:50:29.793939 kernel: vethcf8f4cf6: entered allmulticast mode Jul 9 23:50:29.793965 kernel: vethcf8f4cf6: entered promiscuous mode Jul 9 23:50:29.808189 kernel: cni0: port 1(vethcf8f4cf6) entered blocking state Jul 9 23:50:29.808298 kernel: cni0: port 1(vethcf8f4cf6) entered forwarding state Jul 9 23:50:29.808485 systemd-networkd[1444]: vethcf8f4cf6: Gained carrier Jul 9 23:50:29.809365 systemd-networkd[1444]: cni0: Gained carrier Jul 9 23:50:29.810916 containerd[1527]: 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)(0x4000016938), "name":"cbr0", "type":"bridge"} Jul 9 23:50:29.810916 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Jul 9 23:50:29.823524 kubelet[1815]: E0709 23:50:29.823468 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:29.848766 containerd[1527]: {"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-09T23:50:29.848697950Z" level=info msg="connecting to shim 0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3" address="unix:///run/containerd/s/33d9cc841c761c56972164b44285200be1018d721ba959fa654858a427f366a0" namespace=k8s.io protocol=ttrpc version=3 Jul 9 23:50:29.872522 systemd[1]: Started cri-containerd-0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3.scope - libcontainer container 0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3. Jul 9 23:50:29.883593 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 9 23:50:29.907036 containerd[1527]: time="2025-07-09T23:50:29.906994232Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-klxql,Uid:05fa0051-92e3-4193-9330-65db71654d9a,Namespace:default,Attempt:0,} returns sandbox id \"0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3\"" Jul 9 23:50:29.908153 containerd[1527]: time="2025-07-09T23:50:29.908118753Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 9 23:50:30.823638 kubelet[1815]: E0709 23:50:30.823581 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:30.915360 systemd-networkd[1444]: cni0: Gained IPv6LL Jul 9 23:50:31.591216 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3940085404.mount: Deactivated successfully. Jul 9 23:50:31.619364 systemd-networkd[1444]: vethcf8f4cf6: Gained IPv6LL Jul 9 23:50:31.824767 kubelet[1815]: E0709 23:50:31.823969 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:32.391170 containerd[1527]: time="2025-07-09T23:50:32.391123792Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:32.391570 containerd[1527]: time="2025-07-09T23:50:32.391536642Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69964585" Jul 9 23:50:32.395778 containerd[1527]: time="2025-07-09T23:50:32.395735848Z" level=info msg="ImageCreate event name:\"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:32.399118 containerd[1527]: time="2025-07-09T23:50:32.399084307Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:32.400076 containerd[1527]: time="2025-07-09T23:50:32.400047652Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\", size \"69964463\" in 2.491894286s" Jul 9 23:50:32.400159 containerd[1527]: time="2025-07-09T23:50:32.400081843Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 9 23:50:32.402216 containerd[1527]: time="2025-07-09T23:50:32.402184481Z" level=info msg="CreateContainer within sandbox \"0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 9 23:50:32.412462 containerd[1527]: time="2025-07-09T23:50:32.412420308Z" level=info msg="Container a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:32.412878 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3521179424.mount: Deactivated successfully. Jul 9 23:50:32.418188 containerd[1527]: time="2025-07-09T23:50:32.418069124Z" level=info msg="CreateContainer within sandbox \"0fef5ed8324a1d6f079b5e26a8fc524654952101fecbfce17c7536b61d4147c3\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c\"" Jul 9 23:50:32.420050 containerd[1527]: time="2025-07-09T23:50:32.418736611Z" level=info msg="StartContainer for \"a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c\"" Jul 9 23:50:32.420900 containerd[1527]: time="2025-07-09T23:50:32.420870285Z" level=info msg="connecting to shim a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c" address="unix:///run/containerd/s/33d9cc841c761c56972164b44285200be1018d721ba959fa654858a427f366a0" protocol=ttrpc version=3 Jul 9 23:50:32.442401 systemd[1]: Started cri-containerd-a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c.scope - libcontainer container a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c. Jul 9 23:50:32.473686 containerd[1527]: time="2025-07-09T23:50:32.473648817Z" level=info msg="StartContainer for \"a70c939072acda5e5b8fe013768db3e962080e7b8c6ec56f63ef2dd66d3eb89c\" returns successfully" Jul 9 23:50:32.824578 kubelet[1815]: E0709 23:50:32.824527 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:33.002954 kubelet[1815]: I0709 23:50:33.002775 1815 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-klxql" podStartSLOduration=1.5096799810000001 podStartE2EDuration="4.002751196s" podCreationTimestamp="2025-07-09 23:50:29 +0000 UTC" firstStartedPulling="2025-07-09 23:50:29.907872558 +0000 UTC m=+12.879679133" lastFinishedPulling="2025-07-09 23:50:32.400943773 +0000 UTC m=+15.372750348" observedRunningTime="2025-07-09 23:50:33.002303156 +0000 UTC m=+15.974109731" watchObservedRunningTime="2025-07-09 23:50:33.002751196 +0000 UTC m=+15.974557771" Jul 9 23:50:33.825573 kubelet[1815]: E0709 23:50:33.825522 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:34.826560 kubelet[1815]: E0709 23:50:34.826496 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:35.826647 kubelet[1815]: E0709 23:50:35.826588 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:36.000780 systemd[1]: Created slice kubepods-besteffort-podebcd42ae_38e9_48b3_836a_1232813c118e.slice - libcontainer container kubepods-besteffort-podebcd42ae_38e9_48b3_836a_1232813c118e.slice. Jul 9 23:50:36.036474 kubelet[1815]: I0709 23:50:36.033392 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/ebcd42ae-38e9-48b3-836a-1232813c118e-data\") pod \"nfs-server-provisioner-0\" (UID: \"ebcd42ae-38e9-48b3-836a-1232813c118e\") " pod="default/nfs-server-provisioner-0" Jul 9 23:50:36.036474 kubelet[1815]: I0709 23:50:36.033444 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bhkmk\" (UniqueName: \"kubernetes.io/projected/ebcd42ae-38e9-48b3-836a-1232813c118e-kube-api-access-bhkmk\") pod \"nfs-server-provisioner-0\" (UID: \"ebcd42ae-38e9-48b3-836a-1232813c118e\") " pod="default/nfs-server-provisioner-0" Jul 9 23:50:36.304569 containerd[1527]: time="2025-07-09T23:50:36.304512213Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:ebcd42ae-38e9-48b3-836a-1232813c118e,Namespace:default,Attempt:0,}" Jul 9 23:50:36.322864 systemd-networkd[1444]: vethe41bbcd0: Link UP Jul 9 23:50:36.324746 kernel: cni0: port 2(vethe41bbcd0) entered blocking state Jul 9 23:50:36.324829 kernel: cni0: port 2(vethe41bbcd0) entered disabled state Jul 9 23:50:36.324846 kernel: vethe41bbcd0: entered allmulticast mode Jul 9 23:50:36.329228 kernel: vethe41bbcd0: entered promiscuous mode Jul 9 23:50:36.338698 kernel: cni0: port 2(vethe41bbcd0) entered blocking state Jul 9 23:50:36.338794 kernel: cni0: port 2(vethe41bbcd0) entered forwarding state Jul 9 23:50:36.338780 systemd-networkd[1444]: vethe41bbcd0: Gained carrier Jul 9 23:50:36.342365 containerd[1527]: 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)(0x40001148e8), "name":"cbr0", "type":"bridge"} Jul 9 23:50:36.342365 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Jul 9 23:50:36.363628 containerd[1527]: {"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-09T23:50:36.363533610Z" level=info msg="connecting to shim 4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217" address="unix:///run/containerd/s/8c33662054c9c792db6173cbe24d709610bf849eee3b1311f6f49f1f66fe56ae" namespace=k8s.io protocol=ttrpc version=3 Jul 9 23:50:36.390486 systemd[1]: Started cri-containerd-4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217.scope - libcontainer container 4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217. Jul 9 23:50:36.401158 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 9 23:50:36.428989 containerd[1527]: time="2025-07-09T23:50:36.428951497Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:ebcd42ae-38e9-48b3-836a-1232813c118e,Namespace:default,Attempt:0,} returns sandbox id \"4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217\"" Jul 9 23:50:36.430469 containerd[1527]: time="2025-07-09T23:50:36.430349288Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 9 23:50:36.827079 kubelet[1815]: E0709 23:50:36.827038 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:37.813099 kubelet[1815]: E0709 23:50:37.813055 1815 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:37.827544 kubelet[1815]: E0709 23:50:37.827497 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:38.072540 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2067911100.mount: Deactivated successfully. Jul 9 23:50:38.148376 systemd-networkd[1444]: vethe41bbcd0: Gained IPv6LL Jul 9 23:50:38.828424 kubelet[1815]: E0709 23:50:38.828384 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:39.351032 containerd[1527]: time="2025-07-09T23:50:39.350983636Z" 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 9 23:50:39.351869 containerd[1527]: time="2025-07-09T23:50:39.351500027Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Jul 9 23:50:39.352600 containerd[1527]: time="2025-07-09T23:50:39.352564950Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:39.355122 containerd[1527]: time="2025-07-09T23:50:39.355066229Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:39.355910 containerd[1527]: time="2025-07-09T23:50:39.355871219Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 2.925489874s" Jul 9 23:50:39.355972 containerd[1527]: time="2025-07-09T23:50:39.355908797Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 9 23:50:39.358609 containerd[1527]: time="2025-07-09T23:50:39.358579302Z" level=info msg="CreateContainer within sandbox \"4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 9 23:50:39.367574 containerd[1527]: time="2025-07-09T23:50:39.366371457Z" level=info msg="Container cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:39.374964 containerd[1527]: time="2025-07-09T23:50:39.374864540Z" level=info msg="CreateContainer within sandbox \"4a41b691faa21512088fc9a9fc0599ce57409f790d038db92a46dace7f5cf217\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21\"" Jul 9 23:50:39.375604 containerd[1527]: time="2025-07-09T23:50:39.375574062Z" level=info msg="StartContainer for \"cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21\"" Jul 9 23:50:39.376537 containerd[1527]: time="2025-07-09T23:50:39.376512297Z" level=info msg="connecting to shim cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21" address="unix:///run/containerd/s/8c33662054c9c792db6173cbe24d709610bf849eee3b1311f6f49f1f66fe56ae" protocol=ttrpc version=3 Jul 9 23:50:39.410463 systemd[1]: Started cri-containerd-cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21.scope - libcontainer container cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21. Jul 9 23:50:39.438043 containerd[1527]: time="2025-07-09T23:50:39.436574973Z" level=info msg="StartContainer for \"cee6ba7d25fa7779b05afd21778589ee39ec3deb1c6db7ec0f1c1df6416fcb21\" returns successfully" Jul 9 23:50:39.829452 kubelet[1815]: E0709 23:50:39.829397 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:40.830149 kubelet[1815]: E0709 23:50:40.830087 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:41.830438 kubelet[1815]: E0709 23:50:41.830391 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:42.831472 kubelet[1815]: E0709 23:50:42.831413 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:43.831566 kubelet[1815]: E0709 23:50:43.831512 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:44.831867 kubelet[1815]: E0709 23:50:44.831826 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:45.833196 kubelet[1815]: E0709 23:50:45.833149 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:46.834094 kubelet[1815]: E0709 23:50:46.834052 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:47.835523 kubelet[1815]: E0709 23:50:47.835485 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:48.836132 kubelet[1815]: E0709 23:50:48.836087 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:49.632098 kubelet[1815]: I0709 23:50:49.632032 1815 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.705020632 podStartE2EDuration="14.632012105s" podCreationTimestamp="2025-07-09 23:50:35 +0000 UTC" firstStartedPulling="2025-07-09 23:50:36.430080553 +0000 UTC m=+19.401887128" lastFinishedPulling="2025-07-09 23:50:39.357072026 +0000 UTC m=+22.328878601" observedRunningTime="2025-07-09 23:50:40.01584375 +0000 UTC m=+22.987650325" watchObservedRunningTime="2025-07-09 23:50:49.632012105 +0000 UTC m=+32.603818680" Jul 9 23:50:49.639740 systemd[1]: Created slice kubepods-besteffort-pod4a499018_550a_4fd4_ac41_c0b708a10950.slice - libcontainer container kubepods-besteffort-pod4a499018_550a_4fd4_ac41_c0b708a10950.slice. Jul 9 23:50:49.814302 kubelet[1815]: I0709 23:50:49.814207 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-27vwt\" (UniqueName: \"kubernetes.io/projected/4a499018-550a-4fd4-ac41-c0b708a10950-kube-api-access-27vwt\") pod \"test-pod-1\" (UID: \"4a499018-550a-4fd4-ac41-c0b708a10950\") " pod="default/test-pod-1" Jul 9 23:50:49.814302 kubelet[1815]: I0709 23:50:49.814283 1815 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4d8ab131-47c2-453e-926a-90e735bf450a\" (UniqueName: \"kubernetes.io/nfs/4a499018-550a-4fd4-ac41-c0b708a10950-pvc-4d8ab131-47c2-453e-926a-90e735bf450a\") pod \"test-pod-1\" (UID: \"4a499018-550a-4fd4-ac41-c0b708a10950\") " pod="default/test-pod-1" Jul 9 23:50:49.837051 kubelet[1815]: E0709 23:50:49.836990 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:49.964383 kernel: netfs: FS-Cache loaded Jul 9 23:50:50.009828 kernel: RPC: Registered named UNIX socket transport module. Jul 9 23:50:50.009932 kernel: RPC: Registered udp transport module. Jul 9 23:50:50.009953 kernel: RPC: Registered tcp transport module. Jul 9 23:50:50.011773 kernel: RPC: Registered tcp-with-tls transport module. Jul 9 23:50:50.011806 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 9 23:50:50.206641 kernel: NFS: Registering the id_resolver key type Jul 9 23:50:50.206759 kernel: Key type id_resolver registered Jul 9 23:50:50.206792 kernel: Key type id_legacy registered Jul 9 23:50:50.233267 nfsidmap[2834]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Jul 9 23:50:50.238804 nfsidmap[2834]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 9 23:50:50.240293 nfsidmap[2835]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Jul 9 23:50:50.240657 nfsidmap[2835]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 9 23:50:50.248673 nfsrahead[2837]: setting /var/lib/kubelet/pods/4a499018-550a-4fd4-ac41-c0b708a10950/volumes/kubernetes.io~nfs/pvc-4d8ab131-47c2-453e-926a-90e735bf450a readahead to 128 Jul 9 23:50:50.543611 containerd[1527]: time="2025-07-09T23:50:50.543485263Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4a499018-550a-4fd4-ac41-c0b708a10950,Namespace:default,Attempt:0,}" Jul 9 23:50:50.552575 systemd-networkd[1444]: vetha8f7d40f: Link UP Jul 9 23:50:50.554783 kernel: cni0: port 3(vetha8f7d40f) entered blocking state Jul 9 23:50:50.554846 kernel: cni0: port 3(vetha8f7d40f) entered disabled state Jul 9 23:50:50.554861 kernel: vetha8f7d40f: entered allmulticast mode Jul 9 23:50:50.555616 kernel: vetha8f7d40f: entered promiscuous mode Jul 9 23:50:50.562263 kernel: cni0: port 3(vetha8f7d40f) entered blocking state Jul 9 23:50:50.562332 kernel: cni0: port 3(vetha8f7d40f) entered forwarding state Jul 9 23:50:50.562429 systemd-networkd[1444]: vetha8f7d40f: Gained carrier Jul 9 23:50:50.564110 containerd[1527]: 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)(0x4000014938), "name":"cbr0", "type":"bridge"} Jul 9 23:50:50.564110 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Jul 9 23:50:50.587768 containerd[1527]: {"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-09T23:50:50.587727601Z" level=info msg="connecting to shim e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50" address="unix:///run/containerd/s/d8fd1fc113314b290e8e821d255cb51e9f5a9bf33ff4714e632d26e2166caab2" namespace=k8s.io protocol=ttrpc version=3 Jul 9 23:50:50.610448 systemd[1]: Started cri-containerd-e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50.scope - libcontainer container e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50. Jul 9 23:50:50.621970 systemd-resolved[1352]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 9 23:50:50.643747 containerd[1527]: time="2025-07-09T23:50:50.643708441Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4a499018-550a-4fd4-ac41-c0b708a10950,Namespace:default,Attempt:0,} returns sandbox id \"e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50\"" Jul 9 23:50:50.645204 containerd[1527]: time="2025-07-09T23:50:50.645169263Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 9 23:50:50.837319 kubelet[1815]: E0709 23:50:50.837199 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:50.898300 containerd[1527]: time="2025-07-09T23:50:50.898230571Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 9 23:50:50.898861 containerd[1527]: time="2025-07-09T23:50:50.898831629Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 9 23:50:50.901699 containerd[1527]: time="2025-07-09T23:50:50.901662944Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:30bb68e656e0665bce700e67d2756f68bdca3345fa1099a32bfdb8febcf621cd\", size \"69964463\" in 256.456119ms" Jul 9 23:50:50.901758 containerd[1527]: time="2025-07-09T23:50:50.901697628Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:cd8b38a4e22587134e82fff3512a99b84799274d989a1ec20f58c7f8c89b8511\"" Jul 9 23:50:50.904362 containerd[1527]: time="2025-07-09T23:50:50.904332924Z" level=info msg="CreateContainer within sandbox \"e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 9 23:50:50.913280 containerd[1527]: time="2025-07-09T23:50:50.913060172Z" level=info msg="Container cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f: CDI devices from CRI Config.CDIDevices: []" Jul 9 23:50:50.920154 containerd[1527]: time="2025-07-09T23:50:50.920113177Z" level=info msg="CreateContainer within sandbox \"e74f3794dd7ef831d9542890133b6b98c0fc89c0e4aad2b098fabcea50475a50\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f\"" Jul 9 23:50:50.920978 containerd[1527]: time="2025-07-09T23:50:50.920893813Z" level=info msg="StartContainer for \"cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f\"" Jul 9 23:50:50.921854 containerd[1527]: time="2025-07-09T23:50:50.921826584Z" level=info msg="connecting to shim cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f" address="unix:///run/containerd/s/d8fd1fc113314b290e8e821d255cb51e9f5a9bf33ff4714e632d26e2166caab2" protocol=ttrpc version=3 Jul 9 23:50:50.949415 systemd[1]: Started cri-containerd-cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f.scope - libcontainer container cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f. Jul 9 23:50:50.987489 containerd[1527]: time="2025-07-09T23:50:50.987437199Z" level=info msg="StartContainer for \"cabb1d5d878ac511ab54f23efc1db12d7f50051dab377a55454bb434c0f4c28f\" returns successfully" Jul 9 23:50:51.035342 kubelet[1815]: I0709 23:50:51.035269 1815 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.777312653 podStartE2EDuration="15.035224113s" podCreationTimestamp="2025-07-09 23:50:36 +0000 UTC" firstStartedPulling="2025-07-09 23:50:50.644709618 +0000 UTC m=+33.616516193" lastFinishedPulling="2025-07-09 23:50:50.902621078 +0000 UTC m=+33.874427653" observedRunningTime="2025-07-09 23:50:51.035222473 +0000 UTC m=+34.007029008" watchObservedRunningTime="2025-07-09 23:50:51.035224113 +0000 UTC m=+34.007030648" Jul 9 23:50:51.779460 systemd-networkd[1444]: vetha8f7d40f: Gained IPv6LL Jul 9 23:50:51.837566 kubelet[1815]: E0709 23:50:51.837518 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:52.838325 kubelet[1815]: E0709 23:50:52.838275 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 9 23:50:53.839521 kubelet[1815]: E0709 23:50:53.839425 1815 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"