Apr 30 00:17:40.935534 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Apr 30 00:17:40.935558 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Apr 29 22:24:03 -00 2025 Apr 30 00:17:40.935569 kernel: KASLR enabled Apr 30 00:17:40.935575 kernel: efi: EFI v2.7 by EDK II Apr 30 00:17:40.935580 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbbf018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40d98 Apr 30 00:17:40.935586 kernel: random: crng init done Apr 30 00:17:40.935593 kernel: secureboot: Secure boot disabled Apr 30 00:17:40.935599 kernel: ACPI: Early table checksum verification disabled Apr 30 00:17:40.935605 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Apr 30 00:17:40.935612 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Apr 30 00:17:40.935618 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935624 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935630 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935636 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935643 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935651 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935657 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935663 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935669 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 00:17:40.935676 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Apr 30 00:17:40.935682 kernel: NUMA: Failed to initialise from firmware Apr 30 00:17:40.935688 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Apr 30 00:17:40.935694 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] Apr 30 00:17:40.935700 kernel: Zone ranges: Apr 30 00:17:40.935706 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Apr 30 00:17:40.935714 kernel: DMA32 empty Apr 30 00:17:40.935719 kernel: Normal empty Apr 30 00:17:40.935726 kernel: Movable zone start for each node Apr 30 00:17:40.935732 kernel: Early memory node ranges Apr 30 00:17:40.935738 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Apr 30 00:17:40.935744 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Apr 30 00:17:40.935750 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Apr 30 00:17:40.935757 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Apr 30 00:17:40.935763 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Apr 30 00:17:40.935770 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Apr 30 00:17:40.935776 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Apr 30 00:17:40.935782 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Apr 30 00:17:40.935790 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Apr 30 00:17:40.935811 kernel: psci: probing for conduit method from ACPI. Apr 30 00:17:40.935817 kernel: psci: PSCIv1.1 detected in firmware. Apr 30 00:17:40.935826 kernel: psci: Using standard PSCI v0.2 function IDs Apr 30 00:17:40.935833 kernel: psci: Trusted OS migration not required Apr 30 00:17:40.935840 kernel: psci: SMC Calling Convention v1.1 Apr 30 00:17:40.935849 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Apr 30 00:17:40.935855 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Apr 30 00:17:40.935862 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Apr 30 00:17:40.935869 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 30 00:17:40.935875 kernel: Detected PIPT I-cache on CPU0 Apr 30 00:17:40.935882 kernel: CPU features: detected: GIC system register CPU interface Apr 30 00:17:40.935888 kernel: CPU features: detected: Hardware dirty bit management Apr 30 00:17:40.935895 kernel: CPU features: detected: Spectre-v4 Apr 30 00:17:40.935902 kernel: CPU features: detected: Spectre-BHB Apr 30 00:17:40.935909 kernel: CPU features: kernel page table isolation forced ON by KASLR Apr 30 00:17:40.935916 kernel: CPU features: detected: Kernel page table isolation (KPTI) Apr 30 00:17:40.935923 kernel: CPU features: detected: ARM erratum 1418040 Apr 30 00:17:40.935930 kernel: CPU features: detected: SSBS not fully self-synchronizing Apr 30 00:17:40.935937 kernel: alternatives: applying boot alternatives Apr 30 00:17:40.935945 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=6e9bced8073e517a5f5178e5412663c3084f53d67852b3dfe0380ce71e6d0edd Apr 30 00:17:40.935952 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 00:17:40.935959 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 30 00:17:40.935966 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 00:17:40.935972 kernel: Fallback order for Node 0: 0 Apr 30 00:17:40.935979 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Apr 30 00:17:40.935985 kernel: Policy zone: DMA Apr 30 00:17:40.935993 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 00:17:40.936000 kernel: software IO TLB: area num 4. Apr 30 00:17:40.936007 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Apr 30 00:17:40.936014 kernel: Memory: 2386192K/2572288K available (10304K kernel code, 2186K rwdata, 8104K rodata, 39744K init, 897K bss, 186096K reserved, 0K cma-reserved) Apr 30 00:17:40.936021 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 30 00:17:40.936027 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 00:17:40.936035 kernel: rcu: RCU event tracing is enabled. Apr 30 00:17:40.936042 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Apr 30 00:17:40.936048 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 00:17:40.936055 kernel: Tracing variant of Tasks RCU enabled. Apr 30 00:17:40.936062 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 00:17:40.936069 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Apr 30 00:17:40.936077 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 30 00:17:40.936083 kernel: GICv3: 256 SPIs implemented Apr 30 00:17:40.936090 kernel: GICv3: 0 Extended SPIs implemented Apr 30 00:17:40.936096 kernel: Root IRQ handler: gic_handle_irq Apr 30 00:17:40.936103 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Apr 30 00:17:40.936109 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Apr 30 00:17:40.936116 kernel: ITS [mem 0x08080000-0x0809ffff] Apr 30 00:17:40.936122 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Apr 30 00:17:40.936129 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Apr 30 00:17:40.936143 kernel: GICv3: using LPI property table @0x00000000400f0000 Apr 30 00:17:40.936149 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Apr 30 00:17:40.936158 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 00:17:40.936165 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 30 00:17:40.936181 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Apr 30 00:17:40.936189 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Apr 30 00:17:40.936195 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Apr 30 00:17:40.936202 kernel: arm-pv: using stolen time PV Apr 30 00:17:40.936209 kernel: Console: colour dummy device 80x25 Apr 30 00:17:40.936216 kernel: ACPI: Core revision 20230628 Apr 30 00:17:40.936222 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Apr 30 00:17:40.936229 kernel: pid_max: default: 32768 minimum: 301 Apr 30 00:17:40.936238 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 00:17:40.936245 kernel: landlock: Up and running. Apr 30 00:17:40.936252 kernel: SELinux: Initializing. Apr 30 00:17:40.936258 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 00:17:40.936265 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 00:17:40.936272 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 30 00:17:40.936279 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Apr 30 00:17:40.936286 kernel: rcu: Hierarchical SRCU implementation. Apr 30 00:17:40.936293 kernel: rcu: Max phase no-delay instances is 400. Apr 30 00:17:40.936301 kernel: Platform MSI: ITS@0x8080000 domain created Apr 30 00:17:40.936308 kernel: PCI/MSI: ITS@0x8080000 domain created Apr 30 00:17:40.936315 kernel: Remapping and enabling EFI services. Apr 30 00:17:40.936321 kernel: smp: Bringing up secondary CPUs ... Apr 30 00:17:40.936328 kernel: Detected PIPT I-cache on CPU1 Apr 30 00:17:40.936335 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Apr 30 00:17:40.936342 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Apr 30 00:17:40.936348 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 30 00:17:40.936355 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Apr 30 00:17:40.936362 kernel: Detected PIPT I-cache on CPU2 Apr 30 00:17:40.936370 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Apr 30 00:17:40.936377 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Apr 30 00:17:40.936389 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 30 00:17:40.936397 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Apr 30 00:17:40.936405 kernel: Detected PIPT I-cache on CPU3 Apr 30 00:17:40.936412 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Apr 30 00:17:40.936419 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Apr 30 00:17:40.936426 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 30 00:17:40.936433 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Apr 30 00:17:40.936442 kernel: smp: Brought up 1 node, 4 CPUs Apr 30 00:17:40.936449 kernel: SMP: Total of 4 processors activated. Apr 30 00:17:40.936456 kernel: CPU features: detected: 32-bit EL0 Support Apr 30 00:17:40.936463 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Apr 30 00:17:40.936470 kernel: CPU features: detected: Common not Private translations Apr 30 00:17:40.936477 kernel: CPU features: detected: CRC32 instructions Apr 30 00:17:40.936484 kernel: CPU features: detected: Enhanced Virtualization Traps Apr 30 00:17:40.936491 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Apr 30 00:17:40.936500 kernel: CPU features: detected: LSE atomic instructions Apr 30 00:17:40.936507 kernel: CPU features: detected: Privileged Access Never Apr 30 00:17:40.936514 kernel: CPU features: detected: RAS Extension Support Apr 30 00:17:40.936521 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Apr 30 00:17:40.936528 kernel: CPU: All CPU(s) started at EL1 Apr 30 00:17:40.936535 kernel: alternatives: applying system-wide alternatives Apr 30 00:17:40.936543 kernel: devtmpfs: initialized Apr 30 00:17:40.936550 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 00:17:40.936557 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 30 00:17:40.936566 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 00:17:40.936573 kernel: SMBIOS 3.0.0 present. Apr 30 00:17:40.936580 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Apr 30 00:17:40.936587 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 00:17:40.936594 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 30 00:17:40.936601 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 30 00:17:40.936609 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 30 00:17:40.936616 kernel: audit: initializing netlink subsys (disabled) Apr 30 00:17:40.936623 kernel: audit: type=2000 audit(0.025:1): state=initialized audit_enabled=0 res=1 Apr 30 00:17:40.936632 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 00:17:40.936643 kernel: cpuidle: using governor menu Apr 30 00:17:40.936650 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 30 00:17:40.936658 kernel: ASID allocator initialised with 32768 entries Apr 30 00:17:40.936665 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 00:17:40.936672 kernel: Serial: AMBA PL011 UART driver Apr 30 00:17:40.936679 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Apr 30 00:17:40.936686 kernel: Modules: 0 pages in range for non-PLT usage Apr 30 00:17:40.936693 kernel: Modules: 508928 pages in range for PLT usage Apr 30 00:17:40.936702 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 30 00:17:40.936709 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 30 00:17:40.936717 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 30 00:17:40.936724 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 30 00:17:40.936731 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 00:17:40.936738 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 00:17:40.936745 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 30 00:17:40.936752 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 30 00:17:40.936759 kernel: ACPI: Added _OSI(Module Device) Apr 30 00:17:40.936773 kernel: ACPI: Added _OSI(Processor Device) Apr 30 00:17:40.936780 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 00:17:40.936787 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 00:17:40.936794 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 00:17:40.936801 kernel: ACPI: Interpreter enabled Apr 30 00:17:40.936808 kernel: ACPI: Using GIC for interrupt routing Apr 30 00:17:40.936815 kernel: ACPI: MCFG table detected, 1 entries Apr 30 00:17:40.936822 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Apr 30 00:17:40.936830 kernel: printk: console [ttyAMA0] enabled Apr 30 00:17:40.936838 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 30 00:17:40.936971 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 30 00:17:40.937044 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 30 00:17:40.937109 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 30 00:17:40.937193 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Apr 30 00:17:40.937260 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Apr 30 00:17:40.937269 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Apr 30 00:17:40.937280 kernel: PCI host bridge to bus 0000:00 Apr 30 00:17:40.937351 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Apr 30 00:17:40.937411 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 30 00:17:40.937468 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Apr 30 00:17:40.937553 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 30 00:17:40.937652 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Apr 30 00:17:40.937735 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Apr 30 00:17:40.937807 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Apr 30 00:17:40.937872 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Apr 30 00:17:40.937937 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Apr 30 00:17:40.938002 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Apr 30 00:17:40.938068 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Apr 30 00:17:40.938137 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Apr 30 00:17:40.938220 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Apr 30 00:17:40.938281 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 30 00:17:40.938338 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Apr 30 00:17:40.938348 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 30 00:17:40.938355 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 30 00:17:40.938362 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 30 00:17:40.938370 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 30 00:17:40.938377 kernel: iommu: Default domain type: Translated Apr 30 00:17:40.938384 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 30 00:17:40.938392 kernel: efivars: Registered efivars operations Apr 30 00:17:40.938399 kernel: vgaarb: loaded Apr 30 00:17:40.938407 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 30 00:17:40.938414 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 00:17:40.938421 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 00:17:40.938428 kernel: pnp: PnP ACPI init Apr 30 00:17:40.938499 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Apr 30 00:17:40.938509 kernel: pnp: PnP ACPI: found 1 devices Apr 30 00:17:40.938518 kernel: NET: Registered PF_INET protocol family Apr 30 00:17:40.938525 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 30 00:17:40.938533 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 30 00:17:40.938540 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 00:17:40.938547 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 30 00:17:40.938555 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 30 00:17:40.938562 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 30 00:17:40.938569 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 00:17:40.938576 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 00:17:40.938599 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 00:17:40.938606 kernel: PCI: CLS 0 bytes, default 64 Apr 30 00:17:40.938613 kernel: kvm [1]: HYP mode not available Apr 30 00:17:40.938620 kernel: Initialise system trusted keyrings Apr 30 00:17:40.938628 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 30 00:17:40.938635 kernel: Key type asymmetric registered Apr 30 00:17:40.938642 kernel: Asymmetric key parser 'x509' registered Apr 30 00:17:40.938649 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 30 00:17:40.938656 kernel: io scheduler mq-deadline registered Apr 30 00:17:40.938665 kernel: io scheduler kyber registered Apr 30 00:17:40.938672 kernel: io scheduler bfq registered Apr 30 00:17:40.938679 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 30 00:17:40.938686 kernel: ACPI: button: Power Button [PWRB] Apr 30 00:17:40.938694 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 30 00:17:40.938764 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Apr 30 00:17:40.938773 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 00:17:40.938781 kernel: thunder_xcv, ver 1.0 Apr 30 00:17:40.938788 kernel: thunder_bgx, ver 1.0 Apr 30 00:17:40.938796 kernel: nicpf, ver 1.0 Apr 30 00:17:40.938803 kernel: nicvf, ver 1.0 Apr 30 00:17:40.938877 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 30 00:17:40.938940 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-04-30T00:17:40 UTC (1745972260) Apr 30 00:17:40.938949 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 30 00:17:40.938957 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Apr 30 00:17:40.938964 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 30 00:17:40.938971 kernel: watchdog: Hard watchdog permanently disabled Apr 30 00:17:40.938980 kernel: NET: Registered PF_INET6 protocol family Apr 30 00:17:40.938987 kernel: Segment Routing with IPv6 Apr 30 00:17:40.938994 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 00:17:40.939001 kernel: NET: Registered PF_PACKET protocol family Apr 30 00:17:40.939008 kernel: Key type dns_resolver registered Apr 30 00:17:40.939015 kernel: registered taskstats version 1 Apr 30 00:17:40.939022 kernel: Loading compiled-in X.509 certificates Apr 30 00:17:40.939030 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: bbef389676bd9584646af24e9e264c7789f8630f' Apr 30 00:17:40.939037 kernel: Key type .fscrypt registered Apr 30 00:17:40.939045 kernel: Key type fscrypt-provisioning registered Apr 30 00:17:40.939053 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 00:17:40.939060 kernel: ima: Allocated hash algorithm: sha1 Apr 30 00:17:40.939067 kernel: ima: No architecture policies found Apr 30 00:17:40.939077 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 30 00:17:40.939087 kernel: clk: Disabling unused clocks Apr 30 00:17:40.939094 kernel: Freeing unused kernel memory: 39744K Apr 30 00:17:40.939101 kernel: Run /init as init process Apr 30 00:17:40.939108 kernel: with arguments: Apr 30 00:17:40.939117 kernel: /init Apr 30 00:17:40.939124 kernel: with environment: Apr 30 00:17:40.939136 kernel: HOME=/ Apr 30 00:17:40.939145 kernel: TERM=linux Apr 30 00:17:40.939151 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 00:17:40.939160 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Apr 30 00:17:40.939177 systemd[1]: Detected virtualization kvm. Apr 30 00:17:40.939188 systemd[1]: Detected architecture arm64. Apr 30 00:17:40.939200 systemd[1]: Running in initrd. Apr 30 00:17:40.939210 systemd[1]: No hostname configured, using default hostname. Apr 30 00:17:40.939218 systemd[1]: Hostname set to . Apr 30 00:17:40.939226 systemd[1]: Initializing machine ID from VM UUID. Apr 30 00:17:40.939233 systemd[1]: Queued start job for default target initrd.target. Apr 30 00:17:40.939241 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:17:40.939249 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:17:40.939257 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 00:17:40.939267 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 00:17:40.939274 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 00:17:40.939282 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 00:17:40.939291 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 00:17:40.939299 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 00:17:40.939307 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:17:40.939314 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:17:40.939323 systemd[1]: Reached target paths.target - Path Units. Apr 30 00:17:40.939331 systemd[1]: Reached target slices.target - Slice Units. Apr 30 00:17:40.939339 systemd[1]: Reached target swap.target - Swaps. Apr 30 00:17:40.939346 systemd[1]: Reached target timers.target - Timer Units. Apr 30 00:17:40.939354 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 00:17:40.939362 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 00:17:40.939370 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 00:17:40.939388 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 30 00:17:40.939398 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:17:40.939406 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 00:17:40.939414 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:17:40.939422 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 00:17:40.939430 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 00:17:40.939437 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 00:17:40.939445 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 00:17:40.939453 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 00:17:40.939461 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 00:17:40.939470 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 00:17:40.939477 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:17:40.939485 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 00:17:40.939493 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:17:40.939501 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 00:17:40.939528 systemd-journald[238]: Collecting audit messages is disabled. Apr 30 00:17:40.939549 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 00:17:40.939557 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 00:17:40.939567 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:17:40.939574 kernel: Bridge firewalling registered Apr 30 00:17:40.939582 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 00:17:40.939590 systemd-journald[238]: Journal started Apr 30 00:17:40.939609 systemd-journald[238]: Runtime Journal (/run/log/journal/c677b17d2b1c43979495fda7cdd3bc15) is 5.9M, max 47.3M, 41.4M free. Apr 30 00:17:40.920981 systemd-modules-load[239]: Inserted module 'overlay' Apr 30 00:17:40.939145 systemd-modules-load[239]: Inserted module 'br_netfilter' Apr 30 00:17:40.945896 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 00:17:40.946321 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 00:17:40.950268 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:17:40.952062 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 00:17:40.953769 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 00:17:40.956598 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 00:17:40.967750 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:17:40.969846 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:17:40.971343 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:17:40.975610 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 00:17:40.980619 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:17:40.982721 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 00:17:40.996493 dracut-cmdline[277]: dracut-dracut-053 Apr 30 00:17:40.999064 dracut-cmdline[277]: Using kernel command line parameters: rd.driver.pre=btrfs 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=6e9bced8073e517a5f5178e5412663c3084f53d67852b3dfe0380ce71e6d0edd Apr 30 00:17:41.009920 systemd-resolved[271]: Positive Trust Anchors: Apr 30 00:17:41.009997 systemd-resolved[271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 00:17:41.010029 systemd-resolved[271]: 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 Apr 30 00:17:41.014844 systemd-resolved[271]: Defaulting to hostname 'linux'. Apr 30 00:17:41.015818 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 00:17:41.020093 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:17:41.075194 kernel: SCSI subsystem initialized Apr 30 00:17:41.079187 kernel: Loading iSCSI transport class v2.0-870. Apr 30 00:17:41.087186 kernel: iscsi: registered transport (tcp) Apr 30 00:17:41.100191 kernel: iscsi: registered transport (qla4xxx) Apr 30 00:17:41.100208 kernel: QLogic iSCSI HBA Driver Apr 30 00:17:41.144209 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 00:17:41.159309 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 00:17:41.180599 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 00:17:41.180659 kernel: device-mapper: uevent: version 1.0.3 Apr 30 00:17:41.181715 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 00:17:41.228208 kernel: raid6: neonx8 gen() 15783 MB/s Apr 30 00:17:41.245193 kernel: raid6: neonx4 gen() 15616 MB/s Apr 30 00:17:41.262196 kernel: raid6: neonx2 gen() 13227 MB/s Apr 30 00:17:41.279193 kernel: raid6: neonx1 gen() 10464 MB/s Apr 30 00:17:41.296203 kernel: raid6: int64x8 gen() 6965 MB/s Apr 30 00:17:41.313206 kernel: raid6: int64x4 gen() 7338 MB/s Apr 30 00:17:41.330202 kernel: raid6: int64x2 gen() 6127 MB/s Apr 30 00:17:41.347395 kernel: raid6: int64x1 gen() 5031 MB/s Apr 30 00:17:41.347420 kernel: raid6: using algorithm neonx8 gen() 15783 MB/s Apr 30 00:17:41.365363 kernel: raid6: .... xor() 11928 MB/s, rmw enabled Apr 30 00:17:41.365378 kernel: raid6: using neon recovery algorithm Apr 30 00:17:41.370190 kernel: xor: measuring software checksum speed Apr 30 00:17:41.371545 kernel: 8regs : 17533 MB/sec Apr 30 00:17:41.371558 kernel: 32regs : 19655 MB/sec Apr 30 00:17:41.372191 kernel: arm64_neon : 26954 MB/sec Apr 30 00:17:41.372203 kernel: xor: using function: arm64_neon (26954 MB/sec) Apr 30 00:17:41.429200 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 00:17:41.443142 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 00:17:41.455380 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:17:41.468438 systemd-udevd[460]: Using default interface naming scheme 'v255'. Apr 30 00:17:41.471995 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:17:41.483663 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 00:17:41.495511 dracut-pre-trigger[468]: rd.md=0: removing MD RAID activation Apr 30 00:17:41.525773 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 00:17:41.541359 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 00:17:41.584336 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:17:41.594359 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 00:17:41.608204 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 00:17:41.610889 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 00:17:41.612650 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:17:41.615440 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 00:17:41.628333 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 00:17:41.642493 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Apr 30 00:17:41.650406 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Apr 30 00:17:41.650516 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 30 00:17:41.650529 kernel: GPT:9289727 != 19775487 Apr 30 00:17:41.650538 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 30 00:17:41.650547 kernel: GPT:9289727 != 19775487 Apr 30 00:17:41.650555 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 30 00:17:41.650567 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 00:17:41.646126 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 00:17:41.658717 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 00:17:41.658830 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:17:41.662298 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:17:41.669597 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (511) Apr 30 00:17:41.669623 kernel: BTRFS: device fsid 9647859b-527c-478f-8aa1-9dfa3fa871e3 devid 1 transid 43 /dev/vda3 scanned by (udev-worker) (508) Apr 30 00:17:41.666675 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 00:17:41.666849 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:17:41.670862 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:17:41.681487 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:17:41.694333 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 30 00:17:41.695831 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:17:41.703882 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 30 00:17:41.708844 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 00:17:41.712858 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 30 00:17:41.714076 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 30 00:17:41.729347 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 00:17:41.731320 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:17:41.739658 disk-uuid[551]: Primary Header is updated. Apr 30 00:17:41.739658 disk-uuid[551]: Secondary Entries is updated. Apr 30 00:17:41.739658 disk-uuid[551]: Secondary Header is updated. Apr 30 00:17:41.745317 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 00:17:41.757762 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:17:42.757714 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 00:17:42.757807 disk-uuid[552]: The operation has completed successfully. Apr 30 00:17:42.778723 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 00:17:42.778824 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 00:17:42.801371 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 00:17:42.804352 sh[573]: Success Apr 30 00:17:42.817216 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 30 00:17:42.845951 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 00:17:42.865511 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 00:17:42.868063 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 00:17:42.878195 kernel: BTRFS info (device dm-0): first mount of filesystem 9647859b-527c-478f-8aa1-9dfa3fa871e3 Apr 30 00:17:42.878245 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:17:42.878257 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 00:17:42.879577 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 00:17:42.879593 kernel: BTRFS info (device dm-0): using free space tree Apr 30 00:17:42.883528 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 00:17:42.884912 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 00:17:42.902432 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 00:17:42.904838 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 00:17:42.911349 kernel: BTRFS info (device vda6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 30 00:17:42.911395 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:17:42.911406 kernel: BTRFS info (device vda6): using free space tree Apr 30 00:17:42.914215 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 00:17:42.922867 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 30 00:17:42.925192 kernel: BTRFS info (device vda6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 30 00:17:42.930134 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 00:17:42.936371 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 00:17:43.009245 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 00:17:43.023352 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 00:17:43.037413 ignition[662]: Ignition 2.20.0 Apr 30 00:17:43.037423 ignition[662]: Stage: fetch-offline Apr 30 00:17:43.037457 ignition[662]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:43.037465 ignition[662]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:43.037667 ignition[662]: parsed url from cmdline: "" Apr 30 00:17:43.037670 ignition[662]: no config URL provided Apr 30 00:17:43.037675 ignition[662]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 00:17:43.037682 ignition[662]: no config at "/usr/lib/ignition/user.ign" Apr 30 00:17:43.037710 ignition[662]: op(1): [started] loading QEMU firmware config module Apr 30 00:17:43.037715 ignition[662]: op(1): executing: "modprobe" "qemu_fw_cfg" Apr 30 00:17:43.048147 systemd-networkd[766]: lo: Link UP Apr 30 00:17:43.043031 ignition[662]: op(1): [finished] loading QEMU firmware config module Apr 30 00:17:43.048151 systemd-networkd[766]: lo: Gained carrier Apr 30 00:17:43.048895 systemd-networkd[766]: Enumeration completed Apr 30 00:17:43.049403 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 00:17:43.050770 systemd[1]: Reached target network.target - Network. Apr 30 00:17:43.052333 systemd-networkd[766]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:17:43.052337 systemd-networkd[766]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 00:17:43.059596 ignition[662]: parsing config with SHA512: b090c41c0822b4d18bf622fb9093cd982cd8aa33a094539b1c446244fe2a4e2e2bf17ef0d5f2d75d0c91b406aeeee963a4212eff10b007aa7db9d63f70db2e04 Apr 30 00:17:43.053285 systemd-networkd[766]: eth0: Link UP Apr 30 00:17:43.053288 systemd-networkd[766]: eth0: Gained carrier Apr 30 00:17:43.053295 systemd-networkd[766]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:17:43.063206 ignition[662]: fetch-offline: fetch-offline passed Apr 30 00:17:43.062917 unknown[662]: fetched base config from "system" Apr 30 00:17:43.063277 ignition[662]: Ignition finished successfully Apr 30 00:17:43.062924 unknown[662]: fetched user config from "qemu" Apr 30 00:17:43.064624 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 00:17:43.067582 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Apr 30 00:17:43.076249 systemd-networkd[766]: eth0: DHCPv4 address 10.0.0.152/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 30 00:17:43.077198 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 00:17:43.087903 ignition[772]: Ignition 2.20.0 Apr 30 00:17:43.087914 ignition[772]: Stage: kargs Apr 30 00:17:43.088069 ignition[772]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:43.088079 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:43.090865 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 00:17:43.088814 ignition[772]: kargs: kargs passed Apr 30 00:17:43.088856 ignition[772]: Ignition finished successfully Apr 30 00:17:43.102317 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 00:17:43.111467 ignition[781]: Ignition 2.20.0 Apr 30 00:17:43.111478 ignition[781]: Stage: disks Apr 30 00:17:43.111640 ignition[781]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:43.111649 ignition[781]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:43.112384 ignition[781]: disks: disks passed Apr 30 00:17:43.112430 ignition[781]: Ignition finished successfully Apr 30 00:17:43.115229 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 00:17:43.116974 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 00:17:43.118512 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 00:17:43.120565 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 00:17:43.122532 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 00:17:43.124672 systemd[1]: Reached target basic.target - Basic System. Apr 30 00:17:43.141364 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 00:17:43.152997 systemd-fsck[793]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 30 00:17:43.156963 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 00:17:43.160992 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 00:17:43.210190 kernel: EXT4-fs (vda9): mounted filesystem cd2ccabc-5b27-4350-bc86-21c9a8411827 r/w with ordered data mode. Quota mode: none. Apr 30 00:17:43.210890 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 00:17:43.212205 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 00:17:43.223261 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 00:17:43.224904 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 00:17:43.226125 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 30 00:17:43.226235 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 00:17:43.226262 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 00:17:43.235059 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (801) Apr 30 00:17:43.235081 kernel: BTRFS info (device vda6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 30 00:17:43.232490 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 00:17:43.240437 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:17:43.240459 kernel: BTRFS info (device vda6): using free space tree Apr 30 00:17:43.240469 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 00:17:43.234738 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 00:17:43.241979 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 00:17:43.279440 initrd-setup-root[825]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 00:17:43.282691 initrd-setup-root[832]: cut: /sysroot/etc/group: No such file or directory Apr 30 00:17:43.285844 initrd-setup-root[839]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 00:17:43.288824 initrd-setup-root[846]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 00:17:43.363544 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 00:17:43.373255 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 00:17:43.374888 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 00:17:43.382197 kernel: BTRFS info (device vda6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 30 00:17:43.399679 ignition[915]: INFO : Ignition 2.20.0 Apr 30 00:17:43.401862 ignition[915]: INFO : Stage: mount Apr 30 00:17:43.401862 ignition[915]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:43.401862 ignition[915]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:43.401862 ignition[915]: INFO : mount: mount passed Apr 30 00:17:43.401862 ignition[915]: INFO : Ignition finished successfully Apr 30 00:17:43.403430 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 00:17:43.405704 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 00:17:43.420276 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 00:17:43.876378 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 00:17:43.885346 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 00:17:43.891187 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (929) Apr 30 00:17:43.893564 kernel: BTRFS info (device vda6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 30 00:17:43.893589 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:17:43.893601 kernel: BTRFS info (device vda6): using free space tree Apr 30 00:17:43.896185 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 00:17:43.897563 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 00:17:43.914664 ignition[946]: INFO : Ignition 2.20.0 Apr 30 00:17:43.914664 ignition[946]: INFO : Stage: files Apr 30 00:17:43.916473 ignition[946]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:43.916473 ignition[946]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:43.916473 ignition[946]: DEBUG : files: compiled without relabeling support, skipping Apr 30 00:17:43.920233 ignition[946]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 00:17:43.920233 ignition[946]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 00:17:43.920233 ignition[946]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 00:17:43.920233 ignition[946]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 00:17:43.920233 ignition[946]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 00:17:43.920233 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 30 00:17:43.919107 unknown[946]: wrote ssh authorized keys file for user: core Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 30 00:17:43.930649 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-arm64.raw: attempt #1 Apr 30 00:17:44.207713 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 30 00:17:44.350282 systemd-networkd[766]: eth0: Gained IPv6LL Apr 30 00:17:44.672939 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 30 00:17:44.672939 ignition[946]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Apr 30 00:17:44.676712 ignition[946]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 30 00:17:44.676712 ignition[946]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Apr 30 00:17:44.676712 ignition[946]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Apr 30 00:17:44.676712 ignition[946]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Apr 30 00:17:44.696240 ignition[946]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Apr 30 00:17:44.700360 ignition[946]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Apr 30 00:17:44.702913 ignition[946]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Apr 30 00:17:44.702913 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 00:17:44.702913 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 00:17:44.702913 ignition[946]: INFO : files: files passed Apr 30 00:17:44.702913 ignition[946]: INFO : Ignition finished successfully Apr 30 00:17:44.703693 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 00:17:44.713310 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 00:17:44.715689 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 00:17:44.717444 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 00:17:44.718225 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 00:17:44.723296 initrd-setup-root-after-ignition[975]: grep: /sysroot/oem/oem-release: No such file or directory Apr 30 00:17:44.726667 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:17:44.726667 initrd-setup-root-after-ignition[977]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:17:44.729876 initrd-setup-root-after-ignition[981]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:17:44.728595 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 00:17:44.731555 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 00:17:44.741366 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 00:17:44.761342 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 00:17:44.762264 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 00:17:44.763732 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 00:17:44.765567 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 00:17:44.767384 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 00:17:44.768220 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 00:17:44.783631 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 00:17:44.795367 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 00:17:44.803259 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:17:44.804490 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:17:44.806517 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 00:17:44.808273 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 00:17:44.808398 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 00:17:44.810951 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 00:17:44.813004 systemd[1]: Stopped target basic.target - Basic System. Apr 30 00:17:44.814635 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 00:17:44.816312 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 00:17:44.818212 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 00:17:44.820205 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 00:17:44.822031 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 00:17:44.823984 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 00:17:44.825951 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 00:17:44.827687 systemd[1]: Stopped target swap.target - Swaps. Apr 30 00:17:44.829325 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 00:17:44.829447 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 00:17:44.832328 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:17:44.834413 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:17:44.840282 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 00:17:44.841227 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:17:44.842473 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 00:17:44.842589 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 00:17:44.846457 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 00:17:44.846574 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 00:17:44.848497 systemd[1]: Stopped target paths.target - Path Units. Apr 30 00:17:44.850067 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 00:17:44.853241 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:17:44.855831 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 00:17:44.857942 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 00:17:44.859499 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 00:17:44.859585 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 00:17:44.861113 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 00:17:44.861219 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 00:17:44.862794 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 00:17:44.862896 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 00:17:44.864748 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 00:17:44.864847 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 00:17:44.876346 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 00:17:44.877264 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 00:17:44.877409 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:17:44.881749 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 00:17:44.884198 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 00:17:44.885706 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:17:44.887706 ignition[1001]: INFO : Ignition 2.20.0 Apr 30 00:17:44.887706 ignition[1001]: INFO : Stage: umount Apr 30 00:17:44.887706 ignition[1001]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:17:44.887706 ignition[1001]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Apr 30 00:17:44.893534 ignition[1001]: INFO : umount: umount passed Apr 30 00:17:44.893534 ignition[1001]: INFO : Ignition finished successfully Apr 30 00:17:44.888097 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 00:17:44.888232 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 00:17:44.894485 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 00:17:44.895253 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 00:17:44.895342 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 00:17:44.899195 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 00:17:44.899286 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 00:17:44.903359 systemd[1]: Stopped target network.target - Network. Apr 30 00:17:44.904870 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 00:17:44.904934 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 00:17:44.906722 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 00:17:44.906766 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 00:17:44.908799 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 00:17:44.908843 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 00:17:44.910442 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 00:17:44.910488 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 00:17:44.912375 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 00:17:44.914103 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 00:17:44.922326 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 00:17:44.922429 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 00:17:44.923224 systemd-networkd[766]: eth0: DHCPv6 lease lost Apr 30 00:17:44.924741 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 00:17:44.924787 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:17:44.926362 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 00:17:44.928261 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 00:17:44.930477 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 00:17:44.930523 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:17:44.939295 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 00:17:44.940239 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 00:17:44.940300 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 00:17:44.942409 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 00:17:44.942454 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:17:44.944295 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 00:17:44.944340 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 00:17:44.946525 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:17:44.949302 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 00:17:44.949395 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 00:17:44.951987 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 00:17:44.952063 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 00:17:44.957366 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 00:17:44.957459 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 00:17:44.959097 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 00:17:44.959256 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:17:44.961807 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 00:17:44.961858 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 00:17:44.963754 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 00:17:44.963788 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:17:44.965520 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 00:17:44.965567 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 00:17:44.968471 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 00:17:44.968514 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 00:17:44.971179 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 00:17:44.971227 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:17:44.989436 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 00:17:44.990605 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 00:17:44.990678 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:17:44.992869 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 00:17:44.992919 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:17:44.995129 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 00:17:44.995230 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 00:17:44.997439 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 00:17:44.999638 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 00:17:45.009036 systemd[1]: Switching root. Apr 30 00:17:45.039239 systemd-journald[238]: Journal stopped Apr 30 00:17:45.747385 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Apr 30 00:17:45.747438 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 00:17:45.747450 kernel: SELinux: policy capability open_perms=1 Apr 30 00:17:45.747459 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 00:17:45.747468 kernel: SELinux: policy capability always_check_network=0 Apr 30 00:17:45.747478 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 00:17:45.747491 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 00:17:45.747500 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 00:17:45.747509 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 00:17:45.747519 systemd[1]: Successfully loaded SELinux policy in 32.285ms. Apr 30 00:17:45.747539 kernel: audit: type=1403 audit(1745972265.169:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 00:17:45.747550 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.145ms. Apr 30 00:17:45.747563 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Apr 30 00:17:45.747574 systemd[1]: Detected virtualization kvm. Apr 30 00:17:45.747584 systemd[1]: Detected architecture arm64. Apr 30 00:17:45.747596 systemd[1]: Detected first boot. Apr 30 00:17:45.747606 systemd[1]: Initializing machine ID from VM UUID. Apr 30 00:17:45.747616 zram_generator::config[1045]: No configuration found. Apr 30 00:17:45.747628 systemd[1]: Populated /etc with preset unit settings. Apr 30 00:17:45.747638 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 30 00:17:45.747648 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 30 00:17:45.747660 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 30 00:17:45.747671 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 00:17:45.747681 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 00:17:45.747692 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 00:17:45.747702 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 00:17:45.747713 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 00:17:45.747723 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 00:17:45.747735 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 00:17:45.747746 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 00:17:45.747756 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:17:45.747767 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:17:45.747777 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 00:17:45.747788 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 00:17:45.747798 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 00:17:45.747809 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 00:17:45.747819 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Apr 30 00:17:45.747830 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:17:45.747840 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 30 00:17:45.747850 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 30 00:17:45.747861 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 30 00:17:45.747871 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 00:17:45.747881 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:17:45.747891 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 00:17:45.747903 systemd[1]: Reached target slices.target - Slice Units. Apr 30 00:17:45.747913 systemd[1]: Reached target swap.target - Swaps. Apr 30 00:17:45.747923 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 00:17:45.747934 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 00:17:45.747944 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:17:45.747954 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 00:17:45.747964 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:17:45.747975 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 00:17:45.747985 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 00:17:45.747995 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 00:17:45.748008 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 00:17:45.748018 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 00:17:45.748028 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 00:17:45.748039 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 00:17:45.748050 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). Apr 30 00:17:45.748060 systemd[1]: Reached target machines.target - Containers. Apr 30 00:17:45.748070 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 00:17:45.748081 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:17:45.748092 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 00:17:45.748103 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 00:17:45.748118 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:17:45.748131 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 00:17:45.748141 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:17:45.748152 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 00:17:45.748162 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:17:45.748178 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 00:17:45.748193 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 30 00:17:45.748204 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 30 00:17:45.748214 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 30 00:17:45.748224 systemd[1]: Stopped systemd-fsck-usr.service. Apr 30 00:17:45.748234 kernel: loop: module loaded Apr 30 00:17:45.748245 kernel: fuse: init (API version 7.39) Apr 30 00:17:45.748255 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 00:17:45.748265 kernel: ACPI: bus type drm_connector registered Apr 30 00:17:45.748274 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 00:17:45.748285 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 00:17:45.748313 systemd-journald[1113]: Collecting audit messages is disabled. Apr 30 00:17:45.748335 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 00:17:45.748347 systemd-journald[1113]: Journal started Apr 30 00:17:45.748368 systemd-journald[1113]: Runtime Journal (/run/log/journal/c677b17d2b1c43979495fda7cdd3bc15) is 5.9M, max 47.3M, 41.4M free. Apr 30 00:17:45.538449 systemd[1]: Queued start job for default target multi-user.target. Apr 30 00:17:45.558599 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 30 00:17:45.558953 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 30 00:17:45.752445 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 00:17:45.754199 systemd[1]: verity-setup.service: Deactivated successfully. Apr 30 00:17:45.754228 systemd[1]: Stopped verity-setup.service. Apr 30 00:17:45.758419 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 00:17:45.759075 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 00:17:45.760354 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 00:17:45.761736 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 00:17:45.762919 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 00:17:45.764308 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 00:17:45.765769 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 00:17:45.767133 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 00:17:45.769664 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:17:45.771256 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 00:17:45.772365 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 00:17:45.773802 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:17:45.773958 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:17:45.775432 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 00:17:45.775577 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 00:17:45.777012 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:17:45.777193 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:17:45.778706 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 00:17:45.778853 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 00:17:45.780418 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:17:45.780553 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:17:45.782032 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 00:17:45.783727 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 00:17:45.785465 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 00:17:45.798402 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 00:17:45.808289 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 00:17:45.810507 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 00:17:45.811648 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 00:17:45.811691 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 00:17:45.813708 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 30 00:17:45.816106 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 00:17:45.818473 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 00:17:45.819638 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:17:45.821109 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 00:17:45.823883 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 00:17:45.825289 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 00:17:45.826589 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 00:17:45.827715 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 00:17:45.829361 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 00:17:45.835873 systemd-journald[1113]: Time spent on flushing to /var/log/journal/c677b17d2b1c43979495fda7cdd3bc15 is 15.756ms for 837 entries. Apr 30 00:17:45.835873 systemd-journald[1113]: System Journal (/var/log/journal/c677b17d2b1c43979495fda7cdd3bc15) is 8.0M, max 195.6M, 187.6M free. Apr 30 00:17:45.865401 systemd-journald[1113]: Received client request to flush runtime journal. Apr 30 00:17:45.865439 kernel: loop0: detected capacity change from 0 to 116808 Apr 30 00:17:45.865452 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 00:17:45.834352 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 00:17:45.841451 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 00:17:45.844945 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:17:45.847457 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 00:17:45.848793 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 00:17:45.850928 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 00:17:45.854406 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 00:17:45.859691 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 00:17:45.871465 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 30 00:17:45.880478 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 00:17:45.885766 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 00:17:45.887618 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:17:45.892300 kernel: loop1: detected capacity change from 0 to 201592 Apr 30 00:17:45.897372 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 00:17:45.908435 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 00:17:45.910548 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 00:17:45.911888 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 30 00:17:45.915120 udevadm[1170]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 30 00:17:45.930206 kernel: loop2: detected capacity change from 0 to 113536 Apr 30 00:17:45.939235 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Apr 30 00:17:45.939254 systemd-tmpfiles[1176]: ACLs are not supported, ignoring. Apr 30 00:17:45.947244 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:17:45.969201 kernel: loop3: detected capacity change from 0 to 116808 Apr 30 00:17:45.980194 kernel: loop4: detected capacity change from 0 to 201592 Apr 30 00:17:45.992194 kernel: loop5: detected capacity change from 0 to 113536 Apr 30 00:17:45.996308 (sd-merge)[1183]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Apr 30 00:17:45.996748 (sd-merge)[1183]: Merged extensions into '/usr'. Apr 30 00:17:46.000236 systemd[1]: Reloading requested from client PID 1157 ('systemd-sysext') (unit systemd-sysext.service)... Apr 30 00:17:46.000353 systemd[1]: Reloading... Apr 30 00:17:46.058209 zram_generator::config[1210]: No configuration found. Apr 30 00:17:46.133161 ldconfig[1152]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 00:17:46.163839 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. Apr 30 00:17:46.199693 systemd[1]: Reloading finished in 198 ms. Apr 30 00:17:46.232525 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 00:17:46.235709 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 00:17:46.253611 systemd[1]: Starting ensure-sysext.service... Apr 30 00:17:46.255712 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 00:17:46.263867 systemd[1]: Reloading requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... Apr 30 00:17:46.263881 systemd[1]: Reloading... Apr 30 00:17:46.272367 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 00:17:46.272612 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 00:17:46.273280 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 00:17:46.273489 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Apr 30 00:17:46.273538 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. Apr 30 00:17:46.275875 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 00:17:46.275889 systemd-tmpfiles[1246]: Skipping /boot Apr 30 00:17:46.282794 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 00:17:46.282813 systemd-tmpfiles[1246]: Skipping /boot Apr 30 00:17:46.311236 zram_generator::config[1276]: No configuration found. Apr 30 00:17:46.389905 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. Apr 30 00:17:46.425442 systemd[1]: Reloading finished in 161 ms. Apr 30 00:17:46.439020 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 00:17:46.453627 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:17:46.461345 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 00:17:46.463796 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 00:17:46.466218 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 00:17:46.471452 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 00:17:46.476512 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:17:46.478859 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 00:17:46.485053 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:17:46.486692 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:17:46.489858 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:17:46.492839 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:17:46.497311 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:17:46.499070 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 00:17:46.503244 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 00:17:46.505199 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:17:46.505366 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:17:46.507088 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:17:46.507278 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:17:46.508902 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:17:46.509020 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:17:46.516916 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:17:46.526497 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:17:46.530051 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:17:46.531775 systemd-udevd[1319]: Using default interface naming scheme 'v255'. Apr 30 00:17:46.534923 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:17:46.536099 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:17:46.537423 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 00:17:46.538955 augenrules[1345]: No rules Apr 30 00:17:46.552580 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 00:17:46.554115 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:17:46.558299 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 00:17:46.558469 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 00:17:46.559903 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 00:17:46.561552 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 00:17:46.563192 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:17:46.563328 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:17:46.565709 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:17:46.565832 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:17:46.568710 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:17:46.568862 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:17:46.571358 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 00:17:46.592197 systemd[1]: Finished ensure-sysext.service. Apr 30 00:17:46.596513 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Apr 30 00:17:46.599212 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 43 scanned by (udev-worker) (1369) Apr 30 00:17:46.610524 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 00:17:46.611737 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:17:46.612899 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:17:46.615615 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 00:17:46.618521 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:17:46.621477 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:17:46.625347 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:17:46.635383 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 00:17:46.641335 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 30 00:17:46.642443 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). Apr 30 00:17:46.642925 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:17:46.643376 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:17:46.646392 systemd-resolved[1312]: Positive Trust Anchors: Apr 30 00:17:46.646464 systemd-resolved[1312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 00:17:46.646496 systemd-resolved[1312]: 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 Apr 30 00:17:46.647695 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:17:46.647845 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:17:46.653613 augenrules[1384]: /sbin/augenrules: No change Apr 30 00:17:46.655736 systemd-resolved[1312]: Defaulting to hostname 'linux'. Apr 30 00:17:46.658349 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 00:17:46.659871 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 00:17:46.661220 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 00:17:46.663572 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:17:46.663710 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:17:46.665701 augenrules[1416]: No rules Apr 30 00:17:46.666889 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 00:17:46.667619 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 00:17:46.678605 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:17:46.681362 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 00:17:46.681430 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 00:17:46.688418 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 00:17:46.708458 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 00:17:46.716615 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:17:46.728762 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 00:17:46.730774 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 00:17:46.750518 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 00:17:46.752141 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 30 00:17:46.754029 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 00:17:46.755439 systemd-networkd[1399]: lo: Link UP Apr 30 00:17:46.755447 systemd-networkd[1399]: lo: Gained carrier Apr 30 00:17:46.758397 systemd-networkd[1399]: Enumeration completed Apr 30 00:17:46.758538 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 00:17:46.759808 systemd[1]: Reached target network.target - Network. Apr 30 00:17:46.760697 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:17:46.760708 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 00:17:46.761404 systemd-networkd[1399]: eth0: Link UP Apr 30 00:17:46.761413 systemd-networkd[1399]: eth0: Gained carrier Apr 30 00:17:46.761427 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:17:46.770362 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 00:17:46.780855 lvm[1432]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 00:17:46.783238 systemd-networkd[1399]: eth0: DHCPv4 address 10.0.0.152/16, gateway 10.0.0.1 acquired from 10.0.0.1 Apr 30 00:17:46.787507 systemd-timesyncd[1403]: Network configuration changed, trying to establish connection. Apr 30 00:17:47.200979 systemd-resolved[1312]: Clock change detected. Flushing caches. Apr 30 00:17:47.201086 systemd-timesyncd[1403]: Contacted time server 10.0.0.1:123 (10.0.0.1). Apr 30 00:17:47.201135 systemd-timesyncd[1403]: Initial clock synchronization to Wed 2025-04-30 00:17:47.200920 UTC. Apr 30 00:17:47.209396 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:17:47.230212 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 00:17:47.231816 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:17:47.233031 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 00:17:47.234258 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 00:17:47.235580 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 00:17:47.237048 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 00:17:47.238415 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 00:17:47.239786 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 00:17:47.241126 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 00:17:47.241164 systemd[1]: Reached target paths.target - Path Units. Apr 30 00:17:47.242240 systemd[1]: Reached target timers.target - Timer Units. Apr 30 00:17:47.244161 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 00:17:47.246702 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 00:17:47.256665 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 00:17:47.259082 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 00:17:47.260779 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 00:17:47.262008 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 00:17:47.263058 systemd[1]: Reached target basic.target - Basic System. Apr 30 00:17:47.264073 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 00:17:47.264105 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 00:17:47.265151 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 00:17:47.267158 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 00:17:47.268744 lvm[1442]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 00:17:47.270716 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 00:17:47.275629 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 00:17:47.277265 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). Apr 30 00:17:47.278069 jq[1445]: false Apr 30 00:17:47.279941 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 00:17:47.282750 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 00:17:47.285781 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 00:17:47.291959 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 00:17:47.293561 extend-filesystems[1446]: Found loop3 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found loop4 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found loop5 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda1 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda2 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda3 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found usr Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda4 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda6 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda7 Apr 30 00:17:47.295703 extend-filesystems[1446]: Found vda9 Apr 30 00:17:47.295703 extend-filesystems[1446]: Checking size of /dev/vda9 Apr 30 00:17:47.294346 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 00:17:47.294861 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 00:17:47.297014 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 00:17:47.300854 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 00:17:47.305608 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 00:17:47.320374 jq[1459]: true Apr 30 00:17:47.309017 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 00:17:47.309633 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 00:17:47.309916 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 00:17:47.310056 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 00:17:47.313744 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 00:17:47.314017 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 00:17:47.327470 extend-filesystems[1446]: Resized partition /dev/vda9 Apr 30 00:17:47.334011 dbus-daemon[1444]: [system] SELinux support is enabled Apr 30 00:17:47.334674 extend-filesystems[1473]: resize2fs 1.47.1 (20-May-2024) Apr 30 00:17:47.337777 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 43 scanned by (udev-worker) (1362) Apr 30 00:17:47.334830 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 00:17:47.347555 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Apr 30 00:17:47.346167 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). Apr 30 00:17:47.346199 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 00:17:47.347670 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). Apr 30 00:17:47.347686 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 00:17:47.350078 jq[1466]: true Apr 30 00:17:47.357993 (ntainerd)[1474]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 00:17:47.361632 update_engine[1458]: I20250430 00:17:47.361403 1458 main.cc:92] Flatcar Update Engine starting Apr 30 00:17:47.362665 systemd-logind[1453]: Watching system buttons on /dev/input/event0 (Power Button) Apr 30 00:17:47.363127 systemd-logind[1453]: New seat seat0. Apr 30 00:17:47.365168 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 00:17:47.366729 systemd[1]: Started update-engine.service - Update Engine. Apr 30 00:17:47.369063 update_engine[1458]: I20250430 00:17:47.368762 1458 update_check_scheduler.cc:74] Next update check in 7m55s Apr 30 00:17:47.373614 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Apr 30 00:17:47.375858 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 00:17:47.385352 extend-filesystems[1473]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Apr 30 00:17:47.385352 extend-filesystems[1473]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 30 00:17:47.385352 extend-filesystems[1473]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Apr 30 00:17:47.389453 extend-filesystems[1446]: Resized filesystem in /dev/vda9 Apr 30 00:17:47.386941 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 00:17:47.391193 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 00:17:47.428957 bash[1496]: Updated "/home/core/.ssh/authorized_keys" Apr 30 00:17:47.431120 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 00:17:47.433542 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Apr 30 00:17:47.437481 locksmithd[1480]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 00:17:47.538276 containerd[1474]: time="2025-04-30T00:17:47.538185446Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 30 00:17:47.564780 containerd[1474]: time="2025-04-30T00:17:47.564542006Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.565982 containerd[1474]: time="2025-04-30T00:17:47.565948166Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:17:47.566055 containerd[1474]: time="2025-04-30T00:17:47.566042246Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 00:17:47.566111 containerd[1474]: time="2025-04-30T00:17:47.566097926Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 00:17:47.566337 containerd[1474]: time="2025-04-30T00:17:47.566317046Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 00:17:47.566402 containerd[1474]: time="2025-04-30T00:17:47.566389686Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.566520 containerd[1474]: time="2025-04-30T00:17:47.566491046Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566563206Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566753166Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566768246Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566781086Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566790086Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.566856606Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.567034166Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.567125166Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.567137526Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.567214126Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 00:17:47.567411 containerd[1474]: time="2025-04-30T00:17:47.567255446Z" level=info msg="metadata content store policy set" policy=shared Apr 30 00:17:47.572858 containerd[1474]: time="2025-04-30T00:17:47.572792246Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 00:17:47.572973 containerd[1474]: time="2025-04-30T00:17:47.572957446Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 00:17:47.573042 containerd[1474]: time="2025-04-30T00:17:47.573016006Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 00:17:47.573100 containerd[1474]: time="2025-04-30T00:17:47.573087926Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 00:17:47.573152 containerd[1474]: time="2025-04-30T00:17:47.573140766Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 00:17:47.573336 containerd[1474]: time="2025-04-30T00:17:47.573315326Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 00:17:47.573688 containerd[1474]: time="2025-04-30T00:17:47.573667046Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 00:17:47.573875 containerd[1474]: time="2025-04-30T00:17:47.573852966Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 00:17:47.573955 containerd[1474]: time="2025-04-30T00:17:47.573942686Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 00:17:47.574012 containerd[1474]: time="2025-04-30T00:17:47.573998486Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 00:17:47.574078 containerd[1474]: time="2025-04-30T00:17:47.574064886Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574155 containerd[1474]: time="2025-04-30T00:17:47.574129486Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574211 containerd[1474]: time="2025-04-30T00:17:47.574198806Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574262 containerd[1474]: time="2025-04-30T00:17:47.574250086Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574317 containerd[1474]: time="2025-04-30T00:17:47.574304286Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574370 containerd[1474]: time="2025-04-30T00:17:47.574359046Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574423 containerd[1474]: time="2025-04-30T00:17:47.574411246Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574497 containerd[1474]: time="2025-04-30T00:17:47.574482926Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 00:17:47.574603 containerd[1474]: time="2025-04-30T00:17:47.574556286Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574675 containerd[1474]: time="2025-04-30T00:17:47.574661526Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574738 containerd[1474]: time="2025-04-30T00:17:47.574725926Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574804 containerd[1474]: time="2025-04-30T00:17:47.574791126Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574859 containerd[1474]: time="2025-04-30T00:17:47.574847086Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574914 containerd[1474]: time="2025-04-30T00:17:47.574902286Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.574973 containerd[1474]: time="2025-04-30T00:17:47.574960926Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575026 containerd[1474]: time="2025-04-30T00:17:47.575014246Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575093 containerd[1474]: time="2025-04-30T00:17:47.575076246Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575150 containerd[1474]: time="2025-04-30T00:17:47.575138486Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575204 containerd[1474]: time="2025-04-30T00:17:47.575191206Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575258 containerd[1474]: time="2025-04-30T00:17:47.575246126Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575313 containerd[1474]: time="2025-04-30T00:17:47.575300166Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575368 containerd[1474]: time="2025-04-30T00:17:47.575356566Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 00:17:47.575460 containerd[1474]: time="2025-04-30T00:17:47.575444046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575545 containerd[1474]: time="2025-04-30T00:17:47.575523646Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.575631 containerd[1474]: time="2025-04-30T00:17:47.575617446Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 00:17:47.575864 containerd[1474]: time="2025-04-30T00:17:47.575849766Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 00:17:47.575946 containerd[1474]: time="2025-04-30T00:17:47.575914126Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Apr 30 00:17:47.575998 containerd[1474]: time="2025-04-30T00:17:47.575986926Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 00:17:47.576049 containerd[1474]: time="2025-04-30T00:17:47.576036486Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 00:17:47.576108 containerd[1474]: time="2025-04-30T00:17:47.576095806Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.576164 containerd[1474]: time="2025-04-30T00:17:47.576153046Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 00:17:47.576212 containerd[1474]: time="2025-04-30T00:17:47.576200846Z" level=info msg="NRI interface is disabled by configuration." Apr 30 00:17:47.576264 containerd[1474]: time="2025-04-30T00:17:47.576252526Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 00:17:47.576728 containerd[1474]: time="2025-04-30T00:17:47.576674086Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Apr 30 00:17:47.576916 containerd[1474]: time="2025-04-30T00:17:47.576899246Z" level=info msg="Connect containerd service" Apr 30 00:17:47.577012 containerd[1474]: time="2025-04-30T00:17:47.576997046Z" level=info msg="using legacy CRI server" Apr 30 00:17:47.577066 containerd[1474]: time="2025-04-30T00:17:47.577052966Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 00:17:47.577429 containerd[1474]: time="2025-04-30T00:17:47.577412126Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 00:17:47.579185 containerd[1474]: time="2025-04-30T00:17:47.579149286Z" 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" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579487486Z" level=info msg="Start subscribing containerd event" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579548886Z" level=info msg="Start recovering state" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579622686Z" level=info msg="Start event monitor" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579650006Z" level=info msg="Start snapshots syncer" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579662606Z" level=info msg="Start cni network conf syncer for default" Apr 30 00:17:47.579828 containerd[1474]: time="2025-04-30T00:17:47.579669926Z" level=info msg="Start streaming server" Apr 30 00:17:47.580379 containerd[1474]: time="2025-04-30T00:17:47.580357206Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 00:17:47.580477 containerd[1474]: time="2025-04-30T00:17:47.580462486Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 00:17:47.580673 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 00:17:47.582488 containerd[1474]: time="2025-04-30T00:17:47.582466526Z" level=info msg="containerd successfully booted in 0.045235s" Apr 30 00:17:47.837218 sshd_keygen[1478]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 00:17:47.855752 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 00:17:47.872908 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 00:17:47.878266 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 00:17:47.878445 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 00:17:47.882129 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 00:17:47.897607 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 00:17:47.912861 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 00:17:47.915324 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Apr 30 00:17:47.916735 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 00:17:48.665747 systemd-networkd[1399]: eth0: Gained IPv6LL Apr 30 00:17:48.668351 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 00:17:48.670181 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 00:17:48.682856 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Apr 30 00:17:48.685407 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:17:48.687605 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 00:17:48.702333 systemd[1]: coreos-metadata.service: Deactivated successfully. Apr 30 00:17:48.702552 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Apr 30 00:17:48.705353 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 00:17:48.709292 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 00:17:49.220625 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:17:49.222164 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 00:17:49.226625 systemd[1]: Startup finished in 642ms (kernel) + 4.461s (initrd) + 3.680s (userspace) = 8.784s. Apr 30 00:17:49.227250 (kubelet)[1549]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 30 00:17:49.645840 kubelet[1549]: E0430 00:17:49.645721 1549 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" Apr 30 00:17:49.648316 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 30 00:17:49.648466 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 30 00:17:53.754182 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 00:17:53.755343 systemd[1]: Started sshd@0-10.0.0.152:22-10.0.0.1:55650.service - OpenSSH per-connection server daemon (10.0.0.1:55650). Apr 30 00:17:53.816996 sshd[1562]: Accepted publickey for core from 10.0.0.1 port 55650 ssh2: RSA SHA256:zkGkOea9Md/Gy5pSC8YV7FyThSdabJqqYiI+4lXRQbg Apr 30 00:17:53.818773 sshd-session[1562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:17:53.831207 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 00:17:53.842789 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 00:17:53.844526 systemd-logind[1453]: New session 1 of user core. Apr 30 00:17:53.851476 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 00:17:53.863808 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 00:17:53.866105 (systemd)[1566]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 00:17:53.940676 systemd[1566]: Queued start job for default target default.target. Apr 30 00:17:53.952408 systemd[1566]: Created slice app.slice - User Application Slice. Apr 30 00:17:53.952428 systemd[1566]: Reached target paths.target - Paths. Apr 30 00:17:53.952440 systemd[1566]: Reached target timers.target - Timers. Apr 30 00:17:53.953616 systemd[1566]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 00:17:53.962771 systemd[1566]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 00:17:53.962827 systemd[1566]: Reached target sockets.target - Sockets. Apr 30 00:17:53.962840 systemd[1566]: Reached target basic.target - Basic System. Apr 30 00:17:53.962873 systemd[1566]: Reached target default.target - Main User Target. Apr 30 00:17:53.962896 systemd[1566]: Startup finished in 91ms. Apr 30 00:17:53.963130 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 00:17:53.964405 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 00:17:54.025663 systemd[1]: Started sshd@1-10.0.0.152:22-10.0.0.1:55662.service - OpenSSH per-connection server daemon (10.0.0.1:55662). Apr 30 00:17:54.066187 sshd[1577]: Accepted publickey for core from 10.0.0.1 port 55662 ssh2: RSA SHA256:zkGkOea9Md/Gy5pSC8YV7FyThSdabJqqYiI+4lXRQbg Apr 30 00:17:54.067288 sshd-session[1577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:17:54.071324 systemd-logind[1453]: New session 2 of user core. Apr 30 00:17:54.082757 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 00:17:54.133223 sshd[1579]: Connection closed by 10.0.0.1 port 55662 Apr 30 00:17:54.133654 sshd-session[1577]: pam_unix(sshd:session): session closed for user core Apr 30 00:17:54.148752 systemd[1]: sshd@1-10.0.0.152:22-10.0.0.1:55662.service: Deactivated successfully. Apr 30 00:17:54.150013 systemd[1]: session-2.scope: Deactivated successfully. Apr 30 00:17:54.151203 systemd-logind[1453]: Session 2 logged out. Waiting for processes to exit. Apr 30 00:17:54.152286 systemd[1]: Started sshd@2-10.0.0.152:22-10.0.0.1:55676.service - OpenSSH per-connection server daemon (10.0.0.1:55676). Apr 30 00:17:54.153061 systemd-logind[1453]: Removed session 2. Apr 30 00:17:54.190894 sshd[1584]: Accepted publickey for core from 10.0.0.1 port 55676 ssh2: RSA SHA256:zkGkOea9Md/Gy5pSC8YV7FyThSdabJqqYiI+4lXRQbg Apr 30 00:17:54.192018 sshd-session[1584]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:17:54.195606 systemd-logind[1453]: New session 3 of user core. Apr 30 00:17:54.208755 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 00:17:54.256090 sshd[1586]: Connection closed by 10.0.0.1 port 55676 Apr 30 00:17:54.256351 sshd-session[1584]: pam_unix(sshd:session): session closed for user core Apr 30 00:17:54.267916 systemd[1]: sshd@2-10.0.0.152:22-10.0.0.1:55676.service: Deactivated successfully. Apr 30 00:17:54.269341 systemd[1]: session-3.scope: Deactivated successfully. Apr 30 00:17:54.271585 systemd-logind[1453]: Session 3 logged out. Waiting for processes to exit. Apr 30 00:17:54.272736 systemd[1]: Started sshd@3-10.0.0.152:22-10.0.0.1:55678.service - OpenSSH per-connection server daemon (10.0.0.1:55678). Apr 30 00:17:54.273345 systemd-logind[1453]: Removed session 3. Apr 30 00:17:54.312074 sshd[1591]: Accepted publickey for core from 10.0.0.1 port 55678 ssh2: RSA SHA256:zkGkOea9Md/Gy5pSC8YV7FyThSdabJqqYiI+4lXRQbg Apr 30 00:17:54.313311 sshd-session[1591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:17:54.316768 systemd-logind[1453]: New session 4 of user core. Apr 30 00:17:54.335730 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 00:17:54.386679 sshd[1593]: Connection closed by 10.0.0.1 port 55678 Apr 30 00:17:54.386943 sshd-session[1591]: pam_unix(sshd:session): session closed for user core Apr 30 00:17:54.400934 systemd[1]: sshd@3-10.0.0.152:22-10.0.0.1:55678.service: Deactivated successfully. Apr 30 00:17:54.402304 systemd[1]: session-4.scope: Deactivated successfully. Apr 30 00:17:54.403502 systemd-logind[1453]: Session 4 logged out. Waiting for processes to exit. Apr 30 00:17:54.404691 systemd[1]: Started sshd@4-10.0.0.152:22-10.0.0.1:55692.service - OpenSSH per-connection server daemon (10.0.0.1:55692). Apr 30 00:17:54.405374 systemd-logind[1453]: Removed session 4. Apr 30 00:17:54.443938 sshd[1598]: Accepted publickey for core from 10.0.0.1 port 55692 ssh2: RSA SHA256:zkGkOea9Md/Gy5pSC8YV7FyThSdabJqqYiI+4lXRQbg Apr 30 00:17:54.445113 sshd-session[1598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:17:54.448624 systemd-logind[1453]: New session 5 of user core. Apr 30 00:17:54.462733 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 00:17:54.537098 sudo[1601]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 30 00:17:54.537372 sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 00:17:54.562847 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Apr 30 00:17:54.578418 systemd[1]: coreos-metadata.service: Deactivated successfully. Apr 30 00:17:54.579771 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Apr 30 00:17:54.984747 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:17:54.996851 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:17:55.017815 systemd[1]: Reloading requested from client PID 1644 ('systemctl') (unit session-5.scope)... Apr 30 00:17:55.017837 systemd[1]: Reloading... Apr 30 00:17:55.076599 zram_generator::config[1680]: No configuration found. Apr 30 00:17:55.268283 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. Apr 30 00:17:55.320846 systemd[1]: Reloading finished in 302 ms. Apr 30 00:17:55.362073 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Apr 30 00:17:55.362139 systemd[1]: kubelet.service: Failed with result 'signal'. Apr 30 00:17:55.363627 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:17:55.366665 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:17:55.466135 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:17:55.470183 (kubelet)[1728]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 30 00:17:55.503186 kubelet[1728]: 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. Apr 30 00:17:55.503186 kubelet[1728]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Apr 30 00:17:55.503186 kubelet[1728]: 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. Apr 30 00:17:55.503514 kubelet[1728]: I0430 00:17:55.503267 1728 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" Apr 30 00:17:56.338941 kubelet[1728]: I0430 00:17:56.338897 1728 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" Apr 30 00:17:56.338941 kubelet[1728]: I0430 00:17:56.338929 1728 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 30 00:17:56.339219 kubelet[1728]: I0430 00:17:56.339192 1728 server.go:954] "Client rotation is on, will bootstrap in background" Apr 30 00:17:56.387748 kubelet[1728]: I0430 00:17:56.387705 1728 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 30 00:17:56.393678 kubelet[1728]: E0430 00:17:56.393617 1728 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Apr 30 00:17:56.393678 kubelet[1728]: I0430 00:17:56.393647 1728 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Apr 30 00:17:56.396177 kubelet[1728]: I0430 00:17:56.396156 1728 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 30 00:17:56.396846 kubelet[1728]: I0430 00:17:56.396793 1728 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 30 00:17:56.396999 kubelet[1728]: I0430 00:17:56.396835 1728 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.152","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} Apr 30 00:17:56.397096 kubelet[1728]: I0430 00:17:56.397069 1728 topology_manager.go:138] "Creating topology manager with none policy" Apr 30 00:17:56.397096 kubelet[1728]: I0430 00:17:56.397077 1728 container_manager_linux.go:304] "Creating device plugin manager" Apr 30 00:17:56.397284 kubelet[1728]: I0430 00:17:56.397267 1728 state_mem.go:36] "Initialized new in-memory state store" Apr 30 00:17:56.401342 kubelet[1728]: I0430 00:17:56.401220 1728 kubelet.go:446] "Attempting to sync node with API server" Apr 30 00:17:56.401342 kubelet[1728]: I0430 00:17:56.401247 1728 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 30 00:17:56.401342 kubelet[1728]: I0430 00:17:56.401273 1728 kubelet.go:352] "Adding apiserver pod source" Apr 30 00:17:56.401342 kubelet[1728]: I0430 00:17:56.401284 1728 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 30 00:17:56.401450 kubelet[1728]: E0430 00:17:56.401341 1728 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:17:56.401450 kubelet[1728]: E0430 00:17:56.401404 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:17:56.403907 kubelet[1728]: I0430 00:17:56.403881 1728 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Apr 30 00:17:56.404519 kubelet[1728]: I0430 00:17:56.404501 1728 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Apr 30 00:17:56.404648 kubelet[1728]: W0430 00:17:56.404635 1728 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 30 00:17:56.405439 kubelet[1728]: I0430 00:17:56.405419 1728 watchdog_linux.go:99] "Systemd watchdog is not enabled" Apr 30 00:17:56.405503 kubelet[1728]: I0430 00:17:56.405454 1728 server.go:1287] "Started kubelet" Apr 30 00:17:56.406232 kubelet[1728]: I0430 00:17:56.405640 1728 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Apr 30 00:17:56.407014 kubelet[1728]: I0430 00:17:56.406653 1728 server.go:490] "Adding debug handlers to kubelet server" Apr 30 00:17:56.407648 kubelet[1728]: I0430 00:17:56.407583 1728 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 30 00:17:56.407879 kubelet[1728]: I0430 00:17:56.407852 1728 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 30 00:17:56.407879 kubelet[1728]: I0430 00:17:56.407876 1728 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 30 00:17:56.407986 kubelet[1728]: I0430 00:17:56.407971 1728 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Apr 30 00:17:56.408788 kubelet[1728]: E0430 00:17:56.408743 1728 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.152\" not found" Apr 30 00:17:56.408788 kubelet[1728]: I0430 00:17:56.408790 1728 volume_manager.go:297] "Starting Kubelet Volume Manager" Apr 30 00:17:56.410355 kubelet[1728]: I0430 00:17:56.410330 1728 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Apr 30 00:17:56.410511 kubelet[1728]: I0430 00:17:56.410498 1728 reconciler.go:26] "Reconciler: start to sync state" Apr 30 00:17:56.411667 kubelet[1728]: I0430 00:17:56.411643 1728 factory.go:221] Registration of the systemd container factory successfully Apr 30 00:17:56.411862 kubelet[1728]: I0430 00:17:56.411841 1728 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 Apr 30 00:17:56.414481 kubelet[1728]: I0430 00:17:56.414449 1728 factory.go:221] Registration of the containerd container factory successfully Apr 30 00:17:56.417995 kubelet[1728]: E0430 00:17:56.417976 1728 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 30 00:17:56.432038 kubelet[1728]: I0430 00:17:56.432017 1728 cpu_manager.go:221] "Starting CPU manager" policy="none" Apr 30 00:17:56.432038 kubelet[1728]: I0430 00:17:56.432033 1728 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Apr 30 00:17:56.432137 kubelet[1728]: I0430 00:17:56.432055 1728 state_mem.go:36] "Initialized new in-memory state store" Apr 30 00:17:56.433910 kubelet[1728]: E0430 00:17:56.433872 1728 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.152\" not found" node="10.0.0.152" Apr 30 00:17:56.499195 kubelet[1728]: I0430 00:17:56.499145 1728 policy_none.go:49] "None policy: Start" Apr 30 00:17:56.499195 kubelet[1728]: I0430 00:17:56.499180 1728 memory_manager.go:186] "Starting memorymanager" policy="None" Apr 30 00:17:56.499195 kubelet[1728]: I0430 00:17:56.499193 1728 state_mem.go:35] "Initializing new in-memory state store" Apr 30 00:17:56.504987 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 30 00:17:56.509641 kubelet[1728]: E0430 00:17:56.509613 1728 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.152\" not found" Apr 30 00:17:56.515631 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 30 00:17:56.518312 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 30 00:17:56.519338 kubelet[1728]: I0430 00:17:56.519290 1728 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Apr 30 00:17:56.520400 kubelet[1728]: I0430 00:17:56.520381 1728 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Apr 30 00:17:56.520803 kubelet[1728]: I0430 00:17:56.520467 1728 status_manager.go:227] "Starting to sync pod status with apiserver" Apr 30 00:17:56.520803 kubelet[1728]: I0430 00:17:56.520505 1728 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Apr 30 00:17:56.520803 kubelet[1728]: I0430 00:17:56.520512 1728 kubelet.go:2388] "Starting kubelet main sync loop" Apr 30 00:17:56.520803 kubelet[1728]: E0430 00:17:56.520555 1728 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Apr 30 00:17:56.526519 kubelet[1728]: I0430 00:17:56.526496 1728 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 30 00:17:56.526840 kubelet[1728]: I0430 00:17:56.526823 1728 eviction_manager.go:189] "Eviction manager: starting control loop" Apr 30 00:17:56.526931 kubelet[1728]: I0430 00:17:56.526902 1728 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 30 00:17:56.527141 kubelet[1728]: I0430 00:17:56.527125 1728 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 30 00:17:56.527826 kubelet[1728]: E0430 00:17:56.527794 1728 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Apr 30 00:17:56.527879 kubelet[1728]: E0430 00:17:56.527841 1728 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.152\" not found" Apr 30 00:17:56.628507 kubelet[1728]: I0430 00:17:56.628396 1728 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.152" Apr 30 00:17:56.632101 kubelet[1728]: I0430 00:17:56.632067 1728 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.152" Apr 30 00:17:56.737232 kubelet[1728]: I0430 00:17:56.737181 1728 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 30 00:17:56.737583 containerd[1474]: time="2025-04-30T00:17:56.737540886Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 30 00:17:56.737858 kubelet[1728]: I0430 00:17:56.737743 1728 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 30 00:17:56.991675 sudo[1601]: pam_unix(sudo:session): session closed for user root Apr 30 00:17:56.993613 sshd[1600]: Connection closed by 10.0.0.1 port 55692 Apr 30 00:17:56.994071 sshd-session[1598]: pam_unix(sshd:session): session closed for user core Apr 30 00:17:56.997525 systemd-logind[1453]: Session 5 logged out. Waiting for processes to exit. Apr 30 00:17:56.997840 systemd[1]: sshd@4-10.0.0.152:22-10.0.0.1:55692.service: Deactivated successfully. Apr 30 00:17:57.000347 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 00:17:57.001323 systemd-logind[1453]: Removed session 5. Apr 30 00:17:57.340764 kubelet[1728]: I0430 00:17:57.340716 1728 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 30 00:17:57.340915 kubelet[1728]: W0430 00:17:57.340887 1728 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 Apr 30 00:17:57.341065 kubelet[1728]: W0430 00:17:57.341035 1728 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 Apr 30 00:17:57.341435 kubelet[1728]: W0430 00:17:57.341053 1728 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Apr 30 00:17:57.402423 kubelet[1728]: E0430 00:17:57.402355 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:17:57.402423 kubelet[1728]: I0430 00:17:57.402368 1728 apiserver.go:52] "Watching apiserver" Apr 30 00:17:57.410877 kubelet[1728]: I0430 00:17:57.410840 1728 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Apr 30 00:17:57.411537 systemd[1]: Created slice kubepods-besteffort-pod52f8d3bf_1f89_4c49_8836_347d5aa80684.slice - libcontainer container kubepods-besteffort-pod52f8d3bf_1f89_4c49_8836_347d5aa80684.slice. Apr 30 00:17:57.416923 kubelet[1728]: I0430 00:17:57.416881 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b883e804-12a8-4fde-8150-ac459a5bb405-xtables-lock\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.416923 kubelet[1728]: I0430 00:17:57.416920 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/52f8d3bf-1f89-4c49-8836-347d5aa80684-xtables-lock\") pod \"kube-proxy-hbxmp\" (UID: \"52f8d3bf-1f89-4c49-8836-347d5aa80684\") " pod="kube-system/kube-proxy-hbxmp" Apr 30 00:17:57.417052 kubelet[1728]: I0430 00:17:57.416941 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b883e804-12a8-4fde-8150-ac459a5bb405-run\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.417052 kubelet[1728]: I0430 00:17:57.416957 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b883e804-12a8-4fde-8150-ac459a5bb405-flannel-cfg\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.417052 kubelet[1728]: I0430 00:17:57.416974 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f9htk\" (UniqueName: \"kubernetes.io/projected/b883e804-12a8-4fde-8150-ac459a5bb405-kube-api-access-f9htk\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.417052 kubelet[1728]: I0430 00:17:57.416989 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/52f8d3bf-1f89-4c49-8836-347d5aa80684-kube-proxy\") pod \"kube-proxy-hbxmp\" (UID: \"52f8d3bf-1f89-4c49-8836-347d5aa80684\") " pod="kube-system/kube-proxy-hbxmp" Apr 30 00:17:57.417052 kubelet[1728]: I0430 00:17:57.417004 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/52f8d3bf-1f89-4c49-8836-347d5aa80684-lib-modules\") pod \"kube-proxy-hbxmp\" (UID: \"52f8d3bf-1f89-4c49-8836-347d5aa80684\") " pod="kube-system/kube-proxy-hbxmp" Apr 30 00:17:57.417165 kubelet[1728]: I0430 00:17:57.417018 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g592r\" (UniqueName: \"kubernetes.io/projected/52f8d3bf-1f89-4c49-8836-347d5aa80684-kube-api-access-g592r\") pod \"kube-proxy-hbxmp\" (UID: \"52f8d3bf-1f89-4c49-8836-347d5aa80684\") " pod="kube-system/kube-proxy-hbxmp" Apr 30 00:17:57.417165 kubelet[1728]: I0430 00:17:57.417032 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b883e804-12a8-4fde-8150-ac459a5bb405-cni-plugin\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.417165 kubelet[1728]: I0430 00:17:57.417047 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b883e804-12a8-4fde-8150-ac459a5bb405-cni\") pod \"kube-flannel-ds-7hgh2\" (UID: \"b883e804-12a8-4fde-8150-ac459a5bb405\") " pod="kube-flannel/kube-flannel-ds-7hgh2" Apr 30 00:17:57.422959 systemd[1]: Created slice kubepods-burstable-podb883e804_12a8_4fde_8150_ac459a5bb405.slice - libcontainer container kubepods-burstable-podb883e804_12a8_4fde_8150_ac459a5bb405.slice. Apr 30 00:17:57.721834 kubelet[1728]: E0430 00:17:57.721720 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:17:57.722665 containerd[1474]: time="2025-04-30T00:17:57.722620726Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hbxmp,Uid:52f8d3bf-1f89-4c49-8836-347d5aa80684,Namespace:kube-system,Attempt:0,}" Apr 30 00:17:57.734741 kubelet[1728]: E0430 00:17:57.734704 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:17:57.735171 containerd[1474]: time="2025-04-30T00:17:57.735138486Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7hgh2,Uid:b883e804-12a8-4fde-8150-ac459a5bb405,Namespace:kube-flannel,Attempt:0,}" Apr 30 00:17:58.263333 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount241198817.mount: Deactivated successfully. Apr 30 00:17:58.267867 containerd[1474]: time="2025-04-30T00:17:58.267795486Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:17:58.268403 containerd[1474]: time="2025-04-30T00:17:58.268374206Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Apr 30 00:17:58.270756 containerd[1474]: time="2025-04-30T00:17:58.270717046Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:17:58.272336 containerd[1474]: time="2025-04-30T00:17:58.272060926Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:17:58.273907 containerd[1474]: time="2025-04-30T00:17:58.273868486Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 30 00:17:58.276410 containerd[1474]: time="2025-04-30T00:17:58.276366126Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:17:58.277002 containerd[1474]: time="2025-04-30T00:17:58.276952566Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 554.2104ms" Apr 30 00:17:58.277782 containerd[1474]: time="2025-04-30T00:17:58.277738646Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 542.50472ms" Apr 30 00:17:58.364314 containerd[1474]: time="2025-04-30T00:17:58.364006406Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:17:58.364314 containerd[1474]: time="2025-04-30T00:17:58.364074486Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:17:58.364314 containerd[1474]: time="2025-04-30T00:17:58.364085246Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:17:58.364314 containerd[1474]: time="2025-04-30T00:17:58.364156886Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:17:58.365307 containerd[1474]: time="2025-04-30T00:17:58.365026966Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:17:58.365307 containerd[1474]: time="2025-04-30T00:17:58.365073326Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:17:58.365307 containerd[1474]: time="2025-04-30T00:17:58.365088326Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:17:58.365307 containerd[1474]: time="2025-04-30T00:17:58.365164246Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:17:58.403382 kubelet[1728]: E0430 00:17:58.403278 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:17:58.477738 systemd[1]: Started cri-containerd-141d5b0c17808208b92b5289c80c5f2ed70c83e85a83be39eeed1a00c53b833e.scope - libcontainer container 141d5b0c17808208b92b5289c80c5f2ed70c83e85a83be39eeed1a00c53b833e. Apr 30 00:17:58.479097 systemd[1]: Started cri-containerd-25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f.scope - libcontainer container 25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f. Apr 30 00:17:58.499593 containerd[1474]: time="2025-04-30T00:17:58.499542166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hbxmp,Uid:52f8d3bf-1f89-4c49-8836-347d5aa80684,Namespace:kube-system,Attempt:0,} returns sandbox id \"141d5b0c17808208b92b5289c80c5f2ed70c83e85a83be39eeed1a00c53b833e\"" Apr 30 00:17:58.500760 kubelet[1728]: E0430 00:17:58.500722 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:17:58.502873 containerd[1474]: time="2025-04-30T00:17:58.502783726Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" Apr 30 00:17:58.513257 containerd[1474]: time="2025-04-30T00:17:58.513194006Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7hgh2,Uid:b883e804-12a8-4fde-8150-ac459a5bb405,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\"" Apr 30 00:17:58.516313 kubelet[1728]: E0430 00:17:58.516128 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:17:59.404217 kubelet[1728]: E0430 00:17:59.404168 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:17:59.452642 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3130873748.mount: Deactivated successfully. Apr 30 00:17:59.687376 containerd[1474]: time="2025-04-30T00:17:59.686939126Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:17:59.687836 containerd[1474]: time="2025-04-30T00:17:59.687697646Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=27370353" Apr 30 00:17:59.688585 containerd[1474]: time="2025-04-30T00:17:59.688503966Z" level=info msg="ImageCreate event name:\"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:17:59.690521 containerd[1474]: time="2025-04-30T00:17:59.690445286Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:17:59.691275 containerd[1474]: time="2025-04-30T00:17:59.691241126Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"27369370\" in 1.18822928s" Apr 30 00:17:59.691275 containerd[1474]: time="2025-04-30T00:17:59.691273486Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\"" Apr 30 00:17:59.692611 containerd[1474]: time="2025-04-30T00:17:59.692558406Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 30 00:17:59.693582 containerd[1474]: time="2025-04-30T00:17:59.693512806Z" level=info msg="CreateContainer within sandbox \"141d5b0c17808208b92b5289c80c5f2ed70c83e85a83be39eeed1a00c53b833e\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 30 00:17:59.709678 containerd[1474]: time="2025-04-30T00:17:59.709625526Z" level=info msg="CreateContainer within sandbox \"141d5b0c17808208b92b5289c80c5f2ed70c83e85a83be39eeed1a00c53b833e\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b60e4ed644bb12d1ad30111c9eab94f5e1aa8579127e63ea521f305e276087e7\"" Apr 30 00:17:59.710532 containerd[1474]: time="2025-04-30T00:17:59.710501646Z" level=info msg="StartContainer for \"b60e4ed644bb12d1ad30111c9eab94f5e1aa8579127e63ea521f305e276087e7\"" Apr 30 00:17:59.743830 systemd[1]: Started cri-containerd-b60e4ed644bb12d1ad30111c9eab94f5e1aa8579127e63ea521f305e276087e7.scope - libcontainer container b60e4ed644bb12d1ad30111c9eab94f5e1aa8579127e63ea521f305e276087e7. Apr 30 00:17:59.779345 containerd[1474]: time="2025-04-30T00:17:59.778828246Z" level=info msg="StartContainer for \"b60e4ed644bb12d1ad30111c9eab94f5e1aa8579127e63ea521f305e276087e7\" returns successfully" Apr 30 00:18:00.405262 kubelet[1728]: E0430 00:18:00.405214 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:00.529668 kubelet[1728]: E0430 00:18:00.529634 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:00.538600 kubelet[1728]: I0430 00:18:00.538441 1728 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-hbxmp" podStartSLOduration=3.347899366 podStartE2EDuration="4.538425086s" podCreationTimestamp="2025-04-30 00:17:56 +0000 UTC" firstStartedPulling="2025-04-30 00:17:58.501623406 +0000 UTC m=+3.028320641" lastFinishedPulling="2025-04-30 00:17:59.692149126 +0000 UTC m=+4.218846361" observedRunningTime="2025-04-30 00:18:00.538395606 +0000 UTC m=+5.065092841" watchObservedRunningTime="2025-04-30 00:18:00.538425086 +0000 UTC m=+5.065122321" Apr 30 00:18:00.792200 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1101949104.mount: Deactivated successfully. Apr 30 00:18:00.819130 containerd[1474]: time="2025-04-30T00:18:00.819070566Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:00.819739 containerd[1474]: time="2025-04-30T00:18:00.819694966Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Apr 30 00:18:00.820450 containerd[1474]: time="2025-04-30T00:18:00.820416366Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:00.822611 containerd[1474]: time="2025-04-30T00:18:00.822580326Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:00.823697 containerd[1474]: time="2025-04-30T00:18:00.823641806Z" 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.13098s" Apr 30 00:18:00.823697 containerd[1474]: time="2025-04-30T00:18:00.823678966Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Apr 30 00:18:00.825475 containerd[1474]: time="2025-04-30T00:18:00.825430966Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 30 00:18:00.838189 containerd[1474]: time="2025-04-30T00:18:00.838131886Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958\"" Apr 30 00:18:00.838754 containerd[1474]: time="2025-04-30T00:18:00.838726246Z" level=info msg="StartContainer for \"c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958\"" Apr 30 00:18:00.861764 systemd[1]: Started cri-containerd-c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958.scope - libcontainer container c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958. Apr 30 00:18:00.882666 containerd[1474]: time="2025-04-30T00:18:00.882624086Z" level=info msg="StartContainer for \"c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958\" returns successfully" Apr 30 00:18:00.891033 systemd[1]: cri-containerd-c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958.scope: Deactivated successfully. Apr 30 00:18:01.051352 containerd[1474]: time="2025-04-30T00:18:01.051173406Z" level=info msg="shim disconnected" id=c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958 namespace=k8s.io Apr 30 00:18:01.051352 containerd[1474]: time="2025-04-30T00:18:01.051230566Z" level=warning msg="cleaning up after shim disconnected" id=c9d07854cdc866d76a1123729012a2de917bc5da22abfa24a4159eab819d0958 namespace=k8s.io Apr 30 00:18:01.051352 containerd[1474]: time="2025-04-30T00:18:01.051238726Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 00:18:01.406145 kubelet[1728]: E0430 00:18:01.406091 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:01.532391 kubelet[1728]: E0430 00:18:01.532359 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:01.532623 kubelet[1728]: E0430 00:18:01.532559 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:01.533231 containerd[1474]: time="2025-04-30T00:18:01.533202406Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 30 00:18:02.406839 kubelet[1728]: E0430 00:18:02.406792 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:02.795078 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount88352929.mount: Deactivated successfully. Apr 30 00:18:03.281597 containerd[1474]: time="2025-04-30T00:18:03.281323966Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:03.282475 containerd[1474]: time="2025-04-30T00:18:03.282195606Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Apr 30 00:18:03.283285 containerd[1474]: time="2025-04-30T00:18:03.283243846Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:03.286816 containerd[1474]: time="2025-04-30T00:18:03.286744006Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:03.287856 containerd[1474]: time="2025-04-30T00:18:03.287820006Z" 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.75457932s" Apr 30 00:18:03.287856 containerd[1474]: time="2025-04-30T00:18:03.287852166Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Apr 30 00:18:03.290352 containerd[1474]: time="2025-04-30T00:18:03.290325126Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 30 00:18:03.303805 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2043262885.mount: Deactivated successfully. Apr 30 00:18:03.308980 containerd[1474]: time="2025-04-30T00:18:03.308897366Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b\"" Apr 30 00:18:03.309558 containerd[1474]: time="2025-04-30T00:18:03.309513886Z" level=info msg="StartContainer for \"7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b\"" Apr 30 00:18:03.338784 systemd[1]: Started cri-containerd-7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b.scope - libcontainer container 7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b. Apr 30 00:18:03.363152 containerd[1474]: time="2025-04-30T00:18:03.363110446Z" level=info msg="StartContainer for \"7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b\" returns successfully" Apr 30 00:18:03.374064 systemd[1]: cri-containerd-7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b.scope: Deactivated successfully. Apr 30 00:18:03.407200 kubelet[1728]: E0430 00:18:03.407154 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:03.460578 kubelet[1728]: I0430 00:18:03.460524 1728 kubelet_node_status.go:502] "Fast updating node status as it just became ready" Apr 30 00:18:03.489229 containerd[1474]: time="2025-04-30T00:18:03.489171086Z" level=info msg="shim disconnected" id=7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b namespace=k8s.io Apr 30 00:18:03.489229 containerd[1474]: time="2025-04-30T00:18:03.489223606Z" level=warning msg="cleaning up after shim disconnected" id=7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b namespace=k8s.io Apr 30 00:18:03.489229 containerd[1474]: time="2025-04-30T00:18:03.489231726Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 00:18:03.537173 kubelet[1728]: E0430 00:18:03.537049 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:03.539050 containerd[1474]: time="2025-04-30T00:18:03.539001446Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 30 00:18:03.553733 containerd[1474]: time="2025-04-30T00:18:03.553629766Z" level=info msg="CreateContainer within sandbox \"25bb8beaf9a5ebd8c125db7d0db3dad1010584f87aa2fa8897155579cfd0761f\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"9391c1addd829089d102c5f87268e6a99c24ce9a8a65b22b3a0c9d8a942470d7\"" Apr 30 00:18:03.554137 containerd[1474]: time="2025-04-30T00:18:03.554110766Z" level=info msg="StartContainer for \"9391c1addd829089d102c5f87268e6a99c24ce9a8a65b22b3a0c9d8a942470d7\"" Apr 30 00:18:03.579770 systemd[1]: Started cri-containerd-9391c1addd829089d102c5f87268e6a99c24ce9a8a65b22b3a0c9d8a942470d7.scope - libcontainer container 9391c1addd829089d102c5f87268e6a99c24ce9a8a65b22b3a0c9d8a942470d7. Apr 30 00:18:03.601914 containerd[1474]: time="2025-04-30T00:18:03.601870566Z" level=info msg="StartContainer for \"9391c1addd829089d102c5f87268e6a99c24ce9a8a65b22b3a0c9d8a942470d7\" returns successfully" Apr 30 00:18:03.795869 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-7dbff8eaf159ab5c9cf02c504f85c02e39f816a0ec567c5cc5c1cfa5c77bf55b-rootfs.mount: Deactivated successfully. Apr 30 00:18:04.407671 kubelet[1728]: E0430 00:18:04.407611 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:04.540528 kubelet[1728]: E0430 00:18:04.540404 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:04.566014 kubelet[1728]: I0430 00:18:04.565950 1728 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7hgh2" podStartSLOduration=3.793850286 podStartE2EDuration="8.565930726s" podCreationTimestamp="2025-04-30 00:17:56 +0000 UTC" firstStartedPulling="2025-04-30 00:17:58.517111766 +0000 UTC m=+3.043808961" lastFinishedPulling="2025-04-30 00:18:03.289192166 +0000 UTC m=+7.815889401" observedRunningTime="2025-04-30 00:18:04.565736686 +0000 UTC m=+9.092433921" watchObservedRunningTime="2025-04-30 00:18:04.565930726 +0000 UTC m=+9.092627961" Apr 30 00:18:04.683708 systemd-networkd[1399]: flannel.1: Link UP Apr 30 00:18:04.683714 systemd-networkd[1399]: flannel.1: Gained carrier Apr 30 00:18:05.408367 kubelet[1728]: E0430 00:18:05.408313 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:05.541839 kubelet[1728]: E0430 00:18:05.541800 1728 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Apr 30 00:18:06.201742 systemd-networkd[1399]: flannel.1: Gained IPv6LL Apr 30 00:18:06.409486 kubelet[1728]: E0430 00:18:06.409419 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:07.410214 kubelet[1728]: E0430 00:18:07.410159 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:07.881726 systemd[1]: Created slice kubepods-besteffort-podadbc1e5d_5dad_4d20_b239_4ed1a47df8b2.slice - libcontainer container kubepods-besteffort-podadbc1e5d_5dad_4d20_b239_4ed1a47df8b2.slice. Apr 30 00:18:07.980130 kubelet[1728]: I0430 00:18:07.980088 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zqdkt\" (UniqueName: \"kubernetes.io/projected/adbc1e5d-5dad-4d20-b239-4ed1a47df8b2-kube-api-access-zqdkt\") pod \"nginx-deployment-7fcdb87857-6f4jq\" (UID: \"adbc1e5d-5dad-4d20-b239-4ed1a47df8b2\") " pod="default/nginx-deployment-7fcdb87857-6f4jq" Apr 30 00:18:08.184872 containerd[1474]: time="2025-04-30T00:18:08.184735166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6f4jq,Uid:adbc1e5d-5dad-4d20-b239-4ed1a47df8b2,Namespace:default,Attempt:0,}" Apr 30 00:18:08.233469 systemd-networkd[1399]: cni0: Link UP Apr 30 00:18:08.233476 systemd-networkd[1399]: cni0: Gained carrier Apr 30 00:18:08.233730 systemd-networkd[1399]: cni0: Lost carrier Apr 30 00:18:08.247398 kernel: cni0: port 1(vetha2b3a498) entered blocking state Apr 30 00:18:08.247505 kernel: cni0: port 1(vetha2b3a498) entered disabled state Apr 30 00:18:08.247529 kernel: vetha2b3a498: entered allmulticast mode Apr 30 00:18:08.247547 kernel: vetha2b3a498: entered promiscuous mode Apr 30 00:18:08.248037 kernel: cni0: port 1(vetha2b3a498) entered blocking state Apr 30 00:18:08.248745 kernel: cni0: port 1(vetha2b3a498) entered forwarding state Apr 30 00:18:08.250271 kernel: cni0: port 1(vetha2b3a498) entered disabled state Apr 30 00:18:08.249614 systemd-networkd[1399]: vetha2b3a498: Link UP Apr 30 00:18:08.269658 kernel: cni0: port 1(vetha2b3a498) entered blocking state Apr 30 00:18:08.269759 kernel: cni0: port 1(vetha2b3a498) entered forwarding state Apr 30 00:18:08.270031 systemd-networkd[1399]: vetha2b3a498: Gained carrier Apr 30 00:18:08.270678 systemd-networkd[1399]: cni0: Gained carrier Apr 30 00:18:08.272718 containerd[1474]: 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)(0x4000018938), "name":"cbr0", "type":"bridge"} Apr 30 00:18:08.272718 containerd[1474]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:18:08.296806 containerd[1474]: {"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-04-30T00:18:08.296711646Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:18:08.296806 containerd[1474]: time="2025-04-30T00:18:08.296794046Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:18:08.297026 containerd[1474]: time="2025-04-30T00:18:08.296809686Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:08.297026 containerd[1474]: time="2025-04-30T00:18:08.296898166Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:08.321806 systemd[1]: Started cri-containerd-3b8ead212dd32a515100ce71ecdc62971eb6448a11f6f310c02cf5f20cc6509e.scope - libcontainer container 3b8ead212dd32a515100ce71ecdc62971eb6448a11f6f310c02cf5f20cc6509e. Apr 30 00:18:08.331343 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 30 00:18:08.347310 containerd[1474]: time="2025-04-30T00:18:08.347229846Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6f4jq,Uid:adbc1e5d-5dad-4d20-b239-4ed1a47df8b2,Namespace:default,Attempt:0,} returns sandbox id \"3b8ead212dd32a515100ce71ecdc62971eb6448a11f6f310c02cf5f20cc6509e\"" Apr 30 00:18:08.348650 containerd[1474]: time="2025-04-30T00:18:08.348625166Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 00:18:08.410383 kubelet[1728]: E0430 00:18:08.410316 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:09.410910 kubelet[1728]: E0430 00:18:09.410856 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:09.531138 systemd-networkd[1399]: vetha2b3a498: Gained IPv6LL Apr 30 00:18:09.593738 systemd-networkd[1399]: cni0: Gained IPv6LL Apr 30 00:18:09.984157 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1283267244.mount: Deactivated successfully. Apr 30 00:18:10.412142 kubelet[1728]: E0430 00:18:10.412083 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:10.656833 containerd[1474]: time="2025-04-30T00:18:10.656782526Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:10.657798 containerd[1474]: time="2025-04-30T00:18:10.657763606Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948638" Apr 30 00:18:10.658633 containerd[1474]: time="2025-04-30T00:18:10.658602806Z" level=info msg="ImageCreate event name:\"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:10.665544 containerd[1474]: time="2025-04-30T00:18:10.665268446Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:10.666503 containerd[1474]: time="2025-04-30T00:18:10.666459126Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"69948516\" in 2.31779904s" Apr 30 00:18:10.666503 containerd[1474]: time="2025-04-30T00:18:10.666496766Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 30 00:18:10.669263 containerd[1474]: time="2025-04-30T00:18:10.669198966Z" level=info msg="CreateContainer within sandbox \"3b8ead212dd32a515100ce71ecdc62971eb6448a11f6f310c02cf5f20cc6509e\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 30 00:18:10.680538 containerd[1474]: time="2025-04-30T00:18:10.680494606Z" level=info msg="CreateContainer within sandbox \"3b8ead212dd32a515100ce71ecdc62971eb6448a11f6f310c02cf5f20cc6509e\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"724dfcd026d529eb7432ad14faa213186fec1af8d772b8414b34d45d969b69c7\"" Apr 30 00:18:10.681235 containerd[1474]: time="2025-04-30T00:18:10.681204406Z" level=info msg="StartContainer for \"724dfcd026d529eb7432ad14faa213186fec1af8d772b8414b34d45d969b69c7\"" Apr 30 00:18:10.709730 systemd[1]: Started cri-containerd-724dfcd026d529eb7432ad14faa213186fec1af8d772b8414b34d45d969b69c7.scope - libcontainer container 724dfcd026d529eb7432ad14faa213186fec1af8d772b8414b34d45d969b69c7. Apr 30 00:18:10.730945 containerd[1474]: time="2025-04-30T00:18:10.730897806Z" level=info msg="StartContainer for \"724dfcd026d529eb7432ad14faa213186fec1af8d772b8414b34d45d969b69c7\" returns successfully" Apr 30 00:18:11.412682 kubelet[1728]: E0430 00:18:11.412640 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:11.564792 kubelet[1728]: I0430 00:18:11.564673 1728 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-6f4jq" podStartSLOduration=2.245007726 podStartE2EDuration="4.564656326s" podCreationTimestamp="2025-04-30 00:18:07 +0000 UTC" firstStartedPulling="2025-04-30 00:18:08.348110886 +0000 UTC m=+12.874808121" lastFinishedPulling="2025-04-30 00:18:10.667759486 +0000 UTC m=+15.194456721" observedRunningTime="2025-04-30 00:18:11.564137606 +0000 UTC m=+16.090834841" watchObservedRunningTime="2025-04-30 00:18:11.564656326 +0000 UTC m=+16.091353561" Apr 30 00:18:12.413206 kubelet[1728]: E0430 00:18:12.413158 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:13.413517 kubelet[1728]: E0430 00:18:13.413470 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:14.414526 kubelet[1728]: E0430 00:18:14.414489 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:14.507009 systemd[1]: Created slice kubepods-besteffort-podf6075f3c_f8b2_49fd_bb44_299e95c10d71.slice - libcontainer container kubepods-besteffort-podf6075f3c_f8b2_49fd_bb44_299e95c10d71.slice. Apr 30 00:18:14.521871 kubelet[1728]: I0430 00:18:14.521763 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/f6075f3c-f8b2-49fd-bb44-299e95c10d71-data\") pod \"nfs-server-provisioner-0\" (UID: \"f6075f3c-f8b2-49fd-bb44-299e95c10d71\") " pod="default/nfs-server-provisioner-0" Apr 30 00:18:14.521871 kubelet[1728]: I0430 00:18:14.521802 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4t4qf\" (UniqueName: \"kubernetes.io/projected/f6075f3c-f8b2-49fd-bb44-299e95c10d71-kube-api-access-4t4qf\") pod \"nfs-server-provisioner-0\" (UID: \"f6075f3c-f8b2-49fd-bb44-299e95c10d71\") " pod="default/nfs-server-provisioner-0" Apr 30 00:18:14.809757 containerd[1474]: time="2025-04-30T00:18:14.809642326Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f6075f3c-f8b2-49fd-bb44-299e95c10d71,Namespace:default,Attempt:0,}" Apr 30 00:18:14.837958 systemd-networkd[1399]: veth0c79500e: Link UP Apr 30 00:18:14.839851 kernel: cni0: port 2(veth0c79500e) entered blocking state Apr 30 00:18:14.839881 kernel: cni0: port 2(veth0c79500e) entered disabled state Apr 30 00:18:14.839901 kernel: veth0c79500e: entered allmulticast mode Apr 30 00:18:14.840848 kernel: veth0c79500e: entered promiscuous mode Apr 30 00:18:14.847886 kernel: cni0: port 2(veth0c79500e) entered blocking state Apr 30 00:18:14.847974 kernel: cni0: port 2(veth0c79500e) entered forwarding state Apr 30 00:18:14.847494 systemd-networkd[1399]: veth0c79500e: Gained carrier Apr 30 00:18:14.852262 containerd[1474]: 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)(0x400001e938), "name":"cbr0", "type":"bridge"} Apr 30 00:18:14.852262 containerd[1474]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:18:14.875979 containerd[1474]: {"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-04-30T00:18:14.875878446Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:18:14.875979 containerd[1474]: time="2025-04-30T00:18:14.875931966Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:18:14.875979 containerd[1474]: time="2025-04-30T00:18:14.875942566Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:14.876284 containerd[1474]: time="2025-04-30T00:18:14.876250846Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:14.902794 systemd[1]: Started cri-containerd-32635593400d5bdfb16448b614a068a4c4347afc293f8762b1da7c657f160d5d.scope - libcontainer container 32635593400d5bdfb16448b614a068a4c4347afc293f8762b1da7c657f160d5d. Apr 30 00:18:14.921750 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 30 00:18:14.939966 containerd[1474]: time="2025-04-30T00:18:14.939686686Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f6075f3c-f8b2-49fd-bb44-299e95c10d71,Namespace:default,Attempt:0,} returns sandbox id \"32635593400d5bdfb16448b614a068a4c4347afc293f8762b1da7c657f160d5d\"" Apr 30 00:18:14.941598 containerd[1474]: time="2025-04-30T00:18:14.941531006Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 30 00:18:15.415625 kubelet[1728]: E0430 00:18:15.415582 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:16.402402 kubelet[1728]: E0430 00:18:16.402344 1728 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:16.415945 kubelet[1728]: E0430 00:18:16.415904 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:16.825803 systemd-networkd[1399]: veth0c79500e: Gained IPv6LL Apr 30 00:18:16.870938 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount237868766.mount: Deactivated successfully. Apr 30 00:18:17.416940 kubelet[1728]: E0430 00:18:17.416900 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:18.104114 containerd[1474]: time="2025-04-30T00:18:18.104066566Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:18.105097 containerd[1474]: time="2025-04-30T00:18:18.104887686Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Apr 30 00:18:18.106187 containerd[1474]: time="2025-04-30T00:18:18.105961966Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:18.110658 containerd[1474]: time="2025-04-30T00:18:18.110611406Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:18.112768 containerd[1474]: time="2025-04-30T00:18:18.112125126Z" 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 3.17056296s" Apr 30 00:18:18.112768 containerd[1474]: time="2025-04-30T00:18:18.112170126Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 30 00:18:18.114596 containerd[1474]: time="2025-04-30T00:18:18.114550646Z" level=info msg="CreateContainer within sandbox \"32635593400d5bdfb16448b614a068a4c4347afc293f8762b1da7c657f160d5d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 30 00:18:18.127263 containerd[1474]: time="2025-04-30T00:18:18.127206166Z" level=info msg="CreateContainer within sandbox \"32635593400d5bdfb16448b614a068a4c4347afc293f8762b1da7c657f160d5d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"f1437b126b973e0e23e93e1b036a6d144ab13ad37a87fa4e4f2c245b2373aac8\"" Apr 30 00:18:18.128240 containerd[1474]: time="2025-04-30T00:18:18.128019046Z" level=info msg="StartContainer for \"f1437b126b973e0e23e93e1b036a6d144ab13ad37a87fa4e4f2c245b2373aac8\"" Apr 30 00:18:18.156766 systemd[1]: Started cri-containerd-f1437b126b973e0e23e93e1b036a6d144ab13ad37a87fa4e4f2c245b2373aac8.scope - libcontainer container f1437b126b973e0e23e93e1b036a6d144ab13ad37a87fa4e4f2c245b2373aac8. Apr 30 00:18:18.184423 containerd[1474]: time="2025-04-30T00:18:18.184356966Z" level=info msg="StartContainer for \"f1437b126b973e0e23e93e1b036a6d144ab13ad37a87fa4e4f2c245b2373aac8\" returns successfully" Apr 30 00:18:18.417246 kubelet[1728]: E0430 00:18:18.417121 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:19.418024 kubelet[1728]: E0430 00:18:19.417964 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:20.418993 kubelet[1728]: E0430 00:18:20.418944 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:21.420050 kubelet[1728]: E0430 00:18:21.419993 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:22.420272 kubelet[1728]: E0430 00:18:22.420229 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:23.420957 kubelet[1728]: E0430 00:18:23.420752 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:24.421995 kubelet[1728]: E0430 00:18:24.421935 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:25.422270 kubelet[1728]: E0430 00:18:25.422224 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:26.422877 kubelet[1728]: E0430 00:18:26.422794 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:27.423405 kubelet[1728]: E0430 00:18:27.423323 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:27.945403 kubelet[1728]: I0430 00:18:27.944527 1728 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.772494563 podStartE2EDuration="13.944508723s" podCreationTimestamp="2025-04-30 00:18:14 +0000 UTC" firstStartedPulling="2025-04-30 00:18:14.941104566 +0000 UTC m=+19.467801801" lastFinishedPulling="2025-04-30 00:18:18.113118726 +0000 UTC m=+22.639815961" observedRunningTime="2025-04-30 00:18:18.588834126 +0000 UTC m=+23.115531361" watchObservedRunningTime="2025-04-30 00:18:27.944508723 +0000 UTC m=+32.471205958" Apr 30 00:18:27.952723 systemd[1]: Created slice kubepods-besteffort-pod4f8d899e_dd39_4f64_8f78_def8c0c657fc.slice - libcontainer container kubepods-besteffort-pod4f8d899e_dd39_4f64_8f78_def8c0c657fc.slice. Apr 30 00:18:28.104061 kubelet[1728]: I0430 00:18:28.104007 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-n27x9\" (UniqueName: \"kubernetes.io/projected/4f8d899e-dd39-4f64-8f78-def8c0c657fc-kube-api-access-n27x9\") pod \"test-pod-1\" (UID: \"4f8d899e-dd39-4f64-8f78-def8c0c657fc\") " pod="default/test-pod-1" Apr 30 00:18:28.104061 kubelet[1728]: I0430 00:18:28.104059 1728 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-884a2d78-154c-41b6-b748-05b1be05cbb8\" (UniqueName: \"kubernetes.io/nfs/4f8d899e-dd39-4f64-8f78-def8c0c657fc-pvc-884a2d78-154c-41b6-b748-05b1be05cbb8\") pod \"test-pod-1\" (UID: \"4f8d899e-dd39-4f64-8f78-def8c0c657fc\") " pod="default/test-pod-1" Apr 30 00:18:28.236794 kernel: FS-Cache: Loaded Apr 30 00:18:28.262206 kernel: RPC: Registered named UNIX socket transport module. Apr 30 00:18:28.262324 kernel: RPC: Registered udp transport module. Apr 30 00:18:28.262341 kernel: RPC: Registered tcp transport module. Apr 30 00:18:28.262357 kernel: RPC: Registered tcp-with-tls transport module. Apr 30 00:18:28.262747 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 30 00:18:28.424325 kubelet[1728]: E0430 00:18:28.424273 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:28.449062 kernel: NFS: Registering the id_resolver key type Apr 30 00:18:28.449143 kernel: Key type id_resolver registered Apr 30 00:18:28.449167 kernel: Key type id_legacy registered Apr 30 00:18:28.472853 nfsidmap[2776]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Apr 30 00:18:28.476340 nfsidmap[2779]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Apr 30 00:18:28.556888 containerd[1474]: time="2025-04-30T00:18:28.556756204Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4f8d899e-dd39-4f64-8f78-def8c0c657fc,Namespace:default,Attempt:0,}" Apr 30 00:18:28.577845 systemd-networkd[1399]: veth267304e0: Link UP Apr 30 00:18:28.580010 kernel: cni0: port 3(veth267304e0) entered blocking state Apr 30 00:18:28.580090 kernel: cni0: port 3(veth267304e0) entered disabled state Apr 30 00:18:28.580105 kernel: veth267304e0: entered allmulticast mode Apr 30 00:18:28.581109 kernel: veth267304e0: entered promiscuous mode Apr 30 00:18:28.586877 kernel: cni0: port 3(veth267304e0) entered blocking state Apr 30 00:18:28.587005 kernel: cni0: port 3(veth267304e0) entered forwarding state Apr 30 00:18:28.588021 systemd-networkd[1399]: veth267304e0: Gained carrier Apr 30 00:18:28.592458 containerd[1474]: 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)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Apr 30 00:18:28.592458 containerd[1474]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:18:28.616045 containerd[1474]: {"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-04-30T00:18:28.615709722Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:18:28.616045 containerd[1474]: time="2025-04-30T00:18:28.616000723Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:18:28.616225 containerd[1474]: time="2025-04-30T00:18:28.616025483Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:28.616225 containerd[1474]: time="2025-04-30T00:18:28.616118003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:18:28.639771 systemd[1]: Started cri-containerd-6065e08ebe79e13d748a1ea1fb82837987856a0cd212e6ed19073f095c2b054e.scope - libcontainer container 6065e08ebe79e13d748a1ea1fb82837987856a0cd212e6ed19073f095c2b054e. Apr 30 00:18:28.650975 systemd-resolved[1312]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Apr 30 00:18:28.667481 containerd[1474]: time="2025-04-30T00:18:28.667440370Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4f8d899e-dd39-4f64-8f78-def8c0c657fc,Namespace:default,Attempt:0,} returns sandbox id \"6065e08ebe79e13d748a1ea1fb82837987856a0cd212e6ed19073f095c2b054e\"" Apr 30 00:18:28.668596 containerd[1474]: time="2025-04-30T00:18:28.668438294Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 00:18:28.924730 containerd[1474]: time="2025-04-30T00:18:28.924146724Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:18:28.924730 containerd[1474]: time="2025-04-30T00:18:28.924594606Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 30 00:18:28.928916 containerd[1474]: time="2025-04-30T00:18:28.928641982Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"69948516\" in 260.171968ms" Apr 30 00:18:28.928916 containerd[1474]: time="2025-04-30T00:18:28.928676742Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 30 00:18:28.931135 containerd[1474]: time="2025-04-30T00:18:28.931088792Z" level=info msg="CreateContainer within sandbox \"6065e08ebe79e13d748a1ea1fb82837987856a0cd212e6ed19073f095c2b054e\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 30 00:18:28.941623 containerd[1474]: time="2025-04-30T00:18:28.941493794Z" level=info msg="CreateContainer within sandbox \"6065e08ebe79e13d748a1ea1fb82837987856a0cd212e6ed19073f095c2b054e\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5ef688421fea8cb2199f4a26eb6e8223e4364ebc3f1b1a0095082414ae920082\"" Apr 30 00:18:28.942601 containerd[1474]: time="2025-04-30T00:18:28.942016476Z" level=info msg="StartContainer for \"5ef688421fea8cb2199f4a26eb6e8223e4364ebc3f1b1a0095082414ae920082\"" Apr 30 00:18:28.977763 systemd[1]: Started cri-containerd-5ef688421fea8cb2199f4a26eb6e8223e4364ebc3f1b1a0095082414ae920082.scope - libcontainer container 5ef688421fea8cb2199f4a26eb6e8223e4364ebc3f1b1a0095082414ae920082. Apr 30 00:18:29.071620 containerd[1474]: time="2025-04-30T00:18:29.071446420Z" level=info msg="StartContainer for \"5ef688421fea8cb2199f4a26eb6e8223e4364ebc3f1b1a0095082414ae920082\" returns successfully" Apr 30 00:18:29.424547 kubelet[1728]: E0430 00:18:29.424455 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:29.620409 kubelet[1728]: I0430 00:18:29.620312 1728 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.3590522 podStartE2EDuration="15.620294372s" podCreationTimestamp="2025-04-30 00:18:14 +0000 UTC" firstStartedPulling="2025-04-30 00:18:28.668204253 +0000 UTC m=+33.194901448" lastFinishedPulling="2025-04-30 00:18:28.929446385 +0000 UTC m=+33.456143620" observedRunningTime="2025-04-30 00:18:29.620051091 +0000 UTC m=+34.146748286" watchObservedRunningTime="2025-04-30 00:18:29.620294372 +0000 UTC m=+34.146991567" Apr 30 00:18:29.817742 systemd-networkd[1399]: veth267304e0: Gained IPv6LL Apr 30 00:18:30.425527 kubelet[1728]: E0430 00:18:30.425462 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:31.425681 kubelet[1728]: E0430 00:18:31.425620 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:32.426148 kubelet[1728]: E0430 00:18:32.426095 1728 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:18:32.446479 update_engine[1458]: I20250430 00:18:32.446408 1458 update_attempter.cc:509] Updating boot flags... Apr 30 00:18:32.473641 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 43 scanned by (udev-worker) (2949) Apr 30 00:18:32.505399 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 43 scanned by (udev-worker) (2951)