Mar 25 01:17:35.889575 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 25 01:17:35.889597 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Mon Mar 24 23:39:14 -00 2025 Mar 25 01:17:35.889607 kernel: KASLR enabled Mar 25 01:17:35.889613 kernel: efi: EFI v2.7 by EDK II Mar 25 01:17:35.889618 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 Mar 25 01:17:35.889623 kernel: random: crng init done Mar 25 01:17:35.889630 kernel: secureboot: Secure boot disabled Mar 25 01:17:35.889636 kernel: ACPI: Early table checksum verification disabled Mar 25 01:17:35.889642 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Mar 25 01:17:35.889649 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Mar 25 01:17:35.889655 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889660 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889666 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889672 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889678 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889686 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889692 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889698 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889704 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 25 01:17:35.889709 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Mar 25 01:17:35.889715 kernel: NUMA: Failed to initialise from firmware Mar 25 01:17:35.889721 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Mar 25 01:17:35.889728 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Mar 25 01:17:35.889734 kernel: Zone ranges: Mar 25 01:17:35.889740 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Mar 25 01:17:35.889747 kernel: DMA32 empty Mar 25 01:17:35.889753 kernel: Normal empty Mar 25 01:17:35.889758 kernel: Movable zone start for each node Mar 25 01:17:35.889784 kernel: Early memory node ranges Mar 25 01:17:35.889791 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] Mar 25 01:17:35.889797 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] Mar 25 01:17:35.889803 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] Mar 25 01:17:35.889809 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Mar 25 01:17:35.889815 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Mar 25 01:17:35.889821 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Mar 25 01:17:35.889827 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Mar 25 01:17:35.889833 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Mar 25 01:17:35.889841 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Mar 25 01:17:35.889847 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Mar 25 01:17:35.889853 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Mar 25 01:17:35.889861 kernel: psci: probing for conduit method from ACPI. Mar 25 01:17:35.889867 kernel: psci: PSCIv1.1 detected in firmware. Mar 25 01:17:35.889874 kernel: psci: Using standard PSCI v0.2 function IDs Mar 25 01:17:35.889881 kernel: psci: Trusted OS migration not required Mar 25 01:17:35.889888 kernel: psci: SMC Calling Convention v1.1 Mar 25 01:17:35.889895 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Mar 25 01:17:35.889901 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Mar 25 01:17:35.889907 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Mar 25 01:17:35.889914 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 25 01:17:35.889920 kernel: Detected PIPT I-cache on CPU0 Mar 25 01:17:35.889926 kernel: CPU features: detected: GIC system register CPU interface Mar 25 01:17:35.889932 kernel: CPU features: detected: Hardware dirty bit management Mar 25 01:17:35.889939 kernel: CPU features: detected: Spectre-v4 Mar 25 01:17:35.889946 kernel: CPU features: detected: Spectre-BHB Mar 25 01:17:35.889953 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 25 01:17:35.889959 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 25 01:17:35.889966 kernel: CPU features: detected: ARM erratum 1418040 Mar 25 01:17:35.889972 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 25 01:17:35.889978 kernel: alternatives: applying boot alternatives Mar 25 01:17:35.889986 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=b84e5f613acd6cd0a8a878f32f5653a14f2e6fb2820997fecd5b2bd33a4ba3ab Mar 25 01:17:35.889993 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 25 01:17:35.889999 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 25 01:17:35.890005 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 25 01:17:35.890012 kernel: Fallback order for Node 0: 0 Mar 25 01:17:35.890019 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Mar 25 01:17:35.890026 kernel: Policy zone: DMA Mar 25 01:17:35.890032 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 25 01:17:35.890038 kernel: software IO TLB: area num 4. Mar 25 01:17:35.890044 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Mar 25 01:17:35.890051 kernel: Memory: 2387412K/2572288K available (10304K kernel code, 2186K rwdata, 8096K rodata, 38464K init, 897K bss, 184876K reserved, 0K cma-reserved) Mar 25 01:17:35.890057 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 25 01:17:35.890064 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 25 01:17:35.890071 kernel: rcu: RCU event tracing is enabled. Mar 25 01:17:35.890077 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 25 01:17:35.890084 kernel: Trampoline variant of Tasks RCU enabled. Mar 25 01:17:35.890090 kernel: Tracing variant of Tasks RCU enabled. Mar 25 01:17:35.890098 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 25 01:17:35.890104 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 25 01:17:35.890110 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 25 01:17:35.890117 kernel: GICv3: 256 SPIs implemented Mar 25 01:17:35.890123 kernel: GICv3: 0 Extended SPIs implemented Mar 25 01:17:35.890129 kernel: Root IRQ handler: gic_handle_irq Mar 25 01:17:35.890135 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Mar 25 01:17:35.890142 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Mar 25 01:17:35.890149 kernel: ITS [mem 0x08080000-0x0809ffff] Mar 25 01:17:35.890155 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Mar 25 01:17:35.890161 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Mar 25 01:17:35.890169 kernel: GICv3: using LPI property table @0x00000000400f0000 Mar 25 01:17:35.890176 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Mar 25 01:17:35.890182 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 25 01:17:35.890189 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 25 01:17:35.890195 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 25 01:17:35.890201 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 25 01:17:35.890208 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 25 01:17:35.890214 kernel: arm-pv: using stolen time PV Mar 25 01:17:35.890221 kernel: Console: colour dummy device 80x25 Mar 25 01:17:35.890227 kernel: ACPI: Core revision 20230628 Mar 25 01:17:35.890234 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 25 01:17:35.890250 kernel: pid_max: default: 32768 minimum: 301 Mar 25 01:17:35.890257 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 25 01:17:35.890263 kernel: landlock: Up and running. Mar 25 01:17:35.890270 kernel: SELinux: Initializing. Mar 25 01:17:35.890276 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 25 01:17:35.890283 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 25 01:17:35.890289 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 25 01:17:35.890296 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 25 01:17:35.890302 kernel: rcu: Hierarchical SRCU implementation. Mar 25 01:17:35.890311 kernel: rcu: Max phase no-delay instances is 400. Mar 25 01:17:35.890322 kernel: Platform MSI: ITS@0x8080000 domain created Mar 25 01:17:35.890329 kernel: PCI/MSI: ITS@0x8080000 domain created Mar 25 01:17:35.890336 kernel: Remapping and enabling EFI services. Mar 25 01:17:35.890342 kernel: smp: Bringing up secondary CPUs ... Mar 25 01:17:35.890349 kernel: Detected PIPT I-cache on CPU1 Mar 25 01:17:35.890355 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Mar 25 01:17:35.890362 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Mar 25 01:17:35.890368 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 25 01:17:35.890376 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 25 01:17:35.890383 kernel: Detected PIPT I-cache on CPU2 Mar 25 01:17:35.890394 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Mar 25 01:17:35.890402 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Mar 25 01:17:35.890409 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 25 01:17:35.890416 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Mar 25 01:17:35.890422 kernel: Detected PIPT I-cache on CPU3 Mar 25 01:17:35.890429 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Mar 25 01:17:35.890436 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Mar 25 01:17:35.890445 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 25 01:17:35.890456 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Mar 25 01:17:35.890463 kernel: smp: Brought up 1 node, 4 CPUs Mar 25 01:17:35.890470 kernel: SMP: Total of 4 processors activated. Mar 25 01:17:35.890477 kernel: CPU features: detected: 32-bit EL0 Support Mar 25 01:17:35.890484 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 25 01:17:35.890490 kernel: CPU features: detected: Common not Private translations Mar 25 01:17:35.890497 kernel: CPU features: detected: CRC32 instructions Mar 25 01:17:35.890506 kernel: CPU features: detected: Enhanced Virtualization Traps Mar 25 01:17:35.890513 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 25 01:17:35.890519 kernel: CPU features: detected: LSE atomic instructions Mar 25 01:17:35.890526 kernel: CPU features: detected: Privileged Access Never Mar 25 01:17:35.890533 kernel: CPU features: detected: RAS Extension Support Mar 25 01:17:35.890540 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Mar 25 01:17:35.890547 kernel: CPU: All CPU(s) started at EL1 Mar 25 01:17:35.890554 kernel: alternatives: applying system-wide alternatives Mar 25 01:17:35.890560 kernel: devtmpfs: initialized Mar 25 01:17:35.890569 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 25 01:17:35.890576 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 25 01:17:35.890583 kernel: pinctrl core: initialized pinctrl subsystem Mar 25 01:17:35.890589 kernel: SMBIOS 3.0.0 present. Mar 25 01:17:35.890596 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Mar 25 01:17:35.890603 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 25 01:17:35.890610 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 25 01:17:35.890617 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 25 01:17:35.890624 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 25 01:17:35.890632 kernel: audit: initializing netlink subsys (disabled) Mar 25 01:17:35.890639 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 Mar 25 01:17:35.890646 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 25 01:17:35.890653 kernel: cpuidle: using governor menu Mar 25 01:17:35.890659 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 25 01:17:35.890666 kernel: ASID allocator initialised with 32768 entries Mar 25 01:17:35.890673 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 25 01:17:35.890680 kernel: Serial: AMBA PL011 UART driver Mar 25 01:17:35.890687 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Mar 25 01:17:35.890695 kernel: Modules: 0 pages in range for non-PLT usage Mar 25 01:17:35.890702 kernel: Modules: 509248 pages in range for PLT usage Mar 25 01:17:35.890709 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 25 01:17:35.890716 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Mar 25 01:17:35.890723 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Mar 25 01:17:35.890730 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Mar 25 01:17:35.890736 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 25 01:17:35.890743 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Mar 25 01:17:35.890750 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Mar 25 01:17:35.890758 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Mar 25 01:17:35.890765 kernel: ACPI: Added _OSI(Module Device) Mar 25 01:17:35.890771 kernel: ACPI: Added _OSI(Processor Device) Mar 25 01:17:35.890778 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 25 01:17:35.890785 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 25 01:17:35.890792 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 25 01:17:35.890798 kernel: ACPI: Interpreter enabled Mar 25 01:17:35.890805 kernel: ACPI: Using GIC for interrupt routing Mar 25 01:17:35.890812 kernel: ACPI: MCFG table detected, 1 entries Mar 25 01:17:35.890819 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Mar 25 01:17:35.890827 kernel: printk: console [ttyAMA0] enabled Mar 25 01:17:35.890834 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 25 01:17:35.890960 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 25 01:17:35.891033 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Mar 25 01:17:35.891101 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Mar 25 01:17:35.891169 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Mar 25 01:17:35.891283 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Mar 25 01:17:35.891302 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Mar 25 01:17:35.891310 kernel: PCI host bridge to bus 0000:00 Mar 25 01:17:35.891405 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Mar 25 01:17:35.891487 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Mar 25 01:17:35.891543 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Mar 25 01:17:35.891631 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 25 01:17:35.891721 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Mar 25 01:17:35.891803 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Mar 25 01:17:35.891870 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Mar 25 01:17:35.891936 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Mar 25 01:17:35.892000 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Mar 25 01:17:35.892064 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Mar 25 01:17:35.892128 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Mar 25 01:17:35.892192 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Mar 25 01:17:35.892265 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Mar 25 01:17:35.892334 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Mar 25 01:17:35.892394 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Mar 25 01:17:35.892404 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Mar 25 01:17:35.892411 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Mar 25 01:17:35.892418 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Mar 25 01:17:35.892425 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Mar 25 01:17:35.892435 kernel: iommu: Default domain type: Translated Mar 25 01:17:35.892442 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 25 01:17:35.892448 kernel: efivars: Registered efivars operations Mar 25 01:17:35.892455 kernel: vgaarb: loaded Mar 25 01:17:35.892462 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 25 01:17:35.892469 kernel: VFS: Disk quotas dquot_6.6.0 Mar 25 01:17:35.892476 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 25 01:17:35.892483 kernel: pnp: PnP ACPI init Mar 25 01:17:35.892563 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Mar 25 01:17:35.892575 kernel: pnp: PnP ACPI: found 1 devices Mar 25 01:17:35.892582 kernel: NET: Registered PF_INET protocol family Mar 25 01:17:35.892589 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 25 01:17:35.892596 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 25 01:17:35.892603 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 25 01:17:35.892610 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 25 01:17:35.892617 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 25 01:17:35.892624 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 25 01:17:35.892633 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 25 01:17:35.892640 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 25 01:17:35.892647 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 25 01:17:35.892654 kernel: PCI: CLS 0 bytes, default 64 Mar 25 01:17:35.892675 kernel: kvm [1]: HYP mode not available Mar 25 01:17:35.892683 kernel: Initialise system trusted keyrings Mar 25 01:17:35.892690 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 25 01:17:35.892697 kernel: Key type asymmetric registered Mar 25 01:17:35.892704 kernel: Asymmetric key parser 'x509' registered Mar 25 01:17:35.892711 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Mar 25 01:17:35.892719 kernel: io scheduler mq-deadline registered Mar 25 01:17:35.892726 kernel: io scheduler kyber registered Mar 25 01:17:35.892733 kernel: io scheduler bfq registered Mar 25 01:17:35.892740 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Mar 25 01:17:35.892747 kernel: ACPI: button: Power Button [PWRB] Mar 25 01:17:35.892754 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Mar 25 01:17:35.892826 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Mar 25 01:17:35.892836 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 25 01:17:35.892843 kernel: thunder_xcv, ver 1.0 Mar 25 01:17:35.892852 kernel: thunder_bgx, ver 1.0 Mar 25 01:17:35.892868 kernel: nicpf, ver 1.0 Mar 25 01:17:35.892877 kernel: nicvf, ver 1.0 Mar 25 01:17:35.892952 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 25 01:17:35.893014 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-25T01:17:35 UTC (1742865455) Mar 25 01:17:35.893023 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 25 01:17:35.893030 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Mar 25 01:17:35.893037 kernel: watchdog: Delayed init of the lockup detector failed: -19 Mar 25 01:17:35.893045 kernel: watchdog: Hard watchdog permanently disabled Mar 25 01:17:35.893052 kernel: NET: Registered PF_INET6 protocol family Mar 25 01:17:35.893059 kernel: Segment Routing with IPv6 Mar 25 01:17:35.893066 kernel: In-situ OAM (IOAM) with IPv6 Mar 25 01:17:35.893072 kernel: NET: Registered PF_PACKET protocol family Mar 25 01:17:35.893079 kernel: Key type dns_resolver registered Mar 25 01:17:35.893086 kernel: registered taskstats version 1 Mar 25 01:17:35.893093 kernel: Loading compiled-in X.509 certificates Mar 25 01:17:35.893100 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: ed4ababe871f0afac8b4236504477de11a6baf07' Mar 25 01:17:35.893108 kernel: Key type .fscrypt registered Mar 25 01:17:35.893115 kernel: Key type fscrypt-provisioning registered Mar 25 01:17:35.893122 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 25 01:17:35.893128 kernel: ima: Allocated hash algorithm: sha1 Mar 25 01:17:35.893135 kernel: ima: No architecture policies found Mar 25 01:17:35.893142 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 25 01:17:35.893149 kernel: clk: Disabling unused clocks Mar 25 01:17:35.893156 kernel: Freeing unused kernel memory: 38464K Mar 25 01:17:35.893162 kernel: Run /init as init process Mar 25 01:17:35.893171 kernel: with arguments: Mar 25 01:17:35.893177 kernel: /init Mar 25 01:17:35.893184 kernel: with environment: Mar 25 01:17:35.893190 kernel: HOME=/ Mar 25 01:17:35.893197 kernel: TERM=linux Mar 25 01:17:35.893204 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 25 01:17:35.893211 systemd[1]: Successfully made /usr/ read-only. Mar 25 01:17:35.893221 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 25 01:17:35.893230 systemd[1]: Detected virtualization kvm. Mar 25 01:17:35.893246 systemd[1]: Detected architecture arm64. Mar 25 01:17:35.893254 systemd[1]: Running in initrd. Mar 25 01:17:35.893261 systemd[1]: No hostname configured, using default hostname. Mar 25 01:17:35.893269 systemd[1]: Hostname set to . Mar 25 01:17:35.893276 systemd[1]: Initializing machine ID from VM UUID. Mar 25 01:17:35.893284 systemd[1]: Queued start job for default target initrd.target. Mar 25 01:17:35.893291 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:17:35.893301 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:17:35.893309 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 25 01:17:35.893322 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:17:35.893329 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 25 01:17:35.893338 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 25 01:17:35.893346 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 25 01:17:35.893355 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 25 01:17:35.893363 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:17:35.893370 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:17:35.893378 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:17:35.893385 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:17:35.893392 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:17:35.893400 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:17:35.893407 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:17:35.893414 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:17:35.893423 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 25 01:17:35.893431 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 25 01:17:35.893442 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:17:35.893450 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:17:35.893457 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:17:35.893465 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:17:35.893472 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 25 01:17:35.893480 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:17:35.893489 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 25 01:17:35.893496 systemd[1]: Starting systemd-fsck-usr.service... Mar 25 01:17:35.893504 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:17:35.893511 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:17:35.893519 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:17:35.893526 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:17:35.893534 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 25 01:17:35.893543 systemd[1]: Finished systemd-fsck-usr.service. Mar 25 01:17:35.893551 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 25 01:17:35.893575 systemd-journald[237]: Collecting audit messages is disabled. Mar 25 01:17:35.893595 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:17:35.893603 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 25 01:17:35.893610 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:17:35.893619 systemd-journald[237]: Journal started Mar 25 01:17:35.893637 systemd-journald[237]: Runtime Journal (/run/log/journal/68aaa28476354bc183d54dcc30db90aa) is 5.9M, max 47.3M, 41.4M free. Mar 25 01:17:35.880941 systemd-modules-load[238]: Inserted module 'overlay' Mar 25 01:17:35.895692 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:17:35.895708 kernel: Bridge firewalling registered Mar 25 01:17:35.896707 systemd-modules-load[238]: Inserted module 'br_netfilter' Mar 25 01:17:35.897009 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 25 01:17:35.898406 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:17:35.903658 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:17:35.905393 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:17:35.907773 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:17:35.912454 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:17:35.917324 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 25 01:17:35.920792 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:17:35.921773 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:17:35.923272 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:17:35.926612 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:17:35.933236 dracut-cmdline[271]: dracut-dracut-053 Mar 25 01:17:35.939061 dracut-cmdline[271]: 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=b84e5f613acd6cd0a8a878f32f5653a14f2e6fb2820997fecd5b2bd33a4ba3ab Mar 25 01:17:35.968209 systemd-resolved[279]: Positive Trust Anchors: Mar 25 01:17:35.970052 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:17:35.970090 systemd-resolved[279]: 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 Mar 25 01:17:35.974928 systemd-resolved[279]: Defaulting to hostname 'linux'. Mar 25 01:17:35.975925 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:17:35.977279 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:17:36.005268 kernel: SCSI subsystem initialized Mar 25 01:17:36.009258 kernel: Loading iSCSI transport class v2.0-870. Mar 25 01:17:36.018293 kernel: iscsi: registered transport (tcp) Mar 25 01:17:36.029276 kernel: iscsi: registered transport (qla4xxx) Mar 25 01:17:36.029294 kernel: QLogic iSCSI HBA Driver Mar 25 01:17:36.069811 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 25 01:17:36.071807 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 25 01:17:36.104784 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 25 01:17:36.104835 kernel: device-mapper: uevent: version 1.0.3 Mar 25 01:17:36.106264 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 25 01:17:36.154267 kernel: raid6: neonx8 gen() 15738 MB/s Mar 25 01:17:36.171264 kernel: raid6: neonx4 gen() 15761 MB/s Mar 25 01:17:36.188259 kernel: raid6: neonx2 gen() 13157 MB/s Mar 25 01:17:36.205255 kernel: raid6: neonx1 gen() 10480 MB/s Mar 25 01:17:36.222254 kernel: raid6: int64x8 gen() 6779 MB/s Mar 25 01:17:36.239255 kernel: raid6: int64x4 gen() 7330 MB/s Mar 25 01:17:36.256253 kernel: raid6: int64x2 gen() 6098 MB/s Mar 25 01:17:36.273255 kernel: raid6: int64x1 gen() 5036 MB/s Mar 25 01:17:36.273268 kernel: raid6: using algorithm neonx4 gen() 15761 MB/s Mar 25 01:17:36.290263 kernel: raid6: .... xor() 12345 MB/s, rmw enabled Mar 25 01:17:36.290276 kernel: raid6: using neon recovery algorithm Mar 25 01:17:36.295594 kernel: xor: measuring software checksum speed Mar 25 01:17:36.295611 kernel: 8regs : 21613 MB/sec Mar 25 01:17:36.295625 kernel: 32regs : 21360 MB/sec Mar 25 01:17:36.296551 kernel: arm64_neon : 27168 MB/sec Mar 25 01:17:36.296564 kernel: xor: using function: arm64_neon (27168 MB/sec) Mar 25 01:17:36.345264 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 25 01:17:36.355992 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:17:36.358151 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:17:36.383743 systemd-udevd[460]: Using default interface naming scheme 'v255'. Mar 25 01:17:36.387952 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:17:36.390367 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 25 01:17:36.409278 dracut-pre-trigger[467]: rd.md=0: removing MD RAID activation Mar 25 01:17:36.433653 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:17:36.436384 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:17:36.485557 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:17:36.490068 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 25 01:17:36.508114 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 25 01:17:36.509839 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:17:36.511961 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:17:36.514173 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:17:36.517151 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 25 01:17:36.533638 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:17:36.540529 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Mar 25 01:17:36.552995 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Mar 25 01:17:36.553102 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Mar 25 01:17:36.553119 kernel: GPT:9289727 != 19775487 Mar 25 01:17:36.553128 kernel: GPT:Alternate GPT header not at the end of the disk. Mar 25 01:17:36.553139 kernel: GPT:9289727 != 19775487 Mar 25 01:17:36.553147 kernel: GPT: Use GNU Parted to correct GPT errors. Mar 25 01:17:36.553156 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:17:36.546624 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:17:36.546740 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:17:36.548015 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:17:36.548810 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:17:36.548938 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:17:36.550219 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:17:36.551854 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:17:36.567268 kernel: BTRFS: device fsid bf348154-9cb1-474d-801c-0e035a5758cf devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (514) Mar 25 01:17:36.573275 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (512) Mar 25 01:17:36.578048 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:17:36.585987 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 25 01:17:36.593537 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 25 01:17:36.599774 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 25 01:17:36.600963 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 25 01:17:36.613488 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 25 01:17:36.615454 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 25 01:17:36.617172 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 25 01:17:36.640921 disk-uuid[550]: Primary Header is updated. Mar 25 01:17:36.640921 disk-uuid[550]: Secondary Entries is updated. Mar 25 01:17:36.640921 disk-uuid[550]: Secondary Header is updated. Mar 25 01:17:36.645269 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:17:36.651972 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:17:37.656274 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 25 01:17:37.656692 disk-uuid[555]: The operation has completed successfully. Mar 25 01:17:37.683705 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 25 01:17:37.683807 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 25 01:17:37.708235 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 25 01:17:37.721188 sh[571]: Success Mar 25 01:17:37.736268 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 25 01:17:37.773768 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 25 01:17:37.775737 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 25 01:17:37.785403 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 25 01:17:37.792855 kernel: BTRFS info (device dm-0): first mount of filesystem bf348154-9cb1-474d-801c-0e035a5758cf Mar 25 01:17:37.792888 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Mar 25 01:17:37.792898 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 25 01:17:37.796278 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 25 01:17:37.796293 kernel: BTRFS info (device dm-0): using free space tree Mar 25 01:17:37.800394 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 25 01:17:37.801189 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 25 01:17:37.801900 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 25 01:17:37.804235 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 25 01:17:37.831833 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Mar 25 01:17:37.831872 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 25 01:17:37.832420 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:17:37.834341 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:17:37.838295 kernel: BTRFS info (device vda6): last unmount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Mar 25 01:17:37.840441 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 25 01:17:37.842348 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 25 01:17:37.906071 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:17:37.908919 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:17:37.947831 ignition[663]: Ignition 2.20.0 Mar 25 01:17:37.947841 ignition[663]: Stage: fetch-offline Mar 25 01:17:37.947871 ignition[663]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:37.947880 ignition[663]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:37.948087 ignition[663]: parsed url from cmdline: "" Mar 25 01:17:37.948090 ignition[663]: no config URL provided Mar 25 01:17:37.948095 ignition[663]: reading system config file "/usr/lib/ignition/user.ign" Mar 25 01:17:37.948102 ignition[663]: no config at "/usr/lib/ignition/user.ign" Mar 25 01:17:37.948127 ignition[663]: op(1): [started] loading QEMU firmware config module Mar 25 01:17:37.948131 ignition[663]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 25 01:17:37.954335 ignition[663]: op(1): [finished] loading QEMU firmware config module Mar 25 01:17:37.957838 systemd-networkd[757]: lo: Link UP Mar 25 01:17:37.957848 systemd-networkd[757]: lo: Gained carrier Mar 25 01:17:37.958657 systemd-networkd[757]: Enumeration completed Mar 25 01:17:37.958760 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:17:37.959040 systemd-networkd[757]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:17:37.959043 systemd-networkd[757]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:17:37.959764 systemd-networkd[757]: eth0: Link UP Mar 25 01:17:37.959767 systemd-networkd[757]: eth0: Gained carrier Mar 25 01:17:37.959776 systemd-networkd[757]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:17:37.968908 ignition[663]: parsing config with SHA512: 06fddfc3ac195b09f68efafaabf440908ba133f0eab241edee5aa3f96243738e3439cba7c95500fadcb00b9223acef7026fb7c8c8c5da938133040dba3be7852 Mar 25 01:17:37.961172 systemd[1]: Reached target network.target - Network. Mar 25 01:17:37.972665 unknown[663]: fetched base config from "system" Mar 25 01:17:37.972676 unknown[663]: fetched user config from "qemu" Mar 25 01:17:37.972932 ignition[663]: fetch-offline: fetch-offline passed Mar 25 01:17:37.974898 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:17:37.973010 ignition[663]: Ignition finished successfully Mar 25 01:17:37.976162 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 25 01:17:37.978357 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 25 01:17:37.979282 systemd-networkd[757]: eth0: DHCPv4 address 10.0.0.63/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 25 01:17:37.997641 ignition[771]: Ignition 2.20.0 Mar 25 01:17:37.997651 ignition[771]: Stage: kargs Mar 25 01:17:37.997798 ignition[771]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:37.997808 ignition[771]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:37.998447 ignition[771]: kargs: kargs passed Mar 25 01:17:37.998489 ignition[771]: Ignition finished successfully Mar 25 01:17:38.000619 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 25 01:17:38.002917 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 25 01:17:38.027192 ignition[781]: Ignition 2.20.0 Mar 25 01:17:38.028007 ignition[781]: Stage: disks Mar 25 01:17:38.028173 ignition[781]: no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:38.029869 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 25 01:17:38.028184 ignition[781]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:38.031626 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 25 01:17:38.028893 ignition[781]: disks: disks passed Mar 25 01:17:38.032885 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 25 01:17:38.028937 ignition[781]: Ignition finished successfully Mar 25 01:17:38.034714 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:17:38.036363 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:17:38.037567 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:17:38.040103 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 25 01:17:38.061877 systemd-fsck[792]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 25 01:17:38.065956 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 25 01:17:38.070008 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 25 01:17:38.123174 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 25 01:17:38.124558 kernel: EXT4-fs (vda9): mounted filesystem a7a89271-ee7d-4bda-a834-705261d6cda9 r/w with ordered data mode. Quota mode: none. Mar 25 01:17:38.124333 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 25 01:17:38.126418 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:17:38.127842 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 25 01:17:38.128631 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 25 01:17:38.128676 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 25 01:17:38.128699 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:17:38.144842 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 25 01:17:38.147285 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 25 01:17:38.152110 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (800) Mar 25 01:17:38.152135 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Mar 25 01:17:38.152145 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 25 01:17:38.152155 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:17:38.156263 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:17:38.157224 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:17:38.195672 initrd-setup-root[824]: cut: /sysroot/etc/passwd: No such file or directory Mar 25 01:17:38.200676 initrd-setup-root[831]: cut: /sysroot/etc/group: No such file or directory Mar 25 01:17:38.204486 initrd-setup-root[838]: cut: /sysroot/etc/shadow: No such file or directory Mar 25 01:17:38.207946 initrd-setup-root[845]: cut: /sysroot/etc/gshadow: No such file or directory Mar 25 01:17:38.277575 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 25 01:17:38.279706 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 25 01:17:38.281040 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 25 01:17:38.299276 kernel: BTRFS info (device vda6): last unmount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Mar 25 01:17:38.312437 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 25 01:17:38.321789 ignition[914]: INFO : Ignition 2.20.0 Mar 25 01:17:38.321789 ignition[914]: INFO : Stage: mount Mar 25 01:17:38.323555 ignition[914]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:38.323555 ignition[914]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:38.323555 ignition[914]: INFO : mount: mount passed Mar 25 01:17:38.323555 ignition[914]: INFO : Ignition finished successfully Mar 25 01:17:38.323990 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 25 01:17:38.326152 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 25 01:17:38.935362 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 25 01:17:38.936835 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 25 01:17:38.951932 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (925) Mar 25 01:17:38.951962 kernel: BTRFS info (device vda6): first mount of filesystem 09629b08-d05c-4ce3-8bf7-615041c4b2c9 Mar 25 01:17:38.951974 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Mar 25 01:17:38.952626 kernel: BTRFS info (device vda6): using free space tree Mar 25 01:17:38.955254 kernel: BTRFS info (device vda6): auto enabling async discard Mar 25 01:17:38.956209 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 25 01:17:38.984181 ignition[942]: INFO : Ignition 2.20.0 Mar 25 01:17:38.984181 ignition[942]: INFO : Stage: files Mar 25 01:17:38.985952 ignition[942]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:38.985952 ignition[942]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:38.985952 ignition[942]: DEBUG : files: compiled without relabeling support, skipping Mar 25 01:17:38.989614 ignition[942]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 25 01:17:38.989614 ignition[942]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 25 01:17:38.989614 ignition[942]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 25 01:17:38.993919 ignition[942]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 25 01:17:38.993919 ignition[942]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Mar 25 01:17:38.993919 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 Mar 25 01:17:38.990037 unknown[942]: wrote ssh authorized keys file for user: core Mar 25 01:17:39.369764 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 25 01:17:39.392334 systemd-networkd[757]: eth0: Gained IPv6LL Mar 25 01:17:39.698406 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Mar 25 01:17:39.698406 ignition[942]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Mar 25 01:17:39.701358 ignition[942]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 25 01:17:39.701358 ignition[942]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 25 01:17:39.701358 ignition[942]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Mar 25 01:17:39.701358 ignition[942]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Mar 25 01:17:39.715826 ignition[942]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 25 01:17:39.719276 ignition[942]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 25 01:17:39.720449 ignition[942]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Mar 25 01:17:39.720449 ignition[942]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:17:39.720449 ignition[942]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 25 01:17:39.720449 ignition[942]: INFO : files: files passed Mar 25 01:17:39.720449 ignition[942]: INFO : Ignition finished successfully Mar 25 01:17:39.721276 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 25 01:17:39.726602 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 25 01:17:39.729798 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 25 01:17:39.744580 initrd-setup-root-after-ignition[970]: grep: /sysroot/oem/oem-release: No such file or directory Mar 25 01:17:39.745921 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 25 01:17:39.746016 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 25 01:17:39.750106 initrd-setup-root-after-ignition[973]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:17:39.750106 initrd-setup-root-after-ignition[973]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:17:39.752789 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 25 01:17:39.753138 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:17:39.754968 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 25 01:17:39.757066 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 25 01:17:39.790036 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 25 01:17:39.790133 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 25 01:17:39.791886 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 25 01:17:39.793354 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 25 01:17:39.794839 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 25 01:17:39.795582 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 25 01:17:39.809695 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:17:39.811690 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 25 01:17:39.829584 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:17:39.830504 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:17:39.832223 systemd[1]: Stopped target timers.target - Timer Units. Mar 25 01:17:39.833899 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 25 01:17:39.834021 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 25 01:17:39.836166 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 25 01:17:39.837968 systemd[1]: Stopped target basic.target - Basic System. Mar 25 01:17:39.839400 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 25 01:17:39.840843 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 25 01:17:39.842546 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 25 01:17:39.844286 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 25 01:17:39.846286 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 25 01:17:39.848018 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 25 01:17:39.849799 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 25 01:17:39.851271 systemd[1]: Stopped target swap.target - Swaps. Mar 25 01:17:39.852700 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 25 01:17:39.852820 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 25 01:17:39.855006 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:17:39.856805 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:17:39.858404 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 25 01:17:39.859354 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:17:39.860604 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 25 01:17:39.860718 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 25 01:17:39.863461 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 25 01:17:39.863631 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 25 01:17:39.865630 systemd[1]: Stopped target paths.target - Path Units. Mar 25 01:17:39.867200 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 25 01:17:39.872309 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:17:39.874994 systemd[1]: Stopped target slices.target - Slice Units. Mar 25 01:17:39.876075 systemd[1]: Stopped target sockets.target - Socket Units. Mar 25 01:17:39.877650 systemd[1]: iscsid.socket: Deactivated successfully. Mar 25 01:17:39.877779 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 25 01:17:39.879371 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 25 01:17:39.879488 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 25 01:17:39.881007 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 25 01:17:39.881160 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 25 01:17:39.882897 systemd[1]: ignition-files.service: Deactivated successfully. Mar 25 01:17:39.883038 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 25 01:17:39.885374 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 25 01:17:39.887104 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 25 01:17:39.887328 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:17:39.896839 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 25 01:17:39.897708 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 25 01:17:39.897912 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:17:39.900666 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 25 01:17:39.900846 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 25 01:17:39.908376 ignition[998]: INFO : Ignition 2.20.0 Mar 25 01:17:39.908376 ignition[998]: INFO : Stage: umount Mar 25 01:17:39.912100 ignition[998]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 25 01:17:39.912100 ignition[998]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 25 01:17:39.912100 ignition[998]: INFO : umount: umount passed Mar 25 01:17:39.912100 ignition[998]: INFO : Ignition finished successfully Mar 25 01:17:39.908496 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 25 01:17:39.908580 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 25 01:17:39.912172 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 25 01:17:39.912655 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 25 01:17:39.912749 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 25 01:17:39.915445 systemd[1]: Stopped target network.target - Network. Mar 25 01:17:39.916354 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 25 01:17:39.916426 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 25 01:17:39.918561 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 25 01:17:39.918606 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 25 01:17:39.920296 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 25 01:17:39.920351 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 25 01:17:39.922136 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 25 01:17:39.922179 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 25 01:17:39.924177 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 25 01:17:39.926262 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 25 01:17:39.932455 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 25 01:17:39.933344 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 25 01:17:39.936745 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 25 01:17:39.936969 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 25 01:17:39.937057 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 25 01:17:39.939969 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 25 01:17:39.940555 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 25 01:17:39.940612 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:17:39.942973 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 25 01:17:39.943747 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 25 01:17:39.943803 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 25 01:17:39.945461 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 25 01:17:39.945500 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:17:39.948117 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 25 01:17:39.948161 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 25 01:17:39.949786 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 25 01:17:39.949826 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:17:39.952609 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:17:39.954104 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 25 01:17:39.954159 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:17:39.966969 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 25 01:17:39.971416 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 25 01:17:39.972580 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 25 01:17:39.972657 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 25 01:17:39.974030 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 25 01:17:39.974147 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:17:39.975762 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 25 01:17:39.975842 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 25 01:17:39.977450 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 25 01:17:39.977511 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 25 01:17:39.978656 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 25 01:17:39.978683 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:17:39.980395 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 25 01:17:39.980437 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 25 01:17:39.982935 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 25 01:17:39.982979 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 25 01:17:39.985514 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 25 01:17:39.985558 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 25 01:17:39.987974 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 25 01:17:39.988990 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 25 01:17:39.989043 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:17:39.991536 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 25 01:17:39.991574 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:17:39.994786 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 25 01:17:39.994840 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 25 01:17:40.002140 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 25 01:17:40.002281 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 25 01:17:40.004159 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 25 01:17:40.006704 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 25 01:17:40.026120 systemd[1]: Switching root. Mar 25 01:17:40.052103 systemd-journald[237]: Journal stopped Mar 25 01:17:40.764202 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Mar 25 01:17:40.764274 kernel: SELinux: policy capability network_peer_controls=1 Mar 25 01:17:40.764287 kernel: SELinux: policy capability open_perms=1 Mar 25 01:17:40.764297 kernel: SELinux: policy capability extended_socket_class=1 Mar 25 01:17:40.764306 kernel: SELinux: policy capability always_check_network=0 Mar 25 01:17:40.764324 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 25 01:17:40.764337 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 25 01:17:40.764347 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 25 01:17:40.764361 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 25 01:17:40.764373 kernel: audit: type=1403 audit(1742865460.175:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 25 01:17:40.764388 systemd[1]: Successfully loaded SELinux policy in 32.012ms. Mar 25 01:17:40.764401 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.364ms. Mar 25 01:17:40.764412 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Mar 25 01:17:40.764426 systemd[1]: Detected virtualization kvm. Mar 25 01:17:40.764439 systemd[1]: Detected architecture arm64. Mar 25 01:17:40.764451 systemd[1]: Detected first boot. Mar 25 01:17:40.764461 systemd[1]: Initializing machine ID from VM UUID. Mar 25 01:17:40.764472 zram_generator::config[1046]: No configuration found. Mar 25 01:17:40.764483 kernel: NET: Registered PF_VSOCK protocol family Mar 25 01:17:40.764492 systemd[1]: Populated /etc with preset unit settings. Mar 25 01:17:40.764503 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 25 01:17:40.764513 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 25 01:17:40.764523 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 25 01:17:40.764535 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 25 01:17:40.764545 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 25 01:17:40.764556 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 25 01:17:40.764566 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 25 01:17:40.764578 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 25 01:17:40.764588 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 25 01:17:40.764598 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 25 01:17:40.764611 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 25 01:17:40.764623 systemd[1]: Created slice user.slice - User and Session Slice. Mar 25 01:17:40.764633 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 25 01:17:40.764644 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 25 01:17:40.764654 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 25 01:17:40.764665 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 25 01:17:40.764675 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 25 01:17:40.764686 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 25 01:17:40.764696 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Mar 25 01:17:40.764707 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 25 01:17:40.764719 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 25 01:17:40.764729 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 25 01:17:40.764739 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 25 01:17:40.764750 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 25 01:17:40.764759 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 25 01:17:40.764769 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 25 01:17:40.764780 systemd[1]: Reached target slices.target - Slice Units. Mar 25 01:17:40.764790 systemd[1]: Reached target swap.target - Swaps. Mar 25 01:17:40.764801 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 25 01:17:40.764812 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 25 01:17:40.764822 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 25 01:17:40.764832 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 25 01:17:40.764842 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 25 01:17:40.764853 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 25 01:17:40.764864 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 25 01:17:40.764874 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 25 01:17:40.764884 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 25 01:17:40.764896 systemd[1]: Mounting media.mount - External Media Directory... Mar 25 01:17:40.764907 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 25 01:17:40.764917 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 25 01:17:40.764927 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 25 01:17:40.764938 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). Mar 25 01:17:40.764949 systemd[1]: Reached target machines.target - Containers. Mar 25 01:17:40.764959 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 25 01:17:40.764970 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:17:40.764982 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 25 01:17:40.764992 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 25 01:17:40.765002 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:17:40.765012 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:17:40.765024 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:17:40.765034 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 25 01:17:40.765046 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:17:40.765056 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 25 01:17:40.765066 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 25 01:17:40.765079 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 25 01:17:40.765089 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 25 01:17:40.765099 systemd[1]: Stopped systemd-fsck-usr.service. Mar 25 01:17:40.765110 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:17:40.765120 kernel: fuse: init (API version 7.39) Mar 25 01:17:40.765129 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 25 01:17:40.765139 kernel: loop: module loaded Mar 25 01:17:40.765148 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 25 01:17:40.765159 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 25 01:17:40.765170 kernel: ACPI: bus type drm_connector registered Mar 25 01:17:40.765180 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 25 01:17:40.765190 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 25 01:17:40.765200 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 25 01:17:40.765210 systemd[1]: verity-setup.service: Deactivated successfully. Mar 25 01:17:40.765220 systemd[1]: Stopped verity-setup.service. Mar 25 01:17:40.765231 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 25 01:17:40.765250 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 25 01:17:40.765264 systemd[1]: Mounted media.mount - External Media Directory. Mar 25 01:17:40.765276 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 25 01:17:40.765286 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 25 01:17:40.765296 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 25 01:17:40.765331 systemd-journald[1118]: Collecting audit messages is disabled. Mar 25 01:17:40.765357 systemd-journald[1118]: Journal started Mar 25 01:17:40.765378 systemd-journald[1118]: Runtime Journal (/run/log/journal/68aaa28476354bc183d54dcc30db90aa) is 5.9M, max 47.3M, 41.4M free. Mar 25 01:17:40.560909 systemd[1]: Queued start job for default target multi-user.target. Mar 25 01:17:40.573082 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 25 01:17:40.573462 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 25 01:17:40.768088 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 25 01:17:40.769902 systemd[1]: Started systemd-journald.service - Journal Service. Mar 25 01:17:40.771332 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 25 01:17:40.772806 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 25 01:17:40.772981 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 25 01:17:40.774524 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:17:40.774685 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:17:40.776069 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:17:40.776255 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:17:40.777702 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:17:40.777878 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:17:40.779403 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 25 01:17:40.779555 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 25 01:17:40.780879 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:17:40.781042 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:17:40.782485 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 25 01:17:40.783944 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 25 01:17:40.785536 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 25 01:17:40.787067 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 25 01:17:40.799588 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 25 01:17:40.802148 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 25 01:17:40.804237 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 25 01:17:40.805416 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 25 01:17:40.805455 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 25 01:17:40.807443 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 25 01:17:40.818031 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 25 01:17:40.820210 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 25 01:17:40.821376 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:17:40.822339 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 25 01:17:40.825401 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 25 01:17:40.826733 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:17:40.828415 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 25 01:17:40.829912 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:17:40.832253 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 25 01:17:40.832407 systemd-journald[1118]: Time spent on flushing to /var/log/journal/68aaa28476354bc183d54dcc30db90aa is 10.660ms for 850 entries. Mar 25 01:17:40.832407 systemd-journald[1118]: System Journal (/var/log/journal/68aaa28476354bc183d54dcc30db90aa) is 8M, max 195.6M, 187.6M free. Mar 25 01:17:40.856079 systemd-journald[1118]: Received client request to flush runtime journal. Mar 25 01:17:40.847388 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 25 01:17:40.855916 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 25 01:17:40.864337 kernel: loop0: detected capacity change from 0 to 126448 Mar 25 01:17:40.868952 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 25 01:17:40.871578 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 25 01:17:40.872699 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 25 01:17:40.875277 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 25 01:17:40.875816 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 25 01:17:40.878761 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 25 01:17:40.880190 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 25 01:17:40.885482 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 25 01:17:40.887932 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 25 01:17:40.889910 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 25 01:17:40.891343 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 25 01:17:40.901281 kernel: loop1: detected capacity change from 0 to 189592 Mar 25 01:17:40.911915 udevadm[1179]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 25 01:17:40.913347 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 25 01:17:40.914184 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 25 01:17:40.915500 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 25 01:17:40.919019 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 25 01:17:40.936286 kernel: loop2: detected capacity change from 0 to 103832 Mar 25 01:17:40.954556 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Mar 25 01:17:40.954572 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Mar 25 01:17:40.958911 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 25 01:17:40.970275 kernel: loop3: detected capacity change from 0 to 126448 Mar 25 01:17:40.975268 kernel: loop4: detected capacity change from 0 to 189592 Mar 25 01:17:40.981302 kernel: loop5: detected capacity change from 0 to 103832 Mar 25 01:17:40.984690 (sd-merge)[1189]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Mar 25 01:17:40.985119 (sd-merge)[1189]: Merged extensions into '/usr'. Mar 25 01:17:40.988587 systemd[1]: Reload requested from client PID 1164 ('systemd-sysext') (unit systemd-sysext.service)... Mar 25 01:17:40.988701 systemd[1]: Reloading... Mar 25 01:17:41.045261 zram_generator::config[1213]: No configuration found. Mar 25 01:17:41.094616 ldconfig[1159]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 25 01:17:41.155950 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. Mar 25 01:17:41.206086 systemd[1]: Reloading finished in 216 ms. Mar 25 01:17:41.233026 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 25 01:17:41.234545 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 25 01:17:41.253533 systemd[1]: Starting ensure-sysext.service... Mar 25 01:17:41.255277 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 25 01:17:41.271482 systemd-tmpfiles[1252]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 25 01:17:41.271699 systemd-tmpfiles[1252]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 25 01:17:41.272359 systemd-tmpfiles[1252]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 25 01:17:41.272572 systemd-tmpfiles[1252]: ACLs are not supported, ignoring. Mar 25 01:17:41.272623 systemd-tmpfiles[1252]: ACLs are not supported, ignoring. Mar 25 01:17:41.275131 systemd-tmpfiles[1252]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:17:41.275145 systemd-tmpfiles[1252]: Skipping /boot Mar 25 01:17:41.275418 systemd[1]: Reload requested from client PID 1251 ('systemctl') (unit ensure-sysext.service)... Mar 25 01:17:41.275432 systemd[1]: Reloading... Mar 25 01:17:41.284069 systemd-tmpfiles[1252]: Detected autofs mount point /boot during canonicalization of boot. Mar 25 01:17:41.284088 systemd-tmpfiles[1252]: Skipping /boot Mar 25 01:17:41.323278 zram_generator::config[1284]: No configuration found. Mar 25 01:17:41.400118 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. Mar 25 01:17:41.450083 systemd[1]: Reloading finished in 174 ms. Mar 25 01:17:41.461887 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 25 01:17:41.473417 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 25 01:17:41.481611 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:17:41.483886 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 25 01:17:41.497175 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 25 01:17:41.503495 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 25 01:17:41.505909 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 25 01:17:41.508223 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 25 01:17:41.511655 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:17:41.522148 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:17:41.527497 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:17:41.529676 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:17:41.531341 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:17:41.531450 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:17:41.534308 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 25 01:17:41.536182 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:17:41.536388 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:17:41.538087 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:17:41.538278 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:17:41.540046 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:17:41.540228 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:17:41.549406 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:17:41.550966 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:17:41.555944 systemd-udevd[1327]: Using default interface naming scheme 'v255'. Mar 25 01:17:41.556606 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:17:41.559027 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:17:41.560202 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:17:41.560439 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:17:41.568548 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 25 01:17:41.571495 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 25 01:17:41.574418 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:17:41.574664 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:17:41.577978 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:17:41.578144 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:17:41.579664 augenrules[1354]: No rules Mar 25 01:17:41.580108 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 25 01:17:41.582013 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:17:41.582203 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:17:41.583781 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:17:41.583927 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:17:41.585726 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 25 01:17:41.589375 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 25 01:17:41.596431 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 25 01:17:41.603472 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 25 01:17:41.604469 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 25 01:17:41.605406 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 25 01:17:41.609476 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 25 01:17:41.612412 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 25 01:17:41.630022 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 25 01:17:41.631348 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 25 01:17:41.631396 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Mar 25 01:17:41.638708 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 25 01:17:41.640062 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). Mar 25 01:17:41.655773 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1373) Mar 25 01:17:41.662949 augenrules[1376]: /sbin/augenrules: No change Mar 25 01:17:41.672793 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 25 01:17:41.675293 systemd[1]: Finished ensure-sysext.service. Mar 25 01:17:41.677605 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 25 01:17:41.677762 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 25 01:17:41.679345 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 25 01:17:41.679509 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 25 01:17:41.680815 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 25 01:17:41.681098 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 25 01:17:41.682869 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 25 01:17:41.683021 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 25 01:17:41.685281 augenrules[1417]: No rules Mar 25 01:17:41.685671 systemd[1]: audit-rules.service: Deactivated successfully. Mar 25 01:17:41.685869 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 25 01:17:41.695302 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Mar 25 01:17:41.701542 systemd-resolved[1321]: Positive Trust Anchors: Mar 25 01:17:41.701555 systemd-resolved[1321]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 25 01:17:41.701586 systemd-resolved[1321]: 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 Mar 25 01:17:41.708089 systemd-resolved[1321]: Defaulting to hostname 'linux'. Mar 25 01:17:41.711044 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 25 01:17:41.712623 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 25 01:17:41.713940 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 25 01:17:41.716471 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 25 01:17:41.717647 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 25 01:17:41.717715 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 25 01:17:41.721852 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 25 01:17:41.746297 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 25 01:17:41.774430 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 25 01:17:41.778429 systemd-networkd[1395]: lo: Link UP Mar 25 01:17:41.778437 systemd-networkd[1395]: lo: Gained carrier Mar 25 01:17:41.781980 systemd-networkd[1395]: Enumeration completed Mar 25 01:17:41.787376 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 25 01:17:41.788500 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 25 01:17:41.790156 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:17:41.790166 systemd-networkd[1395]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 25 01:17:41.790427 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 25 01:17:41.791160 systemd-networkd[1395]: eth0: Link UP Mar 25 01:17:41.791167 systemd-networkd[1395]: eth0: Gained carrier Mar 25 01:17:41.791182 systemd-networkd[1395]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 25 01:17:41.794205 systemd[1]: Reached target network.target - Network. Mar 25 01:17:41.795392 systemd[1]: Reached target time-set.target - System Time Set. Mar 25 01:17:41.797659 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 25 01:17:41.802480 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 25 01:17:41.808183 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 25 01:17:41.819436 systemd-networkd[1395]: eth0: DHCPv4 address 10.0.0.63/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 25 01:17:41.820585 systemd-timesyncd[1431]: Network configuration changed, trying to establish connection. Mar 25 01:17:41.821499 systemd-timesyncd[1431]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 25 01:17:41.821556 systemd-timesyncd[1431]: Initial clock synchronization to Tue 2025-03-25 01:17:41.740602 UTC. Mar 25 01:17:41.824217 lvm[1440]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:17:41.827751 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 25 01:17:41.835910 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 25 01:17:41.853337 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 25 01:17:41.854562 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 25 01:17:41.855439 systemd[1]: Reached target sysinit.target - System Initialization. Mar 25 01:17:41.856330 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 25 01:17:41.857253 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 25 01:17:41.858399 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 25 01:17:41.859302 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 25 01:17:41.860316 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 25 01:17:41.861197 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 25 01:17:41.861233 systemd[1]: Reached target paths.target - Path Units. Mar 25 01:17:41.861899 systemd[1]: Reached target timers.target - Timer Units. Mar 25 01:17:41.863413 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 25 01:17:41.865739 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 25 01:17:41.868964 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 25 01:17:41.870302 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 25 01:17:41.871231 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 25 01:17:41.874279 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 25 01:17:41.875820 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 25 01:17:41.878046 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 25 01:17:41.879515 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 25 01:17:41.880530 systemd[1]: Reached target sockets.target - Socket Units. Mar 25 01:17:41.881276 systemd[1]: Reached target basic.target - Basic System. Mar 25 01:17:41.882013 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:17:41.882044 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 25 01:17:41.883098 systemd[1]: Starting containerd.service - containerd container runtime... Mar 25 01:17:41.884934 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 25 01:17:41.885964 lvm[1452]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 25 01:17:41.888474 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 25 01:17:41.893443 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 25 01:17:41.894968 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). Mar 25 01:17:41.896471 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 25 01:17:41.899456 jq[1455]: false Mar 25 01:17:41.899708 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 25 01:17:41.904777 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 25 01:17:41.906769 dbus-daemon[1454]: [system] SELinux support is enabled Mar 25 01:17:41.913025 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 25 01:17:41.915356 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 25 01:17:41.919514 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 25 01:17:41.920186 systemd[1]: Starting update-engine.service - Update Engine... Mar 25 01:17:41.924123 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 25 01:17:41.925100 extend-filesystems[1456]: Found loop3 Mar 25 01:17:41.925100 extend-filesystems[1456]: Found loop4 Mar 25 01:17:41.925100 extend-filesystems[1456]: Found loop5 Mar 25 01:17:41.925100 extend-filesystems[1456]: Found vda Mar 25 01:17:41.925100 extend-filesystems[1456]: Found vda1 Mar 25 01:17:41.925100 extend-filesystems[1456]: Found vda2 Mar 25 01:17:41.925100 extend-filesystems[1456]: Found vda3 Mar 25 01:17:41.929934 extend-filesystems[1456]: Found usr Mar 25 01:17:41.929934 extend-filesystems[1456]: Found vda4 Mar 25 01:17:41.929934 extend-filesystems[1456]: Found vda6 Mar 25 01:17:41.929934 extend-filesystems[1456]: Found vda7 Mar 25 01:17:41.929934 extend-filesystems[1456]: Found vda9 Mar 25 01:17:41.929934 extend-filesystems[1456]: Checking size of /dev/vda9 Mar 25 01:17:41.925762 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 25 01:17:41.930006 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 25 01:17:41.936789 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 25 01:17:41.936971 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 25 01:17:41.937219 systemd[1]: motdgen.service: Deactivated successfully. Mar 25 01:17:41.937429 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 25 01:17:41.938598 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 25 01:17:41.938777 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 25 01:17:41.943651 extend-filesystems[1456]: Resized partition /dev/vda9 Mar 25 01:17:41.946286 extend-filesystems[1482]: resize2fs 1.47.2 (1-Jan-2025) Mar 25 01:17:41.946796 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). Mar 25 01:17:41.946825 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 25 01:17:41.948161 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). Mar 25 01:17:41.948187 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 25 01:17:41.950269 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Mar 25 01:17:41.956129 jq[1470]: true Mar 25 01:17:41.956864 (ntainerd)[1485]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 25 01:17:41.971348 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1366) Mar 25 01:17:41.971431 jq[1487]: true Mar 25 01:17:41.978262 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Mar 25 01:17:41.992519 extend-filesystems[1482]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Mar 25 01:17:41.992519 extend-filesystems[1482]: old_desc_blocks = 1, new_desc_blocks = 1 Mar 25 01:17:41.992519 extend-filesystems[1482]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Mar 25 01:17:41.995353 extend-filesystems[1456]: Resized filesystem in /dev/vda9 Mar 25 01:17:41.995145 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 25 01:17:41.995447 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 25 01:17:42.007386 systemd-logind[1463]: Watching system buttons on /dev/input/event0 (Power Button) Mar 25 01:17:42.007641 systemd-logind[1463]: New seat seat0. Mar 25 01:17:42.014642 update_engine[1467]: I20250325 01:17:42.013484 1467 main.cc:92] Flatcar Update Engine starting Mar 25 01:17:42.016989 systemd[1]: Started update-engine.service - Update Engine. Mar 25 01:17:42.017216 update_engine[1467]: I20250325 01:17:42.017169 1467 update_check_scheduler.cc:74] Next update check in 6m14s Mar 25 01:17:42.023890 systemd[1]: Started systemd-logind.service - User Login Management. Mar 25 01:17:42.027250 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 25 01:17:42.047751 bash[1506]: Updated "/home/core/.ssh/authorized_keys" Mar 25 01:17:42.049409 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 25 01:17:42.051664 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 25 01:17:42.088469 locksmithd[1505]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 25 01:17:42.184433 containerd[1485]: time="2025-03-25T01:17:42Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Mar 25 01:17:42.185482 containerd[1485]: time="2025-03-25T01:17:42.185441907Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194657701Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.89µs" Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194690170Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194707038Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194851682Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194867758Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194893376Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194938555Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195268 containerd[1485]: time="2025-03-25T01:17:42.194950909Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195496 containerd[1485]: time="2025-03-25T01:17:42.195221626Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195545 containerd[1485]: time="2025-03-25T01:17:42.195531781Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195600 containerd[1485]: time="2025-03-25T01:17:42.195585434Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195646 containerd[1485]: time="2025-03-25T01:17:42.195634058Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 25 01:17:42.195800 containerd[1485]: time="2025-03-25T01:17:42.195779454Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 25 01:17:42.196069 containerd[1485]: time="2025-03-25T01:17:42.196046172Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:17:42.196159 containerd[1485]: time="2025-03-25T01:17:42.196142271Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 25 01:17:42.196208 containerd[1485]: time="2025-03-25T01:17:42.196195964Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 25 01:17:42.197267 containerd[1485]: time="2025-03-25T01:17:42.197220112Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 25 01:17:42.197678 containerd[1485]: time="2025-03-25T01:17:42.197642007Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 25 01:17:42.197757 containerd[1485]: time="2025-03-25T01:17:42.197739452Z" level=info msg="metadata content store policy set" policy=shared Mar 25 01:17:42.200691 containerd[1485]: time="2025-03-25T01:17:42.200659692Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 25 01:17:42.200740 containerd[1485]: time="2025-03-25T01:17:42.200708632Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 25 01:17:42.200740 containerd[1485]: time="2025-03-25T01:17:42.200725579Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 25 01:17:42.200777 containerd[1485]: time="2025-03-25T01:17:42.200739992Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 25 01:17:42.200777 containerd[1485]: time="2025-03-25T01:17:42.200753099Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 25 01:17:42.200777 containerd[1485]: time="2025-03-25T01:17:42.200764106Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 25 01:17:42.200777 containerd[1485]: time="2025-03-25T01:17:42.200776104Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 25 01:17:42.200857 containerd[1485]: time="2025-03-25T01:17:42.200788418Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 25 01:17:42.200857 containerd[1485]: time="2025-03-25T01:17:42.200799505Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 25 01:17:42.200857 containerd[1485]: time="2025-03-25T01:17:42.200809958Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 25 01:17:42.200857 containerd[1485]: time="2025-03-25T01:17:42.200820412Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 25 01:17:42.200857 containerd[1485]: time="2025-03-25T01:17:42.200833597Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 25 01:17:42.200970 containerd[1485]: time="2025-03-25T01:17:42.200948703Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 25 01:17:42.200997 containerd[1485]: time="2025-03-25T01:17:42.200978281Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 25 01:17:42.200997 containerd[1485]: time="2025-03-25T01:17:42.200992456Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 25 01:17:42.201036 containerd[1485]: time="2025-03-25T01:17:42.201003582Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 25 01:17:42.201036 containerd[1485]: time="2025-03-25T01:17:42.201014155Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 25 01:17:42.201036 containerd[1485]: time="2025-03-25T01:17:42.201024450Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 25 01:17:42.201085 containerd[1485]: time="2025-03-25T01:17:42.201037477Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 25 01:17:42.201085 containerd[1485]: time="2025-03-25T01:17:42.201048524Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 25 01:17:42.201085 containerd[1485]: time="2025-03-25T01:17:42.201061511Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 25 01:17:42.201085 containerd[1485]: time="2025-03-25T01:17:42.201072083Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 25 01:17:42.201085 containerd[1485]: time="2025-03-25T01:17:42.201082378Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 25 01:17:42.201382 containerd[1485]: time="2025-03-25T01:17:42.201356263Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 25 01:17:42.201410 containerd[1485]: time="2025-03-25T01:17:42.201381724Z" level=info msg="Start snapshots syncer" Mar 25 01:17:42.201440 containerd[1485]: time="2025-03-25T01:17:42.201410351Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 25 01:17:42.201940 containerd[1485]: time="2025-03-25T01:17:42.201882216Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Mar 25 01:17:42.202059 containerd[1485]: time="2025-03-25T01:17:42.202039214Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 25 01:17:42.202189 containerd[1485]: time="2025-03-25T01:17:42.202169881Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 25 01:17:42.202496 containerd[1485]: time="2025-03-25T01:17:42.202458733Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 25 01:17:42.202532 containerd[1485]: time="2025-03-25T01:17:42.202502843Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 25 01:17:42.202532 containerd[1485]: time="2025-03-25T01:17:42.202521651Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 25 01:17:42.202567 containerd[1485]: time="2025-03-25T01:17:42.202536222Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 25 01:17:42.202567 containerd[1485]: time="2025-03-25T01:17:42.202553763Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 25 01:17:42.202704 containerd[1485]: time="2025-03-25T01:17:42.202567582Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 25 01:17:42.202735 containerd[1485]: time="2025-03-25T01:17:42.202720739Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 25 01:17:42.202869 containerd[1485]: time="2025-03-25T01:17:42.202764374Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 25 01:17:42.202869 containerd[1485]: time="2025-03-25T01:17:42.202789161Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 25 01:17:42.202869 containerd[1485]: time="2025-03-25T01:17:42.202806227Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 25 01:17:42.203104 containerd[1485]: time="2025-03-25T01:17:42.203085497Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:17:42.203138 containerd[1485]: time="2025-03-25T01:17:42.203107433Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 25 01:17:42.203138 containerd[1485]: time="2025-03-25T01:17:42.203117292Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:17:42.203138 containerd[1485]: time="2025-03-25T01:17:42.203127469Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 25 01:17:42.203138 containerd[1485]: time="2025-03-25T01:17:42.203135704Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 25 01:17:42.203215 containerd[1485]: time="2025-03-25T01:17:42.203145722Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 25 01:17:42.203215 containerd[1485]: time="2025-03-25T01:17:42.203156968Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 25 01:17:42.203299 containerd[1485]: time="2025-03-25T01:17:42.203236516Z" level=info msg="runtime interface created" Mar 25 01:17:42.203299 containerd[1485]: time="2025-03-25T01:17:42.203254967Z" level=info msg="created NRI interface" Mar 25 01:17:42.203299 containerd[1485]: time="2025-03-25T01:17:42.203264906Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 25 01:17:42.203299 containerd[1485]: time="2025-03-25T01:17:42.203277577Z" level=info msg="Connect containerd service" Mar 25 01:17:42.203422 containerd[1485]: time="2025-03-25T01:17:42.203308382Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 25 01:17:42.203946 containerd[1485]: time="2025-03-25T01:17:42.203919823Z" 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" Mar 25 01:17:42.308491 containerd[1485]: time="2025-03-25T01:17:42.308429794Z" level=info msg="Start subscribing containerd event" Mar 25 01:17:42.308742 containerd[1485]: time="2025-03-25T01:17:42.308628130Z" level=info msg="Start recovering state" Mar 25 01:17:42.308866 containerd[1485]: time="2025-03-25T01:17:42.308818428Z" level=info msg="Start event monitor" Mar 25 01:17:42.308866 containerd[1485]: time="2025-03-25T01:17:42.308845551Z" level=info msg="Start cni network conf syncer for default" Mar 25 01:17:42.309108 containerd[1485]: time="2025-03-25T01:17:42.308854658Z" level=info msg="Start streaming server" Mar 25 01:17:42.309108 containerd[1485]: time="2025-03-25T01:17:42.308991541Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 25 01:17:42.309108 containerd[1485]: time="2025-03-25T01:17:42.309002747Z" level=info msg="runtime interface starting up..." Mar 25 01:17:42.309108 containerd[1485]: time="2025-03-25T01:17:42.309008845Z" level=info msg="starting plugins..." Mar 25 01:17:42.309108 containerd[1485]: time="2025-03-25T01:17:42.309052559Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 25 01:17:42.312353 containerd[1485]: time="2025-03-25T01:17:42.312322866Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 25 01:17:42.312407 containerd[1485]: time="2025-03-25T01:17:42.312381824Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 25 01:17:42.312609 containerd[1485]: time="2025-03-25T01:17:42.312436150Z" level=info msg="containerd successfully booted in 0.128511s" Mar 25 01:17:42.312538 systemd[1]: Started containerd.service - containerd container runtime. Mar 25 01:17:43.156697 sshd_keygen[1471]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 25 01:17:43.175193 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 25 01:17:43.177987 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 25 01:17:43.202721 systemd[1]: issuegen.service: Deactivated successfully. Mar 25 01:17:43.202927 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 25 01:17:43.206703 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 25 01:17:43.231654 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 25 01:17:43.236477 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 25 01:17:43.238574 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Mar 25 01:17:43.239970 systemd[1]: Reached target getty.target - Login Prompts. Mar 25 01:17:43.488352 systemd-networkd[1395]: eth0: Gained IPv6LL Mar 25 01:17:43.490655 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 25 01:17:43.492395 systemd[1]: Reached target network-online.target - Network is Online. Mar 25 01:17:43.494896 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 25 01:17:43.497150 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 25 01:17:43.509702 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 25 01:17:43.523076 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 25 01:17:43.523323 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 25 01:17:43.524778 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 25 01:17:43.531197 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 25 01:17:43.983797 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 25 01:17:43.985504 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 25 01:17:43.986749 systemd[1]: Startup finished in 523ms (kernel) + 4.484s (initrd) + 3.841s (userspace) = 8.849s. Mar 25 01:17:43.987515 (kubelet)[1574]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 25 01:17:44.400839 kubelet[1574]: E0325 01:17:44.400719 1574 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" Mar 25 01:17:44.403451 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 25 01:17:44.403599 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 25 01:17:44.405315 systemd[1]: kubelet.service: Consumed 775ms CPU time, 234.7M memory peak. Mar 25 01:17:48.604582 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 25 01:17:48.605678 systemd[1]: Started sshd@0-10.0.0.63:22-10.0.0.1:56220.service - OpenSSH per-connection server daemon (10.0.0.1:56220). Mar 25 01:17:48.678374 sshd[1588]: Accepted publickey for core from 10.0.0.1 port 56220 ssh2: RSA SHA256:RyyrKoKHvyGTiWIDeMwuNNfmpVLXChNPYxUIZdc99cw Mar 25 01:17:48.680180 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:17:48.685709 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 25 01:17:48.686598 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 25 01:17:48.691261 systemd-logind[1463]: New session 1 of user core. Mar 25 01:17:48.705962 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 25 01:17:48.708463 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 25 01:17:48.727155 (systemd)[1592]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 25 01:17:48.729140 systemd-logind[1463]: New session c1 of user core. Mar 25 01:17:48.831056 systemd[1592]: Queued start job for default target default.target. Mar 25 01:17:48.840186 systemd[1592]: Created slice app.slice - User Application Slice. Mar 25 01:17:48.840215 systemd[1592]: Reached target paths.target - Paths. Mar 25 01:17:48.840275 systemd[1592]: Reached target timers.target - Timers. Mar 25 01:17:48.841448 systemd[1592]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 25 01:17:48.850402 systemd[1592]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 25 01:17:48.850472 systemd[1592]: Reached target sockets.target - Sockets. Mar 25 01:17:48.850508 systemd[1592]: Reached target basic.target - Basic System. Mar 25 01:17:48.850539 systemd[1592]: Reached target default.target - Main User Target. Mar 25 01:17:48.850563 systemd[1592]: Startup finished in 116ms. Mar 25 01:17:48.850717 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 25 01:17:48.852024 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 25 01:17:48.911620 systemd[1]: Started sshd@1-10.0.0.63:22-10.0.0.1:56232.service - OpenSSH per-connection server daemon (10.0.0.1:56232). Mar 25 01:17:48.966372 sshd[1603]: Accepted publickey for core from 10.0.0.1 port 56232 ssh2: RSA SHA256:RyyrKoKHvyGTiWIDeMwuNNfmpVLXChNPYxUIZdc99cw Mar 25 01:17:48.966889 sshd-session[1603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:17:48.971002 systemd-logind[1463]: New session 2 of user core. Mar 25 01:17:48.985471 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 25 01:17:49.036495 sshd[1605]: Connection closed by 10.0.0.1 port 56232 Mar 25 01:17:49.036816 sshd-session[1603]: pam_unix(sshd:session): session closed for user core Mar 25 01:17:49.047390 systemd[1]: sshd@1-10.0.0.63:22-10.0.0.1:56232.service: Deactivated successfully. Mar 25 01:17:49.048937 systemd[1]: session-2.scope: Deactivated successfully. Mar 25 01:17:49.050270 systemd-logind[1463]: Session 2 logged out. Waiting for processes to exit. Mar 25 01:17:49.051472 systemd[1]: Started sshd@2-10.0.0.63:22-10.0.0.1:56236.service - OpenSSH per-connection server daemon (10.0.0.1:56236). Mar 25 01:17:49.052570 systemd-logind[1463]: Removed session 2. Mar 25 01:17:49.104157 sshd[1610]: Accepted publickey for core from 10.0.0.1 port 56236 ssh2: RSA SHA256:RyyrKoKHvyGTiWIDeMwuNNfmpVLXChNPYxUIZdc99cw Mar 25 01:17:49.105456 sshd-session[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:17:49.110079 systemd-logind[1463]: New session 3 of user core. Mar 25 01:17:49.118417 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 25 01:17:49.166318 sshd[1613]: Connection closed by 10.0.0.1 port 56236 Mar 25 01:17:49.166083 sshd-session[1610]: pam_unix(sshd:session): session closed for user core Mar 25 01:17:49.181369 systemd[1]: sshd@2-10.0.0.63:22-10.0.0.1:56236.service: Deactivated successfully. Mar 25 01:17:49.182866 systemd[1]: session-3.scope: Deactivated successfully. Mar 25 01:17:49.183558 systemd-logind[1463]: Session 3 logged out. Waiting for processes to exit. Mar 25 01:17:49.185227 systemd[1]: Started sshd@3-10.0.0.63:22-10.0.0.1:56248.service - OpenSSH per-connection server daemon (10.0.0.1:56248). Mar 25 01:17:49.186121 systemd-logind[1463]: Removed session 3. Mar 25 01:17:49.235518 sshd[1618]: Accepted publickey for core from 10.0.0.1 port 56248 ssh2: RSA SHA256:RyyrKoKHvyGTiWIDeMwuNNfmpVLXChNPYxUIZdc99cw Mar 25 01:17:49.236802 sshd-session[1618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:17:49.241298 systemd-logind[1463]: New session 4 of user core. Mar 25 01:17:49.255414 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 25 01:17:49.306437 sshd[1621]: Connection closed by 10.0.0.1 port 56248 Mar 25 01:17:49.306754 sshd-session[1618]: pam_unix(sshd:session): session closed for user core Mar 25 01:17:49.317200 systemd[1]: sshd@3-10.0.0.63:22-10.0.0.1:56248.service: Deactivated successfully. Mar 25 01:17:49.318669 systemd[1]: session-4.scope: Deactivated successfully. Mar 25 01:17:49.319886 systemd-logind[1463]: Session 4 logged out. Waiting for processes to exit. Mar 25 01:17:49.322023 systemd[1]: Started sshd@4-10.0.0.63:22-10.0.0.1:56258.service - OpenSSH per-connection server daemon (10.0.0.1:56258). Mar 25 01:17:49.322870 systemd-logind[1463]: Removed session 4. Mar 25 01:17:49.365502 sshd[1626]: Accepted publickey for core from 10.0.0.1 port 56258 ssh2: RSA SHA256:RyyrKoKHvyGTiWIDeMwuNNfmpVLXChNPYxUIZdc99cw Mar 25 01:17:49.366714 sshd-session[1626]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 25 01:17:49.371039 systemd-logind[1463]: New session 5 of user core. Mar 25 01:17:49.379431 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 25 01:17:49.439290 sudo[1630]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 25 01:17:49.439565 sudo[1630]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 25 01:17:49.450864 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 25 01:17:49.479473 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 25 01:17:49.479681 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 25 01:17:49.870382 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 25 01:17:49.870527 systemd[1]: kubelet.service: Consumed 775ms CPU time, 234.7M memory peak. Mar 25 01:17:49.872415 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 25 01:17:49.890158 systemd[1]: Reload requested from client PID 1671 ('systemctl') (unit session-5.scope)... Mar 25 01:17:49.890174 systemd[1]: Reloading... Mar 25 01:17:49.968281 zram_generator::config[1720]: No configuration found. Mar 25 01:17:50.266108 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. Mar 25 01:17:50.336353 systemd[1]: Reloading finished in 445 ms. Mar 25 01:17:50.391992 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Mar 25 01:17:50.394647 systemd[1]: kubelet.service: Deactivated successfully. Mar 25 01:17:50.394854 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 25 01:17:50.394896 systemd[1]: kubelet.service: Consumed 89ms CPU time, 82.4M memory peak. Mar 25 01:17:50.396474 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 25 01:17:50.501522 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 25 01:17:50.504966 (kubelet)[1762]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Mar 25 01:17:50.544451 kubelet[1762]: 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. Mar 25 01:17:50.544451 kubelet[1762]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Mar 25 01:17:50.544451 kubelet[1762]: 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. Mar 25 01:17:50.544760 kubelet[1762]: I0325 01:17:50.544479 1762 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Mar 25 01:17:51.245380 kubelet[1762]: I0325 01:17:51.245329 1762 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Mar 25 01:17:51.245380 kubelet[1762]: I0325 01:17:51.245365 1762 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 25 01:17:51.246122 kubelet[1762]: I0325 01:17:51.245917 1762 server.go:929] "Client rotation is on, will bootstrap in background" Mar 25 01:17:51.290657 kubelet[1762]: I0325 01:17:51.290614 1762 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 25 01:17:51.298476 kubelet[1762]: I0325 01:17:51.298451 1762 server.go:1426] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Mar 25 01:17:51.302117 kubelet[1762]: I0325 01:17:51.302076 1762 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Mar 25 01:17:51.303264 kubelet[1762]: I0325 01:17:51.302983 1762 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Mar 25 01:17:51.303264 kubelet[1762]: I0325 01:17:51.303133 1762 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 25 01:17:51.303371 kubelet[1762]: I0325 01:17:51.303157 1762 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.63","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} Mar 25 01:17:51.303523 kubelet[1762]: I0325 01:17:51.303493 1762 topology_manager.go:138] "Creating topology manager with none policy" Mar 25 01:17:51.303523 kubelet[1762]: I0325 01:17:51.303510 1762 container_manager_linux.go:300] "Creating device plugin manager" Mar 25 01:17:51.303703 kubelet[1762]: I0325 01:17:51.303684 1762 state_mem.go:36] "Initialized new in-memory state store" Mar 25 01:17:51.306339 kubelet[1762]: I0325 01:17:51.306316 1762 kubelet.go:408] "Attempting to sync node with API server" Mar 25 01:17:51.306385 kubelet[1762]: I0325 01:17:51.306348 1762 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 25 01:17:51.306385 kubelet[1762]: I0325 01:17:51.306379 1762 kubelet.go:314] "Adding apiserver pod source" Mar 25 01:17:51.306478 kubelet[1762]: I0325 01:17:51.306390 1762 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 25 01:17:51.306498 kubelet[1762]: E0325 01:17:51.306474 1762 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:51.306607 kubelet[1762]: E0325 01:17:51.306565 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:51.310711 kubelet[1762]: I0325 01:17:51.310677 1762 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" Mar 25 01:17:51.312329 kubelet[1762]: I0325 01:17:51.312311 1762 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Mar 25 01:17:51.312566 kubelet[1762]: W0325 01:17:51.312544 1762 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 25 01:17:51.313150 kubelet[1762]: W0325 01:17:51.313077 1762 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.63" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 25 01:17:51.313150 kubelet[1762]: E0325 01:17:51.313117 1762 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.63\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Mar 25 01:17:51.313227 kubelet[1762]: I0325 01:17:51.313179 1762 server.go:1269] "Started kubelet" Mar 25 01:17:51.313394 kubelet[1762]: I0325 01:17:51.313368 1762 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Mar 25 01:17:51.314086 kubelet[1762]: W0325 01:17:51.313426 1762 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 25 01:17:51.314086 kubelet[1762]: E0325 01:17:51.313461 1762 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Mar 25 01:17:51.314086 kubelet[1762]: I0325 01:17:51.313952 1762 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 25 01:17:51.315926 kubelet[1762]: I0325 01:17:51.315640 1762 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 25 01:17:51.319016 kubelet[1762]: I0325 01:17:51.318501 1762 server.go:460] "Adding debug handlers to kubelet server" Mar 25 01:17:51.319016 kubelet[1762]: I0325 01:17:51.318650 1762 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Mar 25 01:17:51.321077 kubelet[1762]: E0325 01:17:51.318919 1762 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.63.182fe6e97cea004c default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.63,UID:10.0.0.63,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.63,},FirstTimestamp:2025-03-25 01:17:51.313150028 +0000 UTC m=+0.805215336,LastTimestamp:2025-03-25 01:17:51.313150028 +0000 UTC m=+0.805215336,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.63,}" Mar 25 01:17:51.321077 kubelet[1762]: I0325 01:17:51.320047 1762 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Mar 25 01:17:51.321296 kubelet[1762]: I0325 01:17:51.321276 1762 volume_manager.go:289] "Starting Kubelet Volume Manager" Mar 25 01:17:51.321627 kubelet[1762]: E0325 01:17:51.321600 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.321850 kubelet[1762]: I0325 01:17:51.321828 1762 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Mar 25 01:17:51.321922 kubelet[1762]: I0325 01:17:51.321908 1762 reconciler.go:26] "Reconciler: start to sync state" Mar 25 01:17:51.324627 kubelet[1762]: I0325 01:17:51.324582 1762 factory.go:221] Registration of the systemd container factory successfully Mar 25 01:17:51.324714 kubelet[1762]: I0325 01:17:51.324693 1762 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 Mar 25 01:17:51.325575 kubelet[1762]: E0325 01:17:51.325516 1762 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 25 01:17:51.326112 kubelet[1762]: I0325 01:17:51.326089 1762 factory.go:221] Registration of the containerd container factory successfully Mar 25 01:17:51.335382 kubelet[1762]: E0325 01:17:51.335324 1762 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.63\" not found" node="10.0.0.63" Mar 25 01:17:51.340573 kubelet[1762]: I0325 01:17:51.340546 1762 cpu_manager.go:214] "Starting CPU manager" policy="none" Mar 25 01:17:51.340573 kubelet[1762]: I0325 01:17:51.340566 1762 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Mar 25 01:17:51.340662 kubelet[1762]: I0325 01:17:51.340592 1762 state_mem.go:36] "Initialized new in-memory state store" Mar 25 01:17:51.403322 kubelet[1762]: I0325 01:17:51.403280 1762 policy_none.go:49] "None policy: Start" Mar 25 01:17:51.404530 kubelet[1762]: I0325 01:17:51.404484 1762 memory_manager.go:170] "Starting memorymanager" policy="None" Mar 25 01:17:51.404530 kubelet[1762]: I0325 01:17:51.404513 1762 state_mem.go:35] "Initializing new in-memory state store" Mar 25 01:17:51.410709 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Mar 25 01:17:51.420492 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Mar 25 01:17:51.421684 kubelet[1762]: E0325 01:17:51.421662 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.423297 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Mar 25 01:17:51.428439 kubelet[1762]: I0325 01:17:51.428377 1762 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Mar 25 01:17:51.429534 kubelet[1762]: I0325 01:17:51.429493 1762 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Mar 25 01:17:51.429534 kubelet[1762]: I0325 01:17:51.429525 1762 status_manager.go:217] "Starting to sync pod status with apiserver" Mar 25 01:17:51.429534 kubelet[1762]: I0325 01:17:51.429541 1762 kubelet.go:2321] "Starting kubelet main sync loop" Mar 25 01:17:51.429675 kubelet[1762]: E0325 01:17:51.429587 1762 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Mar 25 01:17:51.431507 kubelet[1762]: I0325 01:17:51.431271 1762 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Mar 25 01:17:51.431507 kubelet[1762]: I0325 01:17:51.431465 1762 eviction_manager.go:189] "Eviction manager: starting control loop" Mar 25 01:17:51.431507 kubelet[1762]: I0325 01:17:51.431476 1762 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 25 01:17:51.432039 kubelet[1762]: I0325 01:17:51.431750 1762 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Mar 25 01:17:51.432847 kubelet[1762]: E0325 01:17:51.432820 1762 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.63\" not found" Mar 25 01:17:51.533229 kubelet[1762]: I0325 01:17:51.533115 1762 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.63" Mar 25 01:17:51.538263 kubelet[1762]: I0325 01:17:51.538182 1762 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.63" Mar 25 01:17:51.538263 kubelet[1762]: E0325 01:17:51.538212 1762 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.63\": node \"10.0.0.63\" not found" Mar 25 01:17:51.543365 kubelet[1762]: I0325 01:17:51.543325 1762 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 25 01:17:51.543753 containerd[1485]: time="2025-03-25T01:17:51.543711839Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 25 01:17:51.544183 kubelet[1762]: I0325 01:17:51.543936 1762 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 25 01:17:51.549944 kubelet[1762]: E0325 01:17:51.549906 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.650710 kubelet[1762]: E0325 01:17:51.650685 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.751002 kubelet[1762]: E0325 01:17:51.750971 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.851951 kubelet[1762]: E0325 01:17:51.851853 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:51.952615 kubelet[1762]: E0325 01:17:51.952576 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.020671 sudo[1630]: pam_unix(sudo:session): session closed for user root Mar 25 01:17:52.021935 sshd[1629]: Connection closed by 10.0.0.1 port 56258 Mar 25 01:17:52.022304 sshd-session[1626]: pam_unix(sshd:session): session closed for user core Mar 25 01:17:52.025722 systemd[1]: sshd@4-10.0.0.63:22-10.0.0.1:56258.service: Deactivated successfully. Mar 25 01:17:52.027675 systemd[1]: session-5.scope: Deactivated successfully. Mar 25 01:17:52.027887 systemd[1]: session-5.scope: Consumed 409ms CPU time, 76M memory peak. Mar 25 01:17:52.028825 systemd-logind[1463]: Session 5 logged out. Waiting for processes to exit. Mar 25 01:17:52.029965 systemd-logind[1463]: Removed session 5. Mar 25 01:17:52.053136 kubelet[1762]: E0325 01:17:52.053083 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.153579 kubelet[1762]: E0325 01:17:52.153492 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.248006 kubelet[1762]: I0325 01:17:52.247924 1762 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 25 01:17:52.248298 kubelet[1762]: W0325 01:17:52.248115 1762 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 25 01:17:52.248298 kubelet[1762]: W0325 01:17:52.248149 1762 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 25 01:17:52.254053 kubelet[1762]: E0325 01:17:52.254027 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.307302 kubelet[1762]: E0325 01:17:52.307263 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:52.355005 kubelet[1762]: E0325 01:17:52.354964 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.455409 kubelet[1762]: E0325 01:17:52.455254 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.555763 kubelet[1762]: E0325 01:17:52.555721 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:52.656198 kubelet[1762]: E0325 01:17:52.656157 1762 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.63\" not found" Mar 25 01:17:53.308048 kubelet[1762]: I0325 01:17:53.308008 1762 apiserver.go:52] "Watching apiserver" Mar 25 01:17:53.308209 kubelet[1762]: E0325 01:17:53.308054 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:53.316797 systemd[1]: Created slice kubepods-besteffort-pod3772b524_5053_452a_b9ce_d185d649c655.slice - libcontainer container kubepods-besteffort-pod3772b524_5053_452a_b9ce_d185d649c655.slice. Mar 25 01:17:53.322436 kubelet[1762]: I0325 01:17:53.322373 1762 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Mar 25 01:17:53.329994 systemd[1]: Created slice kubepods-burstable-pod8a68cc18_ca73_4551_9254_a9f392088ad8.slice - libcontainer container kubepods-burstable-pod8a68cc18_ca73_4551_9254_a9f392088ad8.slice. Mar 25 01:17:53.332859 kubelet[1762]: I0325 01:17:53.331984 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3772b524-5053-452a-b9ce-d185d649c655-xtables-lock\") pod \"kube-proxy-622gt\" (UID: \"3772b524-5053-452a-b9ce-d185d649c655\") " pod="kube-system/kube-proxy-622gt" Mar 25 01:17:53.332859 kubelet[1762]: I0325 01:17:53.332025 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/3772b524-5053-452a-b9ce-d185d649c655-lib-modules\") pod \"kube-proxy-622gt\" (UID: \"3772b524-5053-452a-b9ce-d185d649c655\") " pod="kube-system/kube-proxy-622gt" Mar 25 01:17:53.332859 kubelet[1762]: I0325 01:17:53.332051 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hrccg\" (UniqueName: \"kubernetes.io/projected/3772b524-5053-452a-b9ce-d185d649c655-kube-api-access-hrccg\") pod \"kube-proxy-622gt\" (UID: \"3772b524-5053-452a-b9ce-d185d649c655\") " pod="kube-system/kube-proxy-622gt" Mar 25 01:17:53.332859 kubelet[1762]: I0325 01:17:53.332073 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/8a68cc18-ca73-4551-9254-a9f392088ad8-flannel-cfg\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.332859 kubelet[1762]: I0325 01:17:53.332094 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8a68cc18-ca73-4551-9254-a9f392088ad8-xtables-lock\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.333055 kubelet[1762]: I0325 01:17:53.332114 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/3772b524-5053-452a-b9ce-d185d649c655-kube-proxy\") pod \"kube-proxy-622gt\" (UID: \"3772b524-5053-452a-b9ce-d185d649c655\") " pod="kube-system/kube-proxy-622gt" Mar 25 01:17:53.333055 kubelet[1762]: I0325 01:17:53.332130 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hmrvj\" (UniqueName: \"kubernetes.io/projected/8a68cc18-ca73-4551-9254-a9f392088ad8-kube-api-access-hmrvj\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.333055 kubelet[1762]: I0325 01:17:53.332150 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/8a68cc18-ca73-4551-9254-a9f392088ad8-run\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.333055 kubelet[1762]: I0325 01:17:53.332170 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/8a68cc18-ca73-4551-9254-a9f392088ad8-cni-plugin\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.333055 kubelet[1762]: I0325 01:17:53.332188 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/8a68cc18-ca73-4551-9254-a9f392088ad8-cni\") pod \"kube-flannel-ds-7v6zx\" (UID: \"8a68cc18-ca73-4551-9254-a9f392088ad8\") " pod="kube-flannel/kube-flannel-ds-7v6zx" Mar 25 01:17:53.629278 containerd[1485]: time="2025-03-25T01:17:53.628818808Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-622gt,Uid:3772b524-5053-452a-b9ce-d185d649c655,Namespace:kube-system,Attempt:0,}" Mar 25 01:17:53.660282 containerd[1485]: time="2025-03-25T01:17:53.660206690Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7v6zx,Uid:8a68cc18-ca73-4551-9254-a9f392088ad8,Namespace:kube-flannel,Attempt:0,}" Mar 25 01:17:54.168032 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1787358424.mount: Deactivated successfully. Mar 25 01:17:54.173821 containerd[1485]: time="2025-03-25T01:17:54.173772951Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 25 01:17:54.175820 containerd[1485]: time="2025-03-25T01:17:54.175280078Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 25 01:17:54.176680 containerd[1485]: time="2025-03-25T01:17:54.176632720Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Mar 25 01:17:54.177316 containerd[1485]: time="2025-03-25T01:17:54.177249263Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Mar 25 01:17:54.178045 containerd[1485]: time="2025-03-25T01:17:54.177986999Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 25 01:17:54.181031 containerd[1485]: time="2025-03-25T01:17:54.180999377Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 25 01:17:54.181634 containerd[1485]: time="2025-03-25T01:17:54.181594045Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 546.376788ms" Mar 25 01:17:54.182297 containerd[1485]: time="2025-03-25T01:17:54.182270146Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 517.113384ms" Mar 25 01:17:54.197233 containerd[1485]: time="2025-03-25T01:17:54.197196434Z" level=info msg="connecting to shim 13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0" address="unix:///run/containerd/s/812f6c3e0874c6a622da6b3e6c0c5500593f18b4ca8f7eddae8732342331e022" namespace=k8s.io protocol=ttrpc version=3 Mar 25 01:17:54.199363 containerd[1485]: time="2025-03-25T01:17:54.199323059Z" level=info msg="connecting to shim 170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c" address="unix:///run/containerd/s/d7f955a853c983932097959f2b548c4228c63179f28ed78b676c9cdc37c9e37e" namespace=k8s.io protocol=ttrpc version=3 Mar 25 01:17:54.217422 systemd[1]: Started cri-containerd-13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0.scope - libcontainer container 13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0. Mar 25 01:17:54.220284 systemd[1]: Started cri-containerd-170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c.scope - libcontainer container 170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c. Mar 25 01:17:54.240137 containerd[1485]: time="2025-03-25T01:17:54.240098724Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-622gt,Uid:3772b524-5053-452a-b9ce-d185d649c655,Namespace:kube-system,Attempt:0,} returns sandbox id \"13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0\"" Mar 25 01:17:54.243046 containerd[1485]: time="2025-03-25T01:17:54.242965280Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.7\"" Mar 25 01:17:54.252854 containerd[1485]: time="2025-03-25T01:17:54.252821585Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7v6zx,Uid:8a68cc18-ca73-4551-9254-a9f392088ad8,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\"" Mar 25 01:17:54.308202 kubelet[1762]: E0325 01:17:54.308158 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:55.212933 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3126317641.mount: Deactivated successfully. Mar 25 01:17:55.308482 kubelet[1762]: E0325 01:17:55.308403 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:55.417680 containerd[1485]: time="2025-03-25T01:17:55.417626386Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:55.418588 containerd[1485]: time="2025-03-25T01:17:55.418550258Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.7: active requests=0, bytes read=26871917" Mar 25 01:17:55.419293 containerd[1485]: time="2025-03-25T01:17:55.419223777Z" level=info msg="ImageCreate event name:\"sha256:939054a0dc9c7c1596b061fc2380758139ce62751b44a0b21b3afc7abd7eb3ff\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:55.421010 containerd[1485]: time="2025-03-25T01:17:55.420975413Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:e5839270c96c3ad1bea1dce4935126d3281297527f3655408d2970aa4b5cf178\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:55.422998 containerd[1485]: time="2025-03-25T01:17:55.422322051Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.7\" with image id \"sha256:939054a0dc9c7c1596b061fc2380758139ce62751b44a0b21b3afc7abd7eb3ff\", repo tag \"registry.k8s.io/kube-proxy:v1.31.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:e5839270c96c3ad1bea1dce4935126d3281297527f3655408d2970aa4b5cf178\", size \"26870934\" in 1.179320802s" Mar 25 01:17:55.422998 containerd[1485]: time="2025-03-25T01:17:55.422352317Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.7\" returns image reference \"sha256:939054a0dc9c7c1596b061fc2380758139ce62751b44a0b21b3afc7abd7eb3ff\"" Mar 25 01:17:55.424143 containerd[1485]: time="2025-03-25T01:17:55.424109104Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Mar 25 01:17:55.425275 containerd[1485]: time="2025-03-25T01:17:55.425137270Z" level=info msg="CreateContainer within sandbox \"13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 25 01:17:55.433687 containerd[1485]: time="2025-03-25T01:17:55.433648810Z" level=info msg="Container c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:17:55.436281 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1244540194.mount: Deactivated successfully. Mar 25 01:17:55.440530 containerd[1485]: time="2025-03-25T01:17:55.440484858Z" level=info msg="CreateContainer within sandbox \"13801665220b6a2f2f610ff91b2891d6c3f2bf0259bc5eaf5f41890204ed49f0\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83\"" Mar 25 01:17:55.441288 containerd[1485]: time="2025-03-25T01:17:55.441228012Z" level=info msg="StartContainer for \"c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83\"" Mar 25 01:17:55.442637 containerd[1485]: time="2025-03-25T01:17:55.442602601Z" level=info msg="connecting to shim c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83" address="unix:///run/containerd/s/812f6c3e0874c6a622da6b3e6c0c5500593f18b4ca8f7eddae8732342331e022" protocol=ttrpc version=3 Mar 25 01:17:55.459407 systemd[1]: Started cri-containerd-c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83.scope - libcontainer container c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83. Mar 25 01:17:55.491297 containerd[1485]: time="2025-03-25T01:17:55.491138676Z" level=info msg="StartContainer for \"c35916fd050e35fb3828de142a9a7f5f7eeb7613c9dfcd75ff2e1ba40f9ccd83\" returns successfully" Mar 25 01:17:56.309544 kubelet[1762]: E0325 01:17:56.309484 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:56.449326 kubelet[1762]: I0325 01:17:56.449271 1762 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-622gt" podStartSLOduration=4.267569549 podStartE2EDuration="5.449234974s" podCreationTimestamp="2025-03-25 01:17:51 +0000 UTC" firstStartedPulling="2025-03-25 01:17:54.24191063 +0000 UTC m=+3.733975938" lastFinishedPulling="2025-03-25 01:17:55.423576095 +0000 UTC m=+4.915641363" observedRunningTime="2025-03-25 01:17:56.448914274 +0000 UTC m=+5.940979542" watchObservedRunningTime="2025-03-25 01:17:56.449234974 +0000 UTC m=+5.941300282" Mar 25 01:17:56.680728 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2924559870.mount: Deactivated successfully. Mar 25 01:17:56.704828 containerd[1485]: time="2025-03-25T01:17:56.704772837Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:56.705360 containerd[1485]: time="2025-03-25T01:17:56.705295781Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Mar 25 01:17:56.705945 containerd[1485]: time="2025-03-25T01:17:56.705909624Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:56.708045 containerd[1485]: time="2025-03-25T01:17:56.708014100Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:56.708665 containerd[1485]: time="2025-03-25T01:17:56.708626744Z" 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.284487053s" Mar 25 01:17:56.708696 containerd[1485]: time="2025-03-25T01:17:56.708672393Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Mar 25 01:17:56.710579 containerd[1485]: time="2025-03-25T01:17:56.710542116Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 25 01:17:56.715701 containerd[1485]: time="2025-03-25T01:17:56.715668717Z" level=info msg="Container 421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:17:56.721323 containerd[1485]: time="2025-03-25T01:17:56.721266144Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\"" Mar 25 01:17:56.721713 containerd[1485]: time="2025-03-25T01:17:56.721684851Z" level=info msg="StartContainer for \"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\"" Mar 25 01:17:56.722508 containerd[1485]: time="2025-03-25T01:17:56.722475497Z" level=info msg="connecting to shim 421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3" address="unix:///run/containerd/s/d7f955a853c983932097959f2b548c4228c63179f28ed78b676c9cdc37c9e37e" protocol=ttrpc version=3 Mar 25 01:17:56.745401 systemd[1]: Started cri-containerd-421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3.scope - libcontainer container 421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3. Mar 25 01:17:56.769985 containerd[1485]: time="2025-03-25T01:17:56.769936967Z" level=info msg="StartContainer for \"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\" returns successfully" Mar 25 01:17:56.772096 systemd[1]: cri-containerd-421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3.scope: Deactivated successfully. Mar 25 01:17:56.773451 containerd[1485]: time="2025-03-25T01:17:56.773418854Z" level=info msg="received exit event container_id:\"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\" id:\"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\" pid:2093 exited_at:{seconds:1742865476 nanos:773092603}" Mar 25 01:17:56.773623 containerd[1485]: time="2025-03-25T01:17:56.773597376Z" level=info msg="TaskExit event in podsandbox handler container_id:\"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\" id:\"421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3\" pid:2093 exited_at:{seconds:1742865476 nanos:773092603}" Mar 25 01:17:57.310544 kubelet[1762]: E0325 01:17:57.310493 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:57.444267 containerd[1485]: time="2025-03-25T01:17:57.444210205Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Mar 25 01:17:57.632104 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-421cd51ecf4a0b1db0811612ff9cad5c5dfac48dbdd9e753a12fe70216a976a3-rootfs.mount: Deactivated successfully. Mar 25 01:17:58.311305 kubelet[1762]: E0325 01:17:58.311252 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:58.562412 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount302308900.mount: Deactivated successfully. Mar 25 01:17:59.008185 containerd[1485]: time="2025-03-25T01:17:59.007663480Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:59.008866 containerd[1485]: time="2025-03-25T01:17:59.008813319Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Mar 25 01:17:59.009793 containerd[1485]: time="2025-03-25T01:17:59.009744746Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:59.012633 containerd[1485]: time="2025-03-25T01:17:59.012587416Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:17:59.013555 containerd[1485]: time="2025-03-25T01:17:59.013424062Z" 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.569159328s" Mar 25 01:17:59.013555 containerd[1485]: time="2025-03-25T01:17:59.013456868Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Mar 25 01:17:59.015839 containerd[1485]: time="2025-03-25T01:17:59.015799261Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 25 01:17:59.023517 containerd[1485]: time="2025-03-25T01:17:59.022193221Z" level=info msg="Container 006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:17:59.025230 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3922992612.mount: Deactivated successfully. Mar 25 01:17:59.029461 containerd[1485]: time="2025-03-25T01:17:59.029333242Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\"" Mar 25 01:17:59.029843 containerd[1485]: time="2025-03-25T01:17:59.029820013Z" level=info msg="StartContainer for \"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\"" Mar 25 01:17:59.030766 containerd[1485]: time="2025-03-25T01:17:59.030667089Z" level=info msg="connecting to shim 006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd" address="unix:///run/containerd/s/d7f955a853c983932097959f2b548c4228c63179f28ed78b676c9cdc37c9e37e" protocol=ttrpc version=3 Mar 25 01:17:59.052479 systemd[1]: Started cri-containerd-006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd.scope - libcontainer container 006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd. Mar 25 01:17:59.077574 containerd[1485]: time="2025-03-25T01:17:59.077537523Z" level=info msg="StartContainer for \"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\" returns successfully" Mar 25 01:17:59.082643 systemd[1]: cri-containerd-006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd.scope: Deactivated successfully. Mar 25 01:17:59.083869 containerd[1485]: time="2025-03-25T01:17:59.083628320Z" level=info msg="received exit event container_id:\"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\" id:\"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\" pid:2171 exited_at:{seconds:1742865479 nanos:83159171}" Mar 25 01:17:59.083869 containerd[1485]: time="2025-03-25T01:17:59.083845893Z" level=info msg="TaskExit event in podsandbox handler container_id:\"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\" id:\"006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd\" pid:2171 exited_at:{seconds:1742865479 nanos:83159171}" Mar 25 01:17:59.097727 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-006e578ce3e8139200fef847c18068b062cd633cf9674b592b75950e39137dfd-rootfs.mount: Deactivated successfully. Mar 25 01:17:59.168945 kubelet[1762]: I0325 01:17:59.168714 1762 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Mar 25 01:17:59.312165 kubelet[1762]: E0325 01:17:59.312014 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:17:59.450797 containerd[1485]: time="2025-03-25T01:17:59.450298703Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 25 01:17:59.458954 containerd[1485]: time="2025-03-25T01:17:59.458404196Z" level=info msg="Container 846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:17:59.463701 containerd[1485]: time="2025-03-25T01:17:59.463607000Z" level=info msg="CreateContainer within sandbox \"170d947e3a536fabd0a7a059b999c00be0d55ca94971506df2138317d9fbf10c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae\"" Mar 25 01:17:59.464361 containerd[1485]: time="2025-03-25T01:17:59.464094651Z" level=info msg="StartContainer for \"846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae\"" Mar 25 01:17:59.464852 containerd[1485]: time="2025-03-25T01:17:59.464823769Z" level=info msg="connecting to shim 846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae" address="unix:///run/containerd/s/d7f955a853c983932097959f2b548c4228c63179f28ed78b676c9cdc37c9e37e" protocol=ttrpc version=3 Mar 25 01:17:59.493419 systemd[1]: Started cri-containerd-846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae.scope - libcontainer container 846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae. Mar 25 01:17:59.528262 containerd[1485]: time="2025-03-25T01:17:59.526981554Z" level=info msg="StartContainer for \"846c858d9aee367da6db1bb495a55bea5b06c15352b23a73673e5702477f07ae\" returns successfully" Mar 25 01:18:00.312452 kubelet[1762]: E0325 01:18:00.312383 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:00.464122 kubelet[1762]: I0325 01:18:00.463907 1762 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7v6zx" podStartSLOduration=4.703361774 podStartE2EDuration="9.463890623s" podCreationTimestamp="2025-03-25 01:17:51 +0000 UTC" firstStartedPulling="2025-03-25 01:17:54.2539621 +0000 UTC m=+3.746027407" lastFinishedPulling="2025-03-25 01:17:59.014490948 +0000 UTC m=+8.506556256" observedRunningTime="2025-03-25 01:18:00.461643716 +0000 UTC m=+9.953709024" watchObservedRunningTime="2025-03-25 01:18:00.463890623 +0000 UTC m=+9.955955891" Mar 25 01:18:00.593820 systemd-networkd[1395]: flannel.1: Link UP Mar 25 01:18:00.593828 systemd-networkd[1395]: flannel.1: Gained carrier Mar 25 01:18:01.312554 kubelet[1762]: E0325 01:18:01.312508 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:01.664359 systemd-networkd[1395]: flannel.1: Gained IPv6LL Mar 25 01:18:02.313380 kubelet[1762]: E0325 01:18:02.313333 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:02.923279 systemd[1]: Created slice kubepods-besteffort-pod10add254_53b7_48a7_abf8_36c72ca42f57.slice - libcontainer container kubepods-besteffort-pod10add254_53b7_48a7_abf8_36c72ca42f57.slice. Mar 25 01:18:02.983288 kubelet[1762]: I0325 01:18:02.983228 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mzg9h\" (UniqueName: \"kubernetes.io/projected/10add254-53b7-48a7-abf8-36c72ca42f57-kube-api-access-mzg9h\") pod \"nginx-deployment-8587fbcb89-97qvh\" (UID: \"10add254-53b7-48a7-abf8-36c72ca42f57\") " pod="default/nginx-deployment-8587fbcb89-97qvh" Mar 25 01:18:03.226449 containerd[1485]: time="2025-03-25T01:18:03.226290325Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-97qvh,Uid:10add254-53b7-48a7-abf8-36c72ca42f57,Namespace:default,Attempt:0,}" Mar 25 01:18:03.246148 systemd-networkd[1395]: cni0: Link UP Mar 25 01:18:03.251475 systemd-networkd[1395]: veth0d6f4aac: Link UP Mar 25 01:18:03.253376 kernel: cni0: port 1(veth0d6f4aac) entered blocking state Mar 25 01:18:03.253453 kernel: cni0: port 1(veth0d6f4aac) entered disabled state Mar 25 01:18:03.253471 kernel: veth0d6f4aac: entered allmulticast mode Mar 25 01:18:03.255427 kernel: veth0d6f4aac: entered promiscuous mode Mar 25 01:18:03.255478 kernel: cni0: port 1(veth0d6f4aac) entered blocking state Mar 25 01:18:03.255493 kernel: cni0: port 1(veth0d6f4aac) entered forwarding state Mar 25 01:18:03.261477 kernel: cni0: port 1(veth0d6f4aac) entered disabled state Mar 25 01:18:03.265552 kernel: cni0: port 1(veth0d6f4aac) entered blocking state Mar 25 01:18:03.265756 kernel: cni0: port 1(veth0d6f4aac) entered forwarding state Mar 25 01:18:03.265833 systemd-networkd[1395]: veth0d6f4aac: Gained carrier Mar 25 01:18:03.266327 systemd-networkd[1395]: cni0: Gained carrier Mar 25 01:18:03.267838 containerd[1485]: 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"} Mar 25 01:18:03.267838 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Mar 25 01:18:03.314104 kubelet[1762]: E0325 01:18:03.314033 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:03.366724 containerd[1485]: {"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-03-25T01:18:03.366682389Z" level=info msg="connecting to shim fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6" address="unix:///run/containerd/s/6c7443efcea5087a99d17322c70487f92be072239669902f012d8a7892652263" namespace=k8s.io protocol=ttrpc version=3 Mar 25 01:18:03.387396 systemd[1]: Started cri-containerd-fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6.scope - libcontainer container fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6. Mar 25 01:18:03.398011 systemd-resolved[1321]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 25 01:18:03.418147 containerd[1485]: time="2025-03-25T01:18:03.418111389Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-97qvh,Uid:10add254-53b7-48a7-abf8-36c72ca42f57,Namespace:default,Attempt:0,} returns sandbox id \"fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6\"" Mar 25 01:18:03.419526 containerd[1485]: time="2025-03-25T01:18:03.419488066Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 25 01:18:04.314392 kubelet[1762]: E0325 01:18:04.314314 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:04.416430 systemd-networkd[1395]: cni0: Gained IPv6LL Mar 25 01:18:04.544352 systemd-networkd[1395]: veth0d6f4aac: Gained IPv6LL Mar 25 01:18:04.983185 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2749307645.mount: Deactivated successfully. Mar 25 01:18:05.314899 kubelet[1762]: E0325 01:18:05.314683 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:05.747102 containerd[1485]: time="2025-03-25T01:18:05.747060039Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:05.748001 containerd[1485]: time="2025-03-25T01:18:05.747813885Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69699071" Mar 25 01:18:05.750070 containerd[1485]: time="2025-03-25T01:18:05.748966825Z" level=info msg="ImageCreate event name:\"sha256:0316958ebc50aae02a8927dacfce6bed17eea282caf1d12360dcf0297d73ba73\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:05.751257 containerd[1485]: time="2025-03-25T01:18:05.751211973Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d3c64994a7c9fa729aa3fff7308f5885794a8ca5b40a66c003d782ae56e17301\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:05.752396 containerd[1485]: time="2025-03-25T01:18:05.752358356Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:0316958ebc50aae02a8927dacfce6bed17eea282caf1d12360dcf0297d73ba73\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d3c64994a7c9fa729aa3fff7308f5885794a8ca5b40a66c003d782ae56e17301\", size \"69698949\" in 2.33283535s" Mar 25 01:18:05.752495 containerd[1485]: time="2025-03-25T01:18:05.752478460Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:0316958ebc50aae02a8927dacfce6bed17eea282caf1d12360dcf0297d73ba73\"" Mar 25 01:18:05.754554 containerd[1485]: time="2025-03-25T01:18:05.754530818Z" level=info msg="CreateContainer within sandbox \"fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 25 01:18:05.761911 containerd[1485]: time="2025-03-25T01:18:05.760359487Z" level=info msg="Container a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:18:05.764945 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount508713586.mount: Deactivated successfully. Mar 25 01:18:05.767762 containerd[1485]: time="2025-03-25T01:18:05.767716120Z" level=info msg="CreateContainer within sandbox \"fd3e7cbe18e433e9c27596de60cc440a6adf21d670021893a93b8dddb4026ec6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70\"" Mar 25 01:18:05.769581 containerd[1485]: time="2025-03-25T01:18:05.768458372Z" level=info msg="StartContainer for \"a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70\"" Mar 25 01:18:05.769581 containerd[1485]: time="2025-03-25T01:18:05.769175276Z" level=info msg="connecting to shim a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70" address="unix:///run/containerd/s/6c7443efcea5087a99d17322c70487f92be072239669902f012d8a7892652263" protocol=ttrpc version=3 Mar 25 01:18:05.791423 systemd[1]: Started cri-containerd-a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70.scope - libcontainer container a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70. Mar 25 01:18:05.816781 containerd[1485]: time="2025-03-25T01:18:05.816650590Z" level=info msg="StartContainer for \"a94b4118bc2c71974f3642570c0d9a0a0dc37cf42b7bcbebfc8c7151f19a0e70\" returns successfully" Mar 25 01:18:06.315558 kubelet[1762]: E0325 01:18:06.315499 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:07.316478 kubelet[1762]: E0325 01:18:07.316440 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:08.317090 kubelet[1762]: E0325 01:18:08.317049 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:09.317310 kubelet[1762]: E0325 01:18:09.317266 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:09.980608 kubelet[1762]: I0325 01:18:09.980549 1762 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-97qvh" podStartSLOduration=5.646347544 podStartE2EDuration="7.980531671s" podCreationTimestamp="2025-03-25 01:18:02 +0000 UTC" firstStartedPulling="2025-03-25 01:18:03.419296463 +0000 UTC m=+12.911361771" lastFinishedPulling="2025-03-25 01:18:05.75348059 +0000 UTC m=+15.245545898" observedRunningTime="2025-03-25 01:18:06.471183209 +0000 UTC m=+15.963248517" watchObservedRunningTime="2025-03-25 01:18:09.980531671 +0000 UTC m=+19.472596979" Mar 25 01:18:09.986431 systemd[1]: Created slice kubepods-besteffort-podc4b03338_49c0_430a_9272_fbaeac8636f4.slice - libcontainer container kubepods-besteffort-podc4b03338_49c0_430a_9272_fbaeac8636f4.slice. Mar 25 01:18:10.021609 kubelet[1762]: I0325 01:18:10.021522 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wg6jt\" (UniqueName: \"kubernetes.io/projected/c4b03338-49c0-430a-9272-fbaeac8636f4-kube-api-access-wg6jt\") pod \"nfs-server-provisioner-0\" (UID: \"c4b03338-49c0-430a-9272-fbaeac8636f4\") " pod="default/nfs-server-provisioner-0" Mar 25 01:18:10.021609 kubelet[1762]: I0325 01:18:10.021566 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/c4b03338-49c0-430a-9272-fbaeac8636f4-data\") pod \"nfs-server-provisioner-0\" (UID: \"c4b03338-49c0-430a-9272-fbaeac8636f4\") " pod="default/nfs-server-provisioner-0" Mar 25 01:18:10.289332 containerd[1485]: time="2025-03-25T01:18:10.289124410Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c4b03338-49c0-430a-9272-fbaeac8636f4,Namespace:default,Attempt:0,}" Mar 25 01:18:10.304522 systemd-networkd[1395]: veth9d8e1402: Link UP Mar 25 01:18:10.306646 kernel: cni0: port 2(veth9d8e1402) entered blocking state Mar 25 01:18:10.306730 kernel: cni0: port 2(veth9d8e1402) entered disabled state Mar 25 01:18:10.306767 kernel: veth9d8e1402: entered allmulticast mode Mar 25 01:18:10.306894 kernel: veth9d8e1402: entered promiscuous mode Mar 25 01:18:10.313459 kernel: cni0: port 2(veth9d8e1402) entered blocking state Mar 25 01:18:10.313528 kernel: cni0: port 2(veth9d8e1402) entered forwarding state Mar 25 01:18:10.313258 systemd-networkd[1395]: veth9d8e1402: Gained carrier Mar 25 01:18:10.314773 containerd[1485]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Mar 25 01:18:10.314773 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Mar 25 01:18:10.317609 kubelet[1762]: E0325 01:18:10.317583 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:10.336828 containerd[1485]: {"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-03-25T01:18:10.336361539Z" level=info msg="connecting to shim ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a" address="unix:///run/containerd/s/f87558f4bc2898d9816a225a79a3ee6d7530b469e23cb6639e79aeed33816e3b" namespace=k8s.io protocol=ttrpc version=3 Mar 25 01:18:10.364401 systemd[1]: Started cri-containerd-ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a.scope - libcontainer container ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a. Mar 25 01:18:10.374075 systemd-resolved[1321]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 25 01:18:10.391950 containerd[1485]: time="2025-03-25T01:18:10.391902593Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c4b03338-49c0-430a-9272-fbaeac8636f4,Namespace:default,Attempt:0,} returns sandbox id \"ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a\"" Mar 25 01:18:10.393953 containerd[1485]: time="2025-03-25T01:18:10.393511167Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 25 01:18:11.307033 kubelet[1762]: E0325 01:18:11.306987 1762 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:11.318484 kubelet[1762]: E0325 01:18:11.318448 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:11.392531 systemd-networkd[1395]: veth9d8e1402: Gained IPv6LL Mar 25 01:18:12.318931 kubelet[1762]: E0325 01:18:12.318882 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:12.746355 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount422890651.mount: Deactivated successfully. Mar 25 01:18:13.319707 kubelet[1762]: E0325 01:18:13.319669 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:14.070796 containerd[1485]: time="2025-03-25T01:18:14.070744640Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:14.071218 containerd[1485]: time="2025-03-25T01:18:14.071168662Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Mar 25 01:18:14.072194 containerd[1485]: time="2025-03-25T01:18:14.072143100Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:14.075263 containerd[1485]: time="2025-03-25T01:18:14.075013656Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:14.076571 containerd[1485]: time="2025-03-25T01:18:14.076447794Z" 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.682902873s" Mar 25 01:18:14.076571 containerd[1485]: time="2025-03-25T01:18:14.076482192Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Mar 25 01:18:14.078293 containerd[1485]: time="2025-03-25T01:18:14.078267475Z" level=info msg="CreateContainer within sandbox \"ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 25 01:18:14.084869 containerd[1485]: time="2025-03-25T01:18:14.083295578Z" level=info msg="Container a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:18:14.089428 containerd[1485]: time="2025-03-25T01:18:14.089397714Z" level=info msg="CreateContainer within sandbox \"ee663564842546727583a603085644b0c6d7488385b899a8993f589d349a4c1a\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff\"" Mar 25 01:18:14.089823 containerd[1485]: time="2025-03-25T01:18:14.089790217Z" level=info msg="StartContainer for \"a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff\"" Mar 25 01:18:14.091032 containerd[1485]: time="2025-03-25T01:18:14.090986926Z" level=info msg="connecting to shim a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff" address="unix:///run/containerd/s/f87558f4bc2898d9816a225a79a3ee6d7530b469e23cb6639e79aeed33816e3b" protocol=ttrpc version=3 Mar 25 01:18:14.113416 systemd[1]: Started cri-containerd-a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff.scope - libcontainer container a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff. Mar 25 01:18:14.136727 containerd[1485]: time="2025-03-25T01:18:14.136676513Z" level=info msg="StartContainer for \"a3fdefbf2c265c6073e0c22b21e189c5e473739da568dc16593eb7b82aae67ff\" returns successfully" Mar 25 01:18:14.320916 kubelet[1762]: E0325 01:18:14.320792 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:15.321548 kubelet[1762]: E0325 01:18:15.321496 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:16.321944 kubelet[1762]: E0325 01:18:16.321888 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:17.322633 kubelet[1762]: E0325 01:18:17.322592 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:18.322813 kubelet[1762]: E0325 01:18:18.322757 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:19.323540 kubelet[1762]: E0325 01:18:19.323486 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:20.324604 kubelet[1762]: E0325 01:18:20.324558 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:21.325055 kubelet[1762]: E0325 01:18:21.325029 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:22.325909 kubelet[1762]: E0325 01:18:22.325854 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:23.326047 kubelet[1762]: E0325 01:18:23.325992 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:24.258092 kubelet[1762]: I0325 01:18:24.258014 1762 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.574216863 podStartE2EDuration="15.257995652s" podCreationTimestamp="2025-03-25 01:18:09 +0000 UTC" firstStartedPulling="2025-03-25 01:18:10.393300657 +0000 UTC m=+19.885365965" lastFinishedPulling="2025-03-25 01:18:14.077079446 +0000 UTC m=+23.569144754" observedRunningTime="2025-03-25 01:18:14.500362287 +0000 UTC m=+23.992427595" watchObservedRunningTime="2025-03-25 01:18:24.257995652 +0000 UTC m=+33.750060960" Mar 25 01:18:24.264639 systemd[1]: Created slice kubepods-besteffort-podecb15e79_0889_4859_8fe3_4b2b8fc5b677.slice - libcontainer container kubepods-besteffort-podecb15e79_0889_4859_8fe3_4b2b8fc5b677.slice. Mar 25 01:18:24.301811 kubelet[1762]: I0325 01:18:24.301774 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7jhzx\" (UniqueName: \"kubernetes.io/projected/ecb15e79-0889-4859-8fe3-4b2b8fc5b677-kube-api-access-7jhzx\") pod \"test-pod-1\" (UID: \"ecb15e79-0889-4859-8fe3-4b2b8fc5b677\") " pod="default/test-pod-1" Mar 25 01:18:24.301811 kubelet[1762]: I0325 01:18:24.301816 1762 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-aa6ed4c6-8780-4178-977d-9dcb03b18922\" (UniqueName: \"kubernetes.io/nfs/ecb15e79-0889-4859-8fe3-4b2b8fc5b677-pvc-aa6ed4c6-8780-4178-977d-9dcb03b18922\") pod \"test-pod-1\" (UID: \"ecb15e79-0889-4859-8fe3-4b2b8fc5b677\") " pod="default/test-pod-1" Mar 25 01:18:24.326965 kubelet[1762]: E0325 01:18:24.326915 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:24.419269 kernel: FS-Cache: Loaded Mar 25 01:18:24.442469 kernel: RPC: Registered named UNIX socket transport module. Mar 25 01:18:24.442575 kernel: RPC: Registered udp transport module. Mar 25 01:18:24.442609 kernel: RPC: Registered tcp transport module. Mar 25 01:18:24.443794 kernel: RPC: Registered tcp-with-tls transport module. Mar 25 01:18:24.443824 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 25 01:18:24.602378 kernel: NFS: Registering the id_resolver key type Mar 25 01:18:24.602494 kernel: Key type id_resolver registered Mar 25 01:18:24.602528 kernel: Key type id_legacy registered Mar 25 01:18:24.622289 nfsidmap[2761]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 25 01:18:24.623736 nfsidmap[2762]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 25 01:18:24.869406 containerd[1485]: time="2025-03-25T01:18:24.869357891Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ecb15e79-0889-4859-8fe3-4b2b8fc5b677,Namespace:default,Attempt:0,}" Mar 25 01:18:24.879090 kernel: cni0: port 3(veth3675006c) entered blocking state Mar 25 01:18:24.879163 kernel: cni0: port 3(veth3675006c) entered disabled state Mar 25 01:18:24.879182 kernel: veth3675006c: entered allmulticast mode Mar 25 01:18:24.879197 kernel: veth3675006c: entered promiscuous mode Mar 25 01:18:24.878663 systemd-networkd[1395]: veth3675006c: Link UP Mar 25 01:18:24.882411 kernel: cni0: port 3(veth3675006c) entered blocking state Mar 25 01:18:24.883097 kernel: cni0: port 3(veth3675006c) entered forwarding state Mar 25 01:18:24.882538 systemd-networkd[1395]: veth3675006c: Gained carrier Mar 25 01:18:24.885658 containerd[1485]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001148e8), "name":"cbr0", "type":"bridge"} Mar 25 01:18:24.885658 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Mar 25 01:18:24.903966 containerd[1485]: {"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-03-25T01:18:24.903925589Z" level=info msg="connecting to shim d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7" address="unix:///run/containerd/s/e9985ae01f0ea8be27b8ce5135879607a0c95d3c7559863d661a13279773d877" namespace=k8s.io protocol=ttrpc version=3 Mar 25 01:18:24.925413 systemd[1]: Started cri-containerd-d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7.scope - libcontainer container d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7. Mar 25 01:18:24.935477 systemd-resolved[1321]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 25 01:18:24.952578 containerd[1485]: time="2025-03-25T01:18:24.952484738Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ecb15e79-0889-4859-8fe3-4b2b8fc5b677,Namespace:default,Attempt:0,} returns sandbox id \"d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7\"" Mar 25 01:18:24.954037 containerd[1485]: time="2025-03-25T01:18:24.953622910Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 25 01:18:25.166528 containerd[1485]: time="2025-03-25T01:18:25.165669385Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 25 01:18:25.167302 containerd[1485]: time="2025-03-25T01:18:25.167262907Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Mar 25 01:18:25.169908 containerd[1485]: time="2025-03-25T01:18:25.169880045Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:0316958ebc50aae02a8927dacfce6bed17eea282caf1d12360dcf0297d73ba73\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d3c64994a7c9fa729aa3fff7308f5885794a8ca5b40a66c003d782ae56e17301\", size \"69698949\" in 216.225016ms" Mar 25 01:18:25.170017 containerd[1485]: time="2025-03-25T01:18:25.170002522Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:0316958ebc50aae02a8927dacfce6bed17eea282caf1d12360dcf0297d73ba73\"" Mar 25 01:18:25.171880 containerd[1485]: time="2025-03-25T01:18:25.171849638Z" level=info msg="CreateContainer within sandbox \"d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 25 01:18:25.177793 containerd[1485]: time="2025-03-25T01:18:25.177754379Z" level=info msg="Container 07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc: CDI devices from CRI Config.CDIDevices: []" Mar 25 01:18:25.183176 containerd[1485]: time="2025-03-25T01:18:25.183121771Z" level=info msg="CreateContainer within sandbox \"d2e66cb4f2f6a6bebe428f225568ea02b098ee80ebe306ed2b42eaebf16815b7\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc\"" Mar 25 01:18:25.183602 containerd[1485]: time="2025-03-25T01:18:25.183577281Z" level=info msg="StartContainer for \"07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc\"" Mar 25 01:18:25.184520 containerd[1485]: time="2025-03-25T01:18:25.184482899Z" level=info msg="connecting to shim 07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc" address="unix:///run/containerd/s/e9985ae01f0ea8be27b8ce5135879607a0c95d3c7559863d661a13279773d877" protocol=ttrpc version=3 Mar 25 01:18:25.200397 systemd[1]: Started cri-containerd-07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc.scope - libcontainer container 07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc. Mar 25 01:18:25.227293 containerd[1485]: time="2025-03-25T01:18:25.227255407Z" level=info msg="StartContainer for \"07057b5fb4bfa4b5cab1f9d5db0af959c99dfa7e74c083b50269984de81dcddc\" returns successfully" Mar 25 01:18:25.327930 kubelet[1762]: E0325 01:18:25.327881 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:25.517904 kubelet[1762]: I0325 01:18:25.517761 1762 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.300453219 podStartE2EDuration="15.51774549s" podCreationTimestamp="2025-03-25 01:18:10 +0000 UTC" firstStartedPulling="2025-03-25 01:18:24.953435874 +0000 UTC m=+34.445501142" lastFinishedPulling="2025-03-25 01:18:25.170728105 +0000 UTC m=+34.662793413" observedRunningTime="2025-03-25 01:18:25.517507176 +0000 UTC m=+35.009572444" watchObservedRunningTime="2025-03-25 01:18:25.51774549 +0000 UTC m=+35.009810758" Mar 25 01:18:26.304415 systemd-networkd[1395]: veth3675006c: Gained IPv6LL Mar 25 01:18:26.329053 kubelet[1762]: E0325 01:18:26.329015 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 25 01:18:27.139293 update_engine[1467]: I20250325 01:18:27.139201 1467 update_attempter.cc:509] Updating boot flags... Mar 25 01:18:27.160274 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2793) Mar 25 01:18:27.184404 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2793) Mar 25 01:18:27.329520 kubelet[1762]: E0325 01:18:27.329462 1762 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"