Dec 13 01:36:24.906332 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Dec 13 01:36:24.906352 kernel: Linux version 6.6.65-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Thu Dec 12 23:24:21 -00 2024 Dec 13 01:36:24.906362 kernel: KASLR enabled Dec 13 01:36:24.906368 kernel: efi: EFI v2.7 by EDK II Dec 13 01:36:24.906373 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Dec 13 01:36:24.906379 kernel: random: crng init done Dec 13 01:36:24.906386 kernel: ACPI: Early table checksum verification disabled Dec 13 01:36:24.906392 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Dec 13 01:36:24.906398 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Dec 13 01:36:24.906406 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906412 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906418 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906424 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906430 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906437 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906444 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906451 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906457 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:36:24.906463 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Dec 13 01:36:24.906469 kernel: NUMA: Failed to initialise from firmware Dec 13 01:36:24.906476 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:36:24.906482 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Dec 13 01:36:24.906488 kernel: Zone ranges: Dec 13 01:36:24.906494 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:36:24.906500 kernel: DMA32 empty Dec 13 01:36:24.906508 kernel: Normal empty Dec 13 01:36:24.906514 kernel: Movable zone start for each node Dec 13 01:36:24.906520 kernel: Early memory node ranges Dec 13 01:36:24.906527 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Dec 13 01:36:24.906533 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Dec 13 01:36:24.906539 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Dec 13 01:36:24.906545 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Dec 13 01:36:24.906552 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Dec 13 01:36:24.906558 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Dec 13 01:36:24.906564 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Dec 13 01:36:24.906570 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:36:24.906576 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Dec 13 01:36:24.906584 kernel: psci: probing for conduit method from ACPI. Dec 13 01:36:24.906590 kernel: psci: PSCIv1.1 detected in firmware. Dec 13 01:36:24.906596 kernel: psci: Using standard PSCI v0.2 function IDs Dec 13 01:36:24.906605 kernel: psci: Trusted OS migration not required Dec 13 01:36:24.906618 kernel: psci: SMC Calling Convention v1.1 Dec 13 01:36:24.906627 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Dec 13 01:36:24.906636 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Dec 13 01:36:24.906643 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Dec 13 01:36:24.906649 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 13 01:36:24.906656 kernel: Detected PIPT I-cache on CPU0 Dec 13 01:36:24.906663 kernel: CPU features: detected: GIC system register CPU interface Dec 13 01:36:24.906669 kernel: CPU features: detected: Hardware dirty bit management Dec 13 01:36:24.906676 kernel: CPU features: detected: Spectre-v4 Dec 13 01:36:24.906683 kernel: CPU features: detected: Spectre-BHB Dec 13 01:36:24.906689 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 13 01:36:24.906696 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 13 01:36:24.906704 kernel: CPU features: detected: ARM erratum 1418040 Dec 13 01:36:24.906711 kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 13 01:36:24.906717 kernel: alternatives: applying boot alternatives Dec 13 01:36:24.906725 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=9494f75a68cfbdce95d0d2f9b58d6d75bc38ee5b4e31dfc2a6da695ffafefba6 Dec 13 01:36:24.906732 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Dec 13 01:36:24.906739 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 01:36:24.906745 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 01:36:24.906752 kernel: Fallback order for Node 0: 0 Dec 13 01:36:24.906759 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Dec 13 01:36:24.906765 kernel: Policy zone: DMA Dec 13 01:36:24.906772 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 01:36:24.906780 kernel: software IO TLB: area num 4. Dec 13 01:36:24.906786 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Dec 13 01:36:24.906793 kernel: Memory: 2386532K/2572288K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39360K init, 897K bss, 185756K reserved, 0K cma-reserved) Dec 13 01:36:24.906801 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 13 01:36:24.906807 kernel: trace event string verifier disabled Dec 13 01:36:24.906814 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 01:36:24.906821 kernel: rcu: RCU event tracing is enabled. Dec 13 01:36:24.906828 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Dec 13 01:36:24.906835 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 01:36:24.906841 kernel: Tracing variant of Tasks RCU enabled. Dec 13 01:36:24.906848 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 01:36:24.906855 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 13 01:36:24.906862 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 13 01:36:24.906869 kernel: GICv3: 256 SPIs implemented Dec 13 01:36:24.906876 kernel: GICv3: 0 Extended SPIs implemented Dec 13 01:36:24.906882 kernel: Root IRQ handler: gic_handle_irq Dec 13 01:36:24.906889 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Dec 13 01:36:24.906896 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Dec 13 01:36:24.906902 kernel: ITS [mem 0x08080000-0x0809ffff] Dec 13 01:36:24.906909 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Dec 13 01:36:24.906916 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Dec 13 01:36:24.906922 kernel: GICv3: using LPI property table @0x00000000400f0000 Dec 13 01:36:24.906929 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Dec 13 01:36:24.906937 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 13 01:36:24.906944 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:36:24.906950 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Dec 13 01:36:24.906957 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Dec 13 01:36:24.906964 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Dec 13 01:36:24.906971 kernel: arm-pv: using stolen time PV Dec 13 01:36:24.906978 kernel: Console: colour dummy device 80x25 Dec 13 01:36:24.906984 kernel: ACPI: Core revision 20230628 Dec 13 01:36:24.906991 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Dec 13 01:36:24.906998 kernel: pid_max: default: 32768 minimum: 301 Dec 13 01:36:24.907006 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Dec 13 01:36:24.907013 kernel: landlock: Up and running. Dec 13 01:36:24.907020 kernel: SELinux: Initializing. Dec 13 01:36:24.907027 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:36:24.907034 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:36:24.907041 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 13 01:36:24.907048 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Dec 13 01:36:24.907054 kernel: rcu: Hierarchical SRCU implementation. Dec 13 01:36:24.907061 kernel: rcu: Max phase no-delay instances is 400. Dec 13 01:36:24.907069 kernel: Platform MSI: ITS@0x8080000 domain created Dec 13 01:36:24.907076 kernel: PCI/MSI: ITS@0x8080000 domain created Dec 13 01:36:24.907083 kernel: Remapping and enabling EFI services. Dec 13 01:36:24.907089 kernel: smp: Bringing up secondary CPUs ... Dec 13 01:36:24.907096 kernel: Detected PIPT I-cache on CPU1 Dec 13 01:36:24.907103 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Dec 13 01:36:24.907110 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Dec 13 01:36:24.907117 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:36:24.907124 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Dec 13 01:36:24.907130 kernel: Detected PIPT I-cache on CPU2 Dec 13 01:36:24.907138 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Dec 13 01:36:24.907146 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Dec 13 01:36:24.907157 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:36:24.907165 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Dec 13 01:36:24.907172 kernel: Detected PIPT I-cache on CPU3 Dec 13 01:36:24.907179 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Dec 13 01:36:24.907186 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Dec 13 01:36:24.907194 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:36:24.907201 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Dec 13 01:36:24.907232 kernel: smp: Brought up 1 node, 4 CPUs Dec 13 01:36:24.907243 kernel: SMP: Total of 4 processors activated. Dec 13 01:36:24.907251 kernel: CPU features: detected: 32-bit EL0 Support Dec 13 01:36:24.907258 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 13 01:36:24.907266 kernel: CPU features: detected: Common not Private translations Dec 13 01:36:24.907273 kernel: CPU features: detected: CRC32 instructions Dec 13 01:36:24.907280 kernel: CPU features: detected: Enhanced Virtualization Traps Dec 13 01:36:24.907287 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 13 01:36:24.907297 kernel: CPU features: detected: LSE atomic instructions Dec 13 01:36:24.907305 kernel: CPU features: detected: Privileged Access Never Dec 13 01:36:24.907312 kernel: CPU features: detected: RAS Extension Support Dec 13 01:36:24.907319 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 13 01:36:24.907326 kernel: CPU: All CPU(s) started at EL1 Dec 13 01:36:24.907333 kernel: alternatives: applying system-wide alternatives Dec 13 01:36:24.907340 kernel: devtmpfs: initialized Dec 13 01:36:24.907348 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 01:36:24.907355 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 13 01:36:24.907363 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 01:36:24.907371 kernel: SMBIOS 3.0.0 present. Dec 13 01:36:24.907378 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Dec 13 01:36:24.907385 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 13 01:36:24.907392 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 13 01:36:24.907400 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 13 01:36:24.907407 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 13 01:36:24.907414 kernel: audit: initializing netlink subsys (disabled) Dec 13 01:36:24.907421 kernel: audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 Dec 13 01:36:24.907430 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 01:36:24.907437 kernel: cpuidle: using governor menu Dec 13 01:36:24.907444 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 13 01:36:24.907451 kernel: ASID allocator initialised with 32768 entries Dec 13 01:36:24.907458 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 01:36:24.907465 kernel: Serial: AMBA PL011 UART driver Dec 13 01:36:24.907473 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Dec 13 01:36:24.907480 kernel: Modules: 0 pages in range for non-PLT usage Dec 13 01:36:24.907487 kernel: Modules: 509040 pages in range for PLT usage Dec 13 01:36:24.907495 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 01:36:24.907503 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Dec 13 01:36:24.907510 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Dec 13 01:36:24.907517 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Dec 13 01:36:24.907524 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 01:36:24.907531 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Dec 13 01:36:24.907539 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Dec 13 01:36:24.907546 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Dec 13 01:36:24.907553 kernel: ACPI: Added _OSI(Module Device) Dec 13 01:36:24.907561 kernel: ACPI: Added _OSI(Processor Device) Dec 13 01:36:24.907568 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 01:36:24.907575 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 01:36:24.907582 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 01:36:24.907590 kernel: ACPI: Interpreter enabled Dec 13 01:36:24.907597 kernel: ACPI: Using GIC for interrupt routing Dec 13 01:36:24.907604 kernel: ACPI: MCFG table detected, 1 entries Dec 13 01:36:24.907615 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Dec 13 01:36:24.907625 kernel: printk: console [ttyAMA0] enabled Dec 13 01:36:24.907632 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Dec 13 01:36:24.907757 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 01:36:24.907832 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Dec 13 01:36:24.907896 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Dec 13 01:36:24.907960 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Dec 13 01:36:24.908022 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Dec 13 01:36:24.908031 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Dec 13 01:36:24.908041 kernel: PCI host bridge to bus 0000:00 Dec 13 01:36:24.908110 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Dec 13 01:36:24.908169 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Dec 13 01:36:24.908304 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Dec 13 01:36:24.908368 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 13 01:36:24.908449 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Dec 13 01:36:24.908524 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Dec 13 01:36:24.908595 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Dec 13 01:36:24.908674 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Dec 13 01:36:24.908740 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 01:36:24.908806 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 01:36:24.908870 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Dec 13 01:36:24.908936 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Dec 13 01:36:24.908995 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Dec 13 01:36:24.909056 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Dec 13 01:36:24.909114 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Dec 13 01:36:24.909124 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Dec 13 01:36:24.909131 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Dec 13 01:36:24.909139 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Dec 13 01:36:24.909146 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Dec 13 01:36:24.909153 kernel: iommu: Default domain type: Translated Dec 13 01:36:24.909160 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 13 01:36:24.909169 kernel: efivars: Registered efivars operations Dec 13 01:36:24.909177 kernel: vgaarb: loaded Dec 13 01:36:24.909184 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 13 01:36:24.909191 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 01:36:24.909199 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 01:36:24.909206 kernel: pnp: PnP ACPI init Dec 13 01:36:24.909300 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Dec 13 01:36:24.909311 kernel: pnp: PnP ACPI: found 1 devices Dec 13 01:36:24.909321 kernel: NET: Registered PF_INET protocol family Dec 13 01:36:24.909328 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 01:36:24.909336 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 01:36:24.909343 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 13 01:36:24.909350 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 01:36:24.909358 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Dec 13 01:36:24.909365 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 01:36:24.909372 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:36:24.909379 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:36:24.909388 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 13 01:36:24.909395 kernel: PCI: CLS 0 bytes, default 64 Dec 13 01:36:24.909402 kernel: kvm [1]: HYP mode not available Dec 13 01:36:24.909409 kernel: Initialise system trusted keyrings Dec 13 01:36:24.909417 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 01:36:24.909424 kernel: Key type asymmetric registered Dec 13 01:36:24.909431 kernel: Asymmetric key parser 'x509' registered Dec 13 01:36:24.909438 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Dec 13 01:36:24.909445 kernel: io scheduler mq-deadline registered Dec 13 01:36:24.909454 kernel: io scheduler kyber registered Dec 13 01:36:24.909461 kernel: io scheduler bfq registered Dec 13 01:36:24.909468 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Dec 13 01:36:24.909476 kernel: ACPI: button: Power Button [PWRB] Dec 13 01:36:24.909483 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Dec 13 01:36:24.909551 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Dec 13 01:36:24.909562 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 01:36:24.909570 kernel: thunder_xcv, ver 1.0 Dec 13 01:36:24.909577 kernel: thunder_bgx, ver 1.0 Dec 13 01:36:24.909586 kernel: nicpf, ver 1.0 Dec 13 01:36:24.909593 kernel: nicvf, ver 1.0 Dec 13 01:36:24.909678 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 13 01:36:24.909742 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-12-13T01:36:24 UTC (1734053784) Dec 13 01:36:24.909752 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 13 01:36:24.909759 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Dec 13 01:36:24.909767 kernel: watchdog: Delayed init of the lockup detector failed: -19 Dec 13 01:36:24.909774 kernel: watchdog: Hard watchdog permanently disabled Dec 13 01:36:24.909783 kernel: NET: Registered PF_INET6 protocol family Dec 13 01:36:24.909791 kernel: Segment Routing with IPv6 Dec 13 01:36:24.909798 kernel: In-situ OAM (IOAM) with IPv6 Dec 13 01:36:24.909805 kernel: NET: Registered PF_PACKET protocol family Dec 13 01:36:24.909813 kernel: Key type dns_resolver registered Dec 13 01:36:24.909820 kernel: registered taskstats version 1 Dec 13 01:36:24.909827 kernel: Loading compiled-in X.509 certificates Dec 13 01:36:24.909835 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.65-flatcar: d83da9ddb9e3c2439731828371f21d0232fd9ffb' Dec 13 01:36:24.909842 kernel: Key type .fscrypt registered Dec 13 01:36:24.909849 kernel: Key type fscrypt-provisioning registered Dec 13 01:36:24.909858 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 01:36:24.909865 kernel: ima: Allocated hash algorithm: sha1 Dec 13 01:36:24.909872 kernel: ima: No architecture policies found Dec 13 01:36:24.909880 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Dec 13 01:36:24.909887 kernel: clk: Disabling unused clocks Dec 13 01:36:24.909894 kernel: Freeing unused kernel memory: 39360K Dec 13 01:36:24.909901 kernel: Run /init as init process Dec 13 01:36:24.909908 kernel: with arguments: Dec 13 01:36:24.909917 kernel: /init Dec 13 01:36:24.909924 kernel: with environment: Dec 13 01:36:24.909931 kernel: HOME=/ Dec 13 01:36:24.909938 kernel: TERM=linux Dec 13 01:36:24.909945 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 01:36:24.909954 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Dec 13 01:36:24.909964 systemd[1]: Detected virtualization kvm. Dec 13 01:36:24.909972 systemd[1]: Detected architecture arm64. Dec 13 01:36:24.909981 systemd[1]: Running in initrd. Dec 13 01:36:24.909988 systemd[1]: No hostname configured, using default hostname. Dec 13 01:36:24.909996 systemd[1]: Hostname set to . Dec 13 01:36:24.910004 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:36:24.910012 systemd[1]: Queued start job for default target initrd.target. Dec 13 01:36:24.910020 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:36:24.910028 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:36:24.910036 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 13 01:36:24.910045 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 13 01:36:24.910053 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 13 01:36:24.910062 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 13 01:36:24.910071 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 13 01:36:24.910080 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 13 01:36:24.910088 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:36:24.910096 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:36:24.910106 systemd[1]: Reached target paths.target - Path Units. Dec 13 01:36:24.910128 systemd[1]: Reached target slices.target - Slice Units. Dec 13 01:36:24.910136 systemd[1]: Reached target swap.target - Swaps. Dec 13 01:36:24.910144 systemd[1]: Reached target timers.target - Timer Units. Dec 13 01:36:24.910152 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 13 01:36:24.910160 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 13 01:36:24.910168 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 13 01:36:24.910176 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 13 01:36:24.910186 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:36:24.910194 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 13 01:36:24.910202 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:36:24.910247 systemd[1]: Reached target sockets.target - Socket Units. Dec 13 01:36:24.910257 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 13 01:36:24.910266 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 13 01:36:24.910274 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 13 01:36:24.910282 systemd[1]: Starting systemd-fsck-usr.service... Dec 13 01:36:24.910289 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 13 01:36:24.910299 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 13 01:36:24.910307 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:36:24.910316 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 13 01:36:24.910324 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:36:24.910331 systemd[1]: Finished systemd-fsck-usr.service. Dec 13 01:36:24.910340 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 13 01:36:24.910349 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:36:24.910374 systemd-journald[237]: Collecting audit messages is disabled. Dec 13 01:36:24.910394 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:36:24.910403 systemd-journald[237]: Journal started Dec 13 01:36:24.910421 systemd-journald[237]: Runtime Journal (/run/log/journal/4953fdc3fe884cd58ac97ea9f9d6eb0e) is 5.9M, max 47.3M, 41.4M free. Dec 13 01:36:24.902492 systemd-modules-load[238]: Inserted module 'overlay' Dec 13 01:36:24.913248 systemd[1]: Started systemd-journald.service - Journal Service. Dec 13 01:36:24.913471 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 13 01:36:24.916266 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 13 01:36:24.918698 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 13 01:36:24.919151 systemd-modules-load[238]: Inserted module 'br_netfilter' Dec 13 01:36:24.919805 kernel: Bridge firewalling registered Dec 13 01:36:24.920135 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 13 01:36:24.921237 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 13 01:36:24.925374 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 13 01:36:24.929300 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:36:24.931321 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:36:24.934370 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:36:24.935938 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 13 01:36:24.938463 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:36:24.941857 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 13 01:36:24.949878 dracut-cmdline[274]: dracut-dracut-053 Dec 13 01:36:24.952299 dracut-cmdline[274]: 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=9494f75a68cfbdce95d0d2f9b58d6d75bc38ee5b4e31dfc2a6da695ffafefba6 Dec 13 01:36:24.969750 systemd-resolved[278]: Positive Trust Anchors: Dec 13 01:36:24.969767 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:36:24.969798 systemd-resolved[278]: 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 Dec 13 01:36:24.974343 systemd-resolved[278]: Defaulting to hostname 'linux'. Dec 13 01:36:24.975261 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 13 01:36:24.977881 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:36:25.022247 kernel: SCSI subsystem initialized Dec 13 01:36:25.025236 kernel: Loading iSCSI transport class v2.0-870. Dec 13 01:36:25.033240 kernel: iscsi: registered transport (tcp) Dec 13 01:36:25.045499 kernel: iscsi: registered transport (qla4xxx) Dec 13 01:36:25.045526 kernel: QLogic iSCSI HBA Driver Dec 13 01:36:25.087465 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 13 01:36:25.098348 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 13 01:36:25.115525 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 13 01:36:25.115574 kernel: device-mapper: uevent: version 1.0.3 Dec 13 01:36:25.115602 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Dec 13 01:36:25.160243 kernel: raid6: neonx8 gen() 15688 MB/s Dec 13 01:36:25.177245 kernel: raid6: neonx4 gen() 15590 MB/s Dec 13 01:36:25.194237 kernel: raid6: neonx2 gen() 13126 MB/s Dec 13 01:36:25.211238 kernel: raid6: neonx1 gen() 10448 MB/s Dec 13 01:36:25.228236 kernel: raid6: int64x8 gen() 6944 MB/s Dec 13 01:36:25.245238 kernel: raid6: int64x4 gen() 7305 MB/s Dec 13 01:36:25.262239 kernel: raid6: int64x2 gen() 6095 MB/s Dec 13 01:36:25.279240 kernel: raid6: int64x1 gen() 5037 MB/s Dec 13 01:36:25.279266 kernel: raid6: using algorithm neonx8 gen() 15688 MB/s Dec 13 01:36:25.296234 kernel: raid6: .... xor() 11862 MB/s, rmw enabled Dec 13 01:36:25.296247 kernel: raid6: using neon recovery algorithm Dec 13 01:36:25.301235 kernel: xor: measuring software checksum speed Dec 13 01:36:25.301264 kernel: 8regs : 19290 MB/sec Dec 13 01:36:25.301283 kernel: 32regs : 18174 MB/sec Dec 13 01:36:25.302540 kernel: arm64_neon : 26247 MB/sec Dec 13 01:36:25.302554 kernel: xor: using function: arm64_neon (26247 MB/sec) Dec 13 01:36:25.353407 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 13 01:36:25.364137 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 13 01:36:25.381382 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:36:25.391891 systemd-udevd[462]: Using default interface naming scheme 'v255'. Dec 13 01:36:25.395053 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:36:25.397314 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 13 01:36:25.411937 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Dec 13 01:36:25.436931 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 13 01:36:25.448331 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 13 01:36:25.488594 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:36:25.494904 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 13 01:36:25.507809 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 13 01:36:25.509102 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 13 01:36:25.510742 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:36:25.512515 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 13 01:36:25.522389 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 13 01:36:25.525244 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Dec 13 01:36:25.536004 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Dec 13 01:36:25.536110 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Dec 13 01:36:25.536122 kernel: GPT:9289727 != 19775487 Dec 13 01:36:25.536131 kernel: GPT:Alternate GPT header not at the end of the disk. Dec 13 01:36:25.536141 kernel: GPT:9289727 != 19775487 Dec 13 01:36:25.536150 kernel: GPT: Use GNU Parted to correct GPT errors. Dec 13 01:36:25.536164 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:36:25.535420 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 13 01:36:25.539901 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 01:36:25.540008 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:36:25.545305 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:36:25.546232 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:36:25.546360 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:36:25.548368 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:36:25.556234 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (509) Dec 13 01:36:25.558252 kernel: BTRFS: device fsid 2893cd1e-612b-4262-912c-10787dc9c881 devid 1 transid 46 /dev/vda3 scanned by (udev-worker) (512) Dec 13 01:36:25.558493 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:36:25.568908 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:36:25.573486 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Dec 13 01:36:25.577754 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Dec 13 01:36:25.583925 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Dec 13 01:36:25.584836 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Dec 13 01:36:25.589705 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Dec 13 01:36:25.604338 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 13 01:36:25.606266 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 13 01:36:25.610754 disk-uuid[551]: Primary Header is updated. Dec 13 01:36:25.610754 disk-uuid[551]: Secondary Entries is updated. Dec 13 01:36:25.610754 disk-uuid[551]: Secondary Header is updated. Dec 13 01:36:25.617248 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:36:25.628364 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:36:26.629924 disk-uuid[552]: The operation has completed successfully. Dec 13 01:36:26.631124 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:36:26.654805 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 01:36:26.654921 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 13 01:36:26.684599 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 13 01:36:26.687683 sh[574]: Success Dec 13 01:36:26.707760 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Dec 13 01:36:26.746387 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 13 01:36:26.757849 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 13 01:36:26.760682 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 13 01:36:26.772058 kernel: BTRFS info (device dm-0): first mount of filesystem 2893cd1e-612b-4262-912c-10787dc9c881 Dec 13 01:36:26.772103 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Dec 13 01:36:26.772115 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Dec 13 01:36:26.772125 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 13 01:36:26.772652 kernel: BTRFS info (device dm-0): using free space tree Dec 13 01:36:26.776347 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 13 01:36:26.777423 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 13 01:36:26.786453 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 13 01:36:26.788508 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 13 01:36:26.797720 kernel: BTRFS info (device vda6): first mount of filesystem dbef6a22-a801-4c1e-a0cd-3fc525f899dd Dec 13 01:36:26.797779 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Dec 13 01:36:26.797803 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:36:26.801238 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:36:26.807818 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 01:36:26.810274 kernel: BTRFS info (device vda6): last unmount of filesystem dbef6a22-a801-4c1e-a0cd-3fc525f899dd Dec 13 01:36:26.814735 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 13 01:36:26.828397 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 13 01:36:26.875497 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 13 01:36:26.890419 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 13 01:36:26.913087 systemd-networkd[761]: lo: Link UP Dec 13 01:36:26.913097 systemd-networkd[761]: lo: Gained carrier Dec 13 01:36:26.913769 systemd-networkd[761]: Enumeration completed Dec 13 01:36:26.914444 systemd-networkd[761]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:36:26.914448 systemd-networkd[761]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 01:36:26.916297 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 13 01:36:26.917053 systemd-networkd[761]: eth0: Link UP Dec 13 01:36:26.917056 systemd-networkd[761]: eth0: Gained carrier Dec 13 01:36:26.917063 systemd-networkd[761]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:36:26.917544 systemd[1]: Reached target network.target - Network. Dec 13 01:36:26.931538 ignition[668]: Ignition 2.19.0 Dec 13 01:36:26.931548 ignition[668]: Stage: fetch-offline Dec 13 01:36:26.931583 ignition[668]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:26.931593 ignition[668]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:26.931750 ignition[668]: parsed url from cmdline: "" Dec 13 01:36:26.931753 ignition[668]: no config URL provided Dec 13 01:36:26.931760 ignition[668]: reading system config file "/usr/lib/ignition/user.ign" Dec 13 01:36:26.931767 ignition[668]: no config at "/usr/lib/ignition/user.ign" Dec 13 01:36:26.931789 ignition[668]: op(1): [started] loading QEMU firmware config module Dec 13 01:36:26.931795 ignition[668]: op(1): executing: "modprobe" "qemu_fw_cfg" Dec 13 01:36:26.938257 systemd-networkd[761]: eth0: DHCPv4 address 10.0.0.93/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 01:36:26.940701 ignition[668]: op(1): [finished] loading QEMU firmware config module Dec 13 01:36:26.947014 ignition[668]: parsing config with SHA512: fe50dfe9d316d1da9d6c1d0ef6c7ad37d08bcd61b8581329265a9545a744c1b57a9e7a0776d97729944752ebeb8fdf7a5fab9af32b5343e0966d206c396aea15 Dec 13 01:36:26.950034 unknown[668]: fetched base config from "system" Dec 13 01:36:26.950044 unknown[668]: fetched user config from "qemu" Dec 13 01:36:26.950343 ignition[668]: fetch-offline: fetch-offline passed Dec 13 01:36:26.950400 ignition[668]: Ignition finished successfully Dec 13 01:36:26.952516 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 13 01:36:26.955461 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Dec 13 01:36:26.961360 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 13 01:36:26.970805 ignition[772]: Ignition 2.19.0 Dec 13 01:36:26.970815 ignition[772]: Stage: kargs Dec 13 01:36:26.970966 ignition[772]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:26.970976 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:26.971640 ignition[772]: kargs: kargs passed Dec 13 01:36:26.971679 ignition[772]: Ignition finished successfully Dec 13 01:36:26.973694 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 13 01:36:26.985424 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 13 01:36:26.993861 ignition[780]: Ignition 2.19.0 Dec 13 01:36:26.993871 ignition[780]: Stage: disks Dec 13 01:36:26.994025 ignition[780]: no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:26.996572 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 13 01:36:26.994035 ignition[780]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:26.997527 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 13 01:36:26.994731 ignition[780]: disks: disks passed Dec 13 01:36:26.998775 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 13 01:36:26.994773 ignition[780]: Ignition finished successfully Dec 13 01:36:26.999637 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 13 01:36:27.000314 systemd[1]: Reached target sysinit.target - System Initialization. Dec 13 01:36:27.001709 systemd[1]: Reached target basic.target - Basic System. Dec 13 01:36:27.012376 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 13 01:36:27.021037 systemd-fsck[790]: ROOT: clean, 14/553520 files, 52654/553472 blocks Dec 13 01:36:27.024404 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 13 01:36:27.026169 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 13 01:36:27.069231 kernel: EXT4-fs (vda9): mounted filesystem 32632247-db8d-4541-89c0-6f68c7fa7ee3 r/w with ordered data mode. Quota mode: none. Dec 13 01:36:27.070228 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 13 01:36:27.070965 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 13 01:36:27.085328 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 13 01:36:27.087401 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 13 01:36:27.089032 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Dec 13 01:36:27.089078 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 13 01:36:27.089099 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 13 01:36:27.092691 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 13 01:36:27.094203 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 13 01:36:27.099227 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (798) Dec 13 01:36:27.101341 kernel: BTRFS info (device vda6): first mount of filesystem dbef6a22-a801-4c1e-a0cd-3fc525f899dd Dec 13 01:36:27.101362 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Dec 13 01:36:27.101377 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:36:27.106247 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:36:27.107558 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 13 01:36:27.139236 initrd-setup-root[822]: cut: /sysroot/etc/passwd: No such file or directory Dec 13 01:36:27.142957 initrd-setup-root[829]: cut: /sysroot/etc/group: No such file or directory Dec 13 01:36:27.146586 initrd-setup-root[836]: cut: /sysroot/etc/shadow: No such file or directory Dec 13 01:36:27.149840 initrd-setup-root[843]: cut: /sysroot/etc/gshadow: No such file or directory Dec 13 01:36:27.217522 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 13 01:36:27.226338 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 13 01:36:27.227707 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 13 01:36:27.232227 kernel: BTRFS info (device vda6): last unmount of filesystem dbef6a22-a801-4c1e-a0cd-3fc525f899dd Dec 13 01:36:27.247464 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 13 01:36:27.248155 ignition[910]: INFO : Ignition 2.19.0 Dec 13 01:36:27.248155 ignition[910]: INFO : Stage: mount Dec 13 01:36:27.248155 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:27.248155 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:27.251391 ignition[910]: INFO : mount: mount passed Dec 13 01:36:27.251391 ignition[910]: INFO : Ignition finished successfully Dec 13 01:36:27.250849 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 13 01:36:27.258379 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 13 01:36:27.770499 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 13 01:36:27.778459 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 13 01:36:27.783234 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (923) Dec 13 01:36:27.785492 kernel: BTRFS info (device vda6): first mount of filesystem dbef6a22-a801-4c1e-a0cd-3fc525f899dd Dec 13 01:36:27.785515 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Dec 13 01:36:27.785526 kernel: BTRFS info (device vda6): using free space tree Dec 13 01:36:27.787232 kernel: BTRFS info (device vda6): auto enabling async discard Dec 13 01:36:27.788451 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 13 01:36:27.803801 ignition[940]: INFO : Ignition 2.19.0 Dec 13 01:36:27.803801 ignition[940]: INFO : Stage: files Dec 13 01:36:27.805013 ignition[940]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:27.805013 ignition[940]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:27.805013 ignition[940]: DEBUG : files: compiled without relabeling support, skipping Dec 13 01:36:27.807771 ignition[940]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 13 01:36:27.807771 ignition[940]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 13 01:36:27.810835 ignition[940]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 13 01:36:27.811788 ignition[940]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 13 01:36:27.811788 ignition[940]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 01:36:27.811300 unknown[940]: wrote ssh authorized keys file for user: core Dec 13 01:36:27.815346 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 01:36:27.816733 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Dec 13 01:36:28.066534 systemd-networkd[761]: eth0: Gained IPv6LL Dec 13 01:36:28.152739 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Dec 13 01:36:28.425906 ignition[940]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Dec 13 01:36:28.425906 ignition[940]: INFO : files: op(8): [started] processing unit "containerd.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(8): [finished] processing unit "containerd.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" Dec 13 01:36:28.428579 ignition[940]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" Dec 13 01:36:28.449434 ignition[940]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 01:36:28.453071 ignition[940]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" Dec 13 01:36:28.455391 ignition[940]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" Dec 13 01:36:28.455391 ignition[940]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 13 01:36:28.455391 ignition[940]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 13 01:36:28.455391 ignition[940]: INFO : files: files passed Dec 13 01:36:28.455391 ignition[940]: INFO : Ignition finished successfully Dec 13 01:36:28.455798 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 13 01:36:28.465400 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 13 01:36:28.467457 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 13 01:36:28.470236 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 01:36:28.470342 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 13 01:36:28.474258 initrd-setup-root-after-ignition[968]: grep: /sysroot/oem/oem-release: No such file or directory Dec 13 01:36:28.477222 initrd-setup-root-after-ignition[970]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:36:28.477222 initrd-setup-root-after-ignition[970]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:36:28.479671 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 13 01:36:28.481721 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 13 01:36:28.482888 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 13 01:36:28.501387 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 13 01:36:28.520270 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 01:36:28.521261 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 13 01:36:28.522372 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 13 01:36:28.523109 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 13 01:36:28.525316 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 13 01:36:28.533367 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 13 01:36:28.547290 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 13 01:36:28.549508 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 13 01:36:28.560764 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:36:28.561790 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:36:28.563253 systemd[1]: Stopped target timers.target - Timer Units. Dec 13 01:36:28.564550 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 01:36:28.564681 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 13 01:36:28.567796 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 13 01:36:28.569284 systemd[1]: Stopped target basic.target - Basic System. Dec 13 01:36:28.570696 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 13 01:36:28.572048 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 13 01:36:28.577348 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 13 01:36:28.578839 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 13 01:36:28.580271 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 13 01:36:28.581801 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 13 01:36:28.583244 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 13 01:36:28.584572 systemd[1]: Stopped target swap.target - Swaps. Dec 13 01:36:28.585734 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 01:36:28.585866 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 13 01:36:28.587788 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:36:28.589249 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:36:28.590734 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 13 01:36:28.595265 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:36:28.596203 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 01:36:28.596337 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 13 01:36:28.598718 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 13 01:36:28.598849 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 13 01:36:28.600309 systemd[1]: Stopped target paths.target - Path Units. Dec 13 01:36:28.601494 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 01:36:28.602293 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:36:28.603857 systemd[1]: Stopped target slices.target - Slice Units. Dec 13 01:36:28.605099 systemd[1]: Stopped target sockets.target - Socket Units. Dec 13 01:36:28.606710 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 01:36:28.606795 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 13 01:36:28.607915 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 01:36:28.607995 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 13 01:36:28.609140 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 13 01:36:28.609253 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 13 01:36:28.610472 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 01:36:28.610567 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 13 01:36:28.621392 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 13 01:36:28.622075 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 01:36:28.622191 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:36:28.624313 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 13 01:36:28.625731 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 01:36:28.625845 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:36:28.627175 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 01:36:28.627350 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 13 01:36:28.636192 ignition[994]: INFO : Ignition 2.19.0 Dec 13 01:36:28.636192 ignition[994]: INFO : Stage: umount Dec 13 01:36:28.638036 ignition[994]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 13 01:36:28.638036 ignition[994]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Dec 13 01:36:28.638036 ignition[994]: INFO : umount: umount passed Dec 13 01:36:28.638036 ignition[994]: INFO : Ignition finished successfully Dec 13 01:36:28.638810 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 01:36:28.638915 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 13 01:36:28.642820 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 13 01:36:28.643301 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 13 01:36:28.644288 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 13 01:36:28.645991 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 01:36:28.646069 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 13 01:36:28.648208 systemd[1]: Stopped target network.target - Network. Dec 13 01:36:28.649102 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 01:36:28.649165 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 13 01:36:28.650500 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 13 01:36:28.650543 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 13 01:36:28.651796 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 01:36:28.651837 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 13 01:36:28.653225 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 13 01:36:28.653271 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 13 01:36:28.654679 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 01:36:28.654720 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 13 01:36:28.656360 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 13 01:36:28.659465 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 13 01:36:28.663522 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 01:36:28.665295 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 13 01:36:28.668278 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 13 01:36:28.668354 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:36:28.669618 systemd-networkd[761]: eth0: DHCPv6 lease lost Dec 13 01:36:28.671819 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 01:36:28.671922 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 13 01:36:28.673297 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 01:36:28.673333 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:36:28.684422 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 13 01:36:28.685306 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 13 01:36:28.685374 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 13 01:36:28.686964 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 01:36:28.687006 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:36:28.688458 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 13 01:36:28.688501 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 13 01:36:28.690162 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:36:28.698673 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 01:36:28.698780 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 13 01:36:28.701785 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 01:36:28.701915 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:36:28.703489 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 01:36:28.703525 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 13 01:36:28.704981 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 01:36:28.705015 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:36:28.706322 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 01:36:28.706362 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 13 01:36:28.708286 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 01:36:28.708324 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 13 01:36:28.710199 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 01:36:28.710247 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 13 01:36:28.727361 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 13 01:36:28.728152 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 13 01:36:28.728204 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:36:28.729805 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:36:28.729845 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:36:28.734523 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 01:36:28.734628 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 13 01:36:28.736308 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 13 01:36:28.739159 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 13 01:36:28.746836 systemd[1]: Switching root. Dec 13 01:36:28.779751 systemd-journald[237]: Journal stopped Dec 13 01:36:29.473490 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Dec 13 01:36:29.473541 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 01:36:29.473553 kernel: SELinux: policy capability open_perms=1 Dec 13 01:36:29.473563 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 01:36:29.473574 kernel: SELinux: policy capability always_check_network=0 Dec 13 01:36:29.473584 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 01:36:29.473605 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 01:36:29.473616 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 01:36:29.473626 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 13 01:36:29.473635 kernel: audit: type=1403 audit(1734053788.942:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 01:36:29.473647 systemd[1]: Successfully loaded SELinux policy in 30.550ms. Dec 13 01:36:29.473667 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.520ms. Dec 13 01:36:29.473680 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Dec 13 01:36:29.473693 systemd[1]: Detected virtualization kvm. Dec 13 01:36:29.473704 systemd[1]: Detected architecture arm64. Dec 13 01:36:29.473715 systemd[1]: Detected first boot. Dec 13 01:36:29.473726 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:36:29.473736 zram_generator::config[1058]: No configuration found. Dec 13 01:36:29.473747 systemd[1]: Populated /etc with preset unit settings. Dec 13 01:36:29.473758 systemd[1]: Queued start job for default target multi-user.target. Dec 13 01:36:29.473770 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Dec 13 01:36:29.473785 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 13 01:36:29.473796 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 13 01:36:29.473807 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 13 01:36:29.473818 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 13 01:36:29.473828 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 13 01:36:29.473840 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 13 01:36:29.473850 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 13 01:36:29.473861 systemd[1]: Created slice user.slice - User and Session Slice. Dec 13 01:36:29.473873 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 13 01:36:29.473884 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 13 01:36:29.473895 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 13 01:36:29.473906 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 13 01:36:29.473917 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 13 01:36:29.473928 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 13 01:36:29.473939 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Dec 13 01:36:29.473949 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 13 01:36:29.473960 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 13 01:36:29.473972 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 13 01:36:29.473983 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 13 01:36:29.473994 systemd[1]: Reached target slices.target - Slice Units. Dec 13 01:36:29.474004 systemd[1]: Reached target swap.target - Swaps. Dec 13 01:36:29.474015 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 13 01:36:29.474026 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 13 01:36:29.474037 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 13 01:36:29.474048 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Dec 13 01:36:29.474061 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 13 01:36:29.474072 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 13 01:36:29.474082 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 13 01:36:29.474093 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 13 01:36:29.474104 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 13 01:36:29.474114 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 13 01:36:29.474124 systemd[1]: Mounting media.mount - External Media Directory... Dec 13 01:36:29.474135 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 13 01:36:29.474145 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 13 01:36:29.474164 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 13 01:36:29.474175 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 13 01:36:29.474185 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:36:29.474196 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 13 01:36:29.474206 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 13 01:36:29.474236 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 13 01:36:29.474249 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 13 01:36:29.474260 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 13 01:36:29.474272 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 13 01:36:29.474283 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 13 01:36:29.474293 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 13 01:36:29.474304 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Dec 13 01:36:29.474316 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Dec 13 01:36:29.474326 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 13 01:36:29.474337 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 13 01:36:29.474348 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 13 01:36:29.474358 kernel: loop: module loaded Dec 13 01:36:29.474369 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 13 01:36:29.474380 kernel: fuse: init (API version 7.39) Dec 13 01:36:29.474389 kernel: ACPI: bus type drm_connector registered Dec 13 01:36:29.474399 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 13 01:36:29.474411 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 13 01:36:29.474421 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 13 01:36:29.474432 systemd[1]: Mounted media.mount - External Media Directory. Dec 13 01:36:29.474442 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 13 01:36:29.474452 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 13 01:36:29.474464 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 13 01:36:29.474476 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 13 01:36:29.474486 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 01:36:29.474501 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 13 01:36:29.474511 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 01:36:29.474522 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 13 01:36:29.474550 systemd-journald[1135]: Collecting audit messages is disabled. Dec 13 01:36:29.474572 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 01:36:29.474583 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 13 01:36:29.474601 systemd-journald[1135]: Journal started Dec 13 01:36:29.474625 systemd-journald[1135]: Runtime Journal (/run/log/journal/4953fdc3fe884cd58ac97ea9f9d6eb0e) is 5.9M, max 47.3M, 41.4M free. Dec 13 01:36:29.476263 systemd[1]: Started systemd-journald.service - Journal Service. Dec 13 01:36:29.477255 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 01:36:29.477412 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 13 01:36:29.478720 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 01:36:29.478867 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 13 01:36:29.480079 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 01:36:29.480293 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 13 01:36:29.481480 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 13 01:36:29.482673 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 13 01:36:29.484094 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 13 01:36:29.485449 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 13 01:36:29.496053 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 13 01:36:29.509303 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 13 01:36:29.511289 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 13 01:36:29.512099 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 13 01:36:29.515390 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 13 01:36:29.518416 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 13 01:36:29.519407 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 01:36:29.520450 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 13 01:36:29.521428 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 13 01:36:29.523456 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 13 01:36:29.530228 systemd-journald[1135]: Time spent on flushing to /var/log/journal/4953fdc3fe884cd58ac97ea9f9d6eb0e is 14.983ms for 827 entries. Dec 13 01:36:29.530228 systemd-journald[1135]: System Journal (/var/log/journal/4953fdc3fe884cd58ac97ea9f9d6eb0e) is 8.0M, max 195.6M, 187.6M free. Dec 13 01:36:29.555052 systemd-journald[1135]: Received client request to flush runtime journal. Dec 13 01:36:29.530334 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 13 01:36:29.533601 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 13 01:36:29.535903 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 13 01:36:29.537014 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 13 01:36:29.552433 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Dec 13 01:36:29.553653 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 13 01:36:29.555287 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 13 01:36:29.558267 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 13 01:36:29.559926 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 13 01:36:29.566732 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Dec 13 01:36:29.566751 systemd-tmpfiles[1191]: ACLs are not supported, ignoring. Dec 13 01:36:29.567257 udevadm[1198]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Dec 13 01:36:29.571018 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 13 01:36:29.581363 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 13 01:36:29.602097 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 13 01:36:29.613565 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 13 01:36:29.624963 systemd-tmpfiles[1213]: ACLs are not supported, ignoring. Dec 13 01:36:29.624982 systemd-tmpfiles[1213]: ACLs are not supported, ignoring. Dec 13 01:36:29.628695 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 13 01:36:29.953760 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 13 01:36:29.962436 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 13 01:36:29.980797 systemd-udevd[1219]: Using default interface naming scheme 'v255'. Dec 13 01:36:29.993202 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 13 01:36:29.999491 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 13 01:36:30.021044 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1220) Dec 13 01:36:30.021126 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1220) Dec 13 01:36:30.025407 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 13 01:36:30.028318 systemd[1]: Found device dev-ttyAMA0.device - /dev/ttyAMA0. Dec 13 01:36:30.045268 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 46 scanned by (udev-worker) (1236) Dec 13 01:36:30.070007 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 13 01:36:30.074579 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Dec 13 01:36:30.124454 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 13 01:36:30.126051 systemd-networkd[1225]: lo: Link UP Dec 13 01:36:30.126270 systemd-networkd[1225]: lo: Gained carrier Dec 13 01:36:30.127082 systemd-networkd[1225]: Enumeration completed Dec 13 01:36:30.127255 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 13 01:36:30.127712 systemd-networkd[1225]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:36:30.127788 systemd-networkd[1225]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 13 01:36:30.128547 systemd-networkd[1225]: eth0: Link UP Dec 13 01:36:30.128656 systemd-networkd[1225]: eth0: Gained carrier Dec 13 01:36:30.128707 systemd-networkd[1225]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 13 01:36:30.129267 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 13 01:36:30.136124 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Dec 13 01:36:30.140118 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Dec 13 01:36:30.150275 systemd-networkd[1225]: eth0: DHCPv4 address 10.0.0.93/16, gateway 10.0.0.1 acquired from 10.0.0.1 Dec 13 01:36:30.156876 lvm[1258]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:36:30.169452 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 13 01:36:30.189539 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Dec 13 01:36:30.190701 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 13 01:36:30.202397 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Dec 13 01:36:30.205842 lvm[1265]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:36:30.237462 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Dec 13 01:36:30.238555 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 13 01:36:30.239513 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). Dec 13 01:36:30.239544 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 13 01:36:30.240278 systemd[1]: Reached target machines.target - Containers. Dec 13 01:36:30.241918 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Dec 13 01:36:30.249401 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 13 01:36:30.251252 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 13 01:36:30.252144 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:36:30.253041 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 13 01:36:30.257359 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Dec 13 01:36:30.259798 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 13 01:36:30.261333 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 13 01:36:30.268548 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 13 01:36:30.272283 kernel: loop0: detected capacity change from 0 to 114328 Dec 13 01:36:30.278629 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 01:36:30.279301 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Dec 13 01:36:30.285236 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 13 01:36:30.319235 kernel: loop1: detected capacity change from 0 to 194512 Dec 13 01:36:30.355264 kernel: loop2: detected capacity change from 0 to 114432 Dec 13 01:36:30.403248 kernel: loop3: detected capacity change from 0 to 114328 Dec 13 01:36:30.418412 kernel: loop4: detected capacity change from 0 to 194512 Dec 13 01:36:30.429238 kernel: loop5: detected capacity change from 0 to 114432 Dec 13 01:36:30.433038 (sd-merge)[1288]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Dec 13 01:36:30.433509 (sd-merge)[1288]: Merged extensions into '/usr'. Dec 13 01:36:30.436926 systemd[1]: Reloading requested from client PID 1273 ('systemd-sysext') (unit systemd-sysext.service)... Dec 13 01:36:30.436947 systemd[1]: Reloading... Dec 13 01:36:30.472638 zram_generator::config[1320]: No configuration found. Dec 13 01:36:30.515736 ldconfig[1269]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 13 01:36:30.566372 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. Dec 13 01:36:30.608278 systemd[1]: Reloading finished in 170 ms. Dec 13 01:36:30.622000 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 13 01:36:30.623196 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 13 01:36:30.636354 systemd[1]: Starting ensure-sysext.service... Dec 13 01:36:30.637973 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 13 01:36:30.642810 systemd[1]: Reloading requested from client PID 1357 ('systemctl') (unit ensure-sysext.service)... Dec 13 01:36:30.642826 systemd[1]: Reloading... Dec 13 01:36:30.653129 systemd-tmpfiles[1358]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 13 01:36:30.653411 systemd-tmpfiles[1358]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 13 01:36:30.654036 systemd-tmpfiles[1358]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 01:36:30.654281 systemd-tmpfiles[1358]: ACLs are not supported, ignoring. Dec 13 01:36:30.654331 systemd-tmpfiles[1358]: ACLs are not supported, ignoring. Dec 13 01:36:30.656590 systemd-tmpfiles[1358]: Detected autofs mount point /boot during canonicalization of boot. Dec 13 01:36:30.656602 systemd-tmpfiles[1358]: Skipping /boot Dec 13 01:36:30.663248 systemd-tmpfiles[1358]: Detected autofs mount point /boot during canonicalization of boot. Dec 13 01:36:30.663261 systemd-tmpfiles[1358]: Skipping /boot Dec 13 01:36:30.687391 zram_generator::config[1385]: No configuration found. Dec 13 01:36:30.767863 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. Dec 13 01:36:30.810162 systemd[1]: Reloading finished in 167 ms. Dec 13 01:36:30.824968 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 13 01:36:30.839646 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Dec 13 01:36:30.842364 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 13 01:36:30.844511 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 13 01:36:30.848484 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 13 01:36:30.853419 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 13 01:36:30.858260 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:36:30.863523 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 13 01:36:30.868437 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 13 01:36:30.873106 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 13 01:36:30.874077 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:36:30.874966 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 01:36:30.875113 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 13 01:36:30.876919 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 13 01:36:30.878494 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 01:36:30.878648 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 13 01:36:30.882363 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 01:36:30.882579 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 13 01:36:30.893451 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 13 01:36:30.896560 systemd[1]: Finished ensure-sysext.service. Dec 13 01:36:30.899736 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 13 01:36:30.902234 augenrules[1465]: No rules Dec 13 01:36:30.915464 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 13 01:36:30.917472 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 13 01:36:30.920755 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 13 01:36:30.925385 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 13 01:36:30.926272 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 13 01:36:30.927968 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Dec 13 01:36:30.932375 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 13 01:36:30.932802 systemd-resolved[1434]: Positive Trust Anchors: Dec 13 01:36:30.932813 systemd-resolved[1434]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:36:30.932845 systemd-resolved[1434]: 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 Dec 13 01:36:30.934135 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Dec 13 01:36:30.935392 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 13 01:36:30.936764 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 13 01:36:30.936914 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 13 01:36:30.938129 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 01:36:30.938293 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 13 01:36:30.939454 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 13 01:36:30.939603 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 13 01:36:30.940840 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 13 01:36:30.941021 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 13 01:36:30.942970 systemd-resolved[1434]: Defaulting to hostname 'linux'. Dec 13 01:36:30.947507 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 13 01:36:30.948690 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 13 01:36:30.950298 systemd[1]: Reached target network.target - Network. Dec 13 01:36:30.950993 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 13 01:36:30.951900 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 13 01:36:30.951964 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 13 01:36:30.951992 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). Dec 13 01:36:30.995049 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Dec 13 01:36:30.996100 systemd-timesyncd[1480]: Contacted time server 10.0.0.1:123 (10.0.0.1). Dec 13 01:36:30.996149 systemd-timesyncd[1480]: Initial clock synchronization to Fri 2024-12-13 01:36:31.293947 UTC. Dec 13 01:36:30.996445 systemd[1]: Reached target sysinit.target - System Initialization. Dec 13 01:36:30.997315 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 13 01:36:30.998275 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 13 01:36:30.999186 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 13 01:36:31.000105 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 13 01:36:31.000140 systemd[1]: Reached target paths.target - Path Units. Dec 13 01:36:31.000868 systemd[1]: Reached target time-set.target - System Time Set. Dec 13 01:36:31.001843 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 13 01:36:31.002883 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 13 01:36:31.003861 systemd[1]: Reached target timers.target - Timer Units. Dec 13 01:36:31.005596 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 13 01:36:31.007914 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 13 01:36:31.009730 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 13 01:36:31.015314 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 13 01:36:31.016151 systemd[1]: Reached target sockets.target - Socket Units. Dec 13 01:36:31.016937 systemd[1]: Reached target basic.target - Basic System. Dec 13 01:36:31.017808 systemd[1]: System is tainted: cgroupsv1 Dec 13 01:36:31.017853 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 13 01:36:31.017875 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 13 01:36:31.019158 systemd[1]: Starting containerd.service - containerd container runtime... Dec 13 01:36:31.021038 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 13 01:36:31.022786 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 13 01:36:31.027403 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 13 01:36:31.028269 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). Dec 13 01:36:31.029357 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 13 01:36:31.035492 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 13 01:36:31.038360 jq[1497]: false Dec 13 01:36:31.037326 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 13 01:36:31.043202 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 13 01:36:31.049871 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 13 01:36:31.052531 systemd[1]: Starting update-engine.service - Update Engine... Dec 13 01:36:31.056968 extend-filesystems[1499]: Found loop3 Dec 13 01:36:31.056968 extend-filesystems[1499]: Found loop4 Dec 13 01:36:31.056968 extend-filesystems[1499]: Found loop5 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda1 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda2 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda3 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found usr Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda4 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda6 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda7 Dec 13 01:36:31.061769 extend-filesystems[1499]: Found vda9 Dec 13 01:36:31.061769 extend-filesystems[1499]: Checking size of /dev/vda9 Dec 13 01:36:31.058379 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 13 01:36:31.065170 dbus-daemon[1496]: [system] SELinux support is enabled Dec 13 01:36:31.066510 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 13 01:36:31.091123 jq[1516]: true Dec 13 01:36:31.072579 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 13 01:36:31.072818 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 13 01:36:31.073071 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 01:36:31.073992 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 13 01:36:31.077462 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 01:36:31.077675 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 13 01:36:31.095010 extend-filesystems[1499]: Resized partition /dev/vda9 Dec 13 01:36:31.097766 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). Dec 13 01:36:31.098107 extend-filesystems[1531]: resize2fs 1.47.1 (20-May-2024) Dec 13 01:36:31.097812 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 13 01:36:31.100013 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). Dec 13 01:36:31.100041 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 13 01:36:31.103127 (ntainerd)[1526]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 13 01:36:31.104708 update_engine[1513]: I20241213 01:36:31.102638 1513 main.cc:92] Flatcar Update Engine starting Dec 13 01:36:31.113523 update_engine[1513]: I20241213 01:36:31.113461 1513 update_check_scheduler.cc:74] Next update check in 10m42s Dec 13 01:36:31.114733 systemd[1]: Started update-engine.service - Update Engine. Dec 13 01:36:31.114803 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Dec 13 01:36:31.114828 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 46 scanned by (udev-worker) (1235) Dec 13 01:36:31.116828 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 01:36:31.118144 jq[1523]: true Dec 13 01:36:31.119442 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 13 01:36:31.126226 systemd-logind[1505]: Watching system buttons on /dev/input/event0 (Power Button) Dec 13 01:36:31.130530 systemd-logind[1505]: New seat seat0. Dec 13 01:36:31.140920 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Dec 13 01:36:31.146732 systemd[1]: Started systemd-logind.service - User Login Management. Dec 13 01:36:31.161570 extend-filesystems[1531]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Dec 13 01:36:31.161570 extend-filesystems[1531]: old_desc_blocks = 1, new_desc_blocks = 1 Dec 13 01:36:31.161570 extend-filesystems[1531]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Dec 13 01:36:31.173351 extend-filesystems[1499]: Resized filesystem in /dev/vda9 Dec 13 01:36:31.163116 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 01:36:31.163451 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 13 01:36:31.198039 locksmithd[1535]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 01:36:31.203662 bash[1554]: Updated "/home/core/.ssh/authorized_keys" Dec 13 01:36:31.205922 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 13 01:36:31.207978 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 13 01:36:31.307717 containerd[1526]: time="2024-12-13T01:36:31.307580525Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Dec 13 01:36:31.336245 containerd[1526]: time="2024-12-13T01:36:31.336185758Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337648518Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.65-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337686772Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337716146Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337890735Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337909322Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337966786Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.337979647Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.338190083Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.338205932Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.338219748Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:36:31.338861 containerd[1526]: time="2024-12-13T01:36:31.338229913Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338324842Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338510632Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338638794Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338658087Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338745215Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Dec 13 01:36:31.339159 containerd[1526]: time="2024-12-13T01:36:31.338792804Z" level=info msg="metadata content store policy set" policy=shared Dec 13 01:36:31.345577 containerd[1526]: time="2024-12-13T01:36:31.345545168Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Dec 13 01:36:31.345695 containerd[1526]: time="2024-12-13T01:36:31.345681420Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Dec 13 01:36:31.345832 containerd[1526]: time="2024-12-13T01:36:31.345816967Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Dec 13 01:36:31.345897 containerd[1526]: time="2024-12-13T01:36:31.345884719Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Dec 13 01:36:31.345976 containerd[1526]: time="2024-12-13T01:36:31.345940067Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Dec 13 01:36:31.346184 containerd[1526]: time="2024-12-13T01:36:31.346163364Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Dec 13 01:36:31.347308 containerd[1526]: time="2024-12-13T01:36:31.347282217Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Dec 13 01:36:31.347519 containerd[1526]: time="2024-12-13T01:36:31.347498129Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Dec 13 01:36:31.347597 containerd[1526]: time="2024-12-13T01:36:31.347582892Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Dec 13 01:36:31.347653 containerd[1526]: time="2024-12-13T01:36:31.347640397Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Dec 13 01:36:31.347716 containerd[1526]: time="2024-12-13T01:36:31.347703088Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.347771 containerd[1526]: time="2024-12-13T01:36:31.347758020Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.347844 containerd[1526]: time="2024-12-13T01:36:31.347830627Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.347906 containerd[1526]: time="2024-12-13T01:36:31.347893899Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.347964 containerd[1526]: time="2024-12-13T01:36:31.347952317Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.348015 containerd[1526]: time="2024-12-13T01:36:31.348003722Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.348082 containerd[1526]: time="2024-12-13T01:36:31.348057949Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.348144 containerd[1526]: time="2024-12-13T01:36:31.348131137Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Dec 13 01:36:31.348203 containerd[1526]: time="2024-12-13T01:36:31.348192459Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348302 containerd[1526]: time="2024-12-13T01:36:31.348285437Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348382 containerd[1526]: time="2024-12-13T01:36:31.348361280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348437 containerd[1526]: time="2024-12-13T01:36:31.348425299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348496 containerd[1526]: time="2024-12-13T01:36:31.348483302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348549 containerd[1526]: time="2024-12-13T01:36:31.348537985Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348605 containerd[1526]: time="2024-12-13T01:36:31.348594162Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348662 containerd[1526]: time="2024-12-13T01:36:31.348649717Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348728 containerd[1526]: time="2024-12-13T01:36:31.348716100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348787 containerd[1526]: time="2024-12-13T01:36:31.348774808Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348845 containerd[1526]: time="2024-12-13T01:36:31.348828454Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348909 containerd[1526]: time="2024-12-13T01:36:31.348896083Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.348964 containerd[1526]: time="2024-12-13T01:36:31.348953338Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.349030 containerd[1526]: time="2024-12-13T01:36:31.349018353Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Dec 13 01:36:31.349098 containerd[1526]: time="2024-12-13T01:36:31.349085442Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.349172 containerd[1526]: time="2024-12-13T01:36:31.349157468Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.349229 containerd[1526]: time="2024-12-13T01:36:31.349215927Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Dec 13 01:36:31.349430 containerd[1526]: time="2024-12-13T01:36:31.349408148Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Dec 13 01:36:31.349553 containerd[1526]: time="2024-12-13T01:36:31.349533613Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Dec 13 01:36:31.349608 containerd[1526]: time="2024-12-13T01:36:31.349596802Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Dec 13 01:36:31.349669 containerd[1526]: time="2024-12-13T01:36:31.349654514Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Dec 13 01:36:31.349732 containerd[1526]: time="2024-12-13T01:36:31.349715794Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.349789 containerd[1526]: time="2024-12-13T01:36:31.349777863Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Dec 13 01:36:31.349845 containerd[1526]: time="2024-12-13T01:36:31.349833169Z" level=info msg="NRI interface is disabled by configuration." Dec 13 01:36:31.349895 containerd[1526]: time="2024-12-13T01:36:31.349883786Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Dec 13 01:36:31.350384 containerd[1526]: time="2024-12-13T01:36:31.350312914Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Dec 13 01:36:31.350565 containerd[1526]: time="2024-12-13T01:36:31.350548907Z" level=info msg="Connect containerd service" Dec 13 01:36:31.350639 containerd[1526]: time="2024-12-13T01:36:31.350626991Z" level=info msg="using legacy CRI server" Dec 13 01:36:31.350706 containerd[1526]: time="2024-12-13T01:36:31.350691922Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 13 01:36:31.350851 containerd[1526]: time="2024-12-13T01:36:31.350828050Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Dec 13 01:36:31.351623 containerd[1526]: time="2024-12-13T01:36:31.351591419Z" 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" Dec 13 01:36:31.351950 containerd[1526]: time="2024-12-13T01:36:31.351855252Z" level=info msg="Start subscribing containerd event" Dec 13 01:36:31.351950 containerd[1526]: time="2024-12-13T01:36:31.351925494Z" level=info msg="Start recovering state" Dec 13 01:36:31.352018 containerd[1526]: time="2024-12-13T01:36:31.351993454Z" level=info msg="Start event monitor" Dec 13 01:36:31.352018 containerd[1526]: time="2024-12-13T01:36:31.352005693Z" level=info msg="Start snapshots syncer" Dec 13 01:36:31.352018 containerd[1526]: time="2024-12-13T01:36:31.352017186Z" level=info msg="Start cni network conf syncer for default" Dec 13 01:36:31.352077 containerd[1526]: time="2024-12-13T01:36:31.352024820Z" level=info msg="Start streaming server" Dec 13 01:36:31.352485 containerd[1526]: time="2024-12-13T01:36:31.352461582Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 13 01:36:31.352618 containerd[1526]: time="2024-12-13T01:36:31.352595179Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 13 01:36:31.353484 containerd[1526]: time="2024-12-13T01:36:31.353461401Z" level=info msg="containerd successfully booted in 0.047391s" Dec 13 01:36:31.353574 systemd[1]: Started containerd.service - containerd container runtime. Dec 13 01:36:31.736007 sshd_keygen[1521]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 13 01:36:31.755539 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 13 01:36:31.764801 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 13 01:36:31.769786 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 01:36:31.770025 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 13 01:36:31.772529 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 13 01:36:31.783652 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 13 01:36:31.786191 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 13 01:36:31.788120 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Dec 13 01:36:31.789179 systemd[1]: Reached target getty.target - Login Prompts. Dec 13 01:36:31.970948 systemd-networkd[1225]: eth0: Gained IPv6LL Dec 13 01:36:31.973403 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 13 01:36:31.974896 systemd[1]: Reached target network-online.target - Network is Online. Dec 13 01:36:31.992508 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Dec 13 01:36:31.995310 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 13 01:36:31.997292 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 13 01:36:32.013737 systemd[1]: coreos-metadata.service: Deactivated successfully. Dec 13 01:36:32.014110 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Dec 13 01:36:32.017346 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 13 01:36:32.018780 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 13 01:36:32.492944 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 13 01:36:32.494355 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 13 01:36:32.495760 systemd[1]: Startup finished in 4.780s (kernel) + 3.583s (userspace) = 8.364s. Dec 13 01:36:32.497133 (kubelet)[1622]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Dec 13 01:36:32.991052 kubelet[1622]: E1213 01:36:32.990909 1622 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Dec 13 01:36:32.993529 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Dec 13 01:36:32.993705 systemd[1]: kubelet.service: Failed with result 'exit-code'. Dec 13 01:36:37.196781 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 13 01:36:37.208436 systemd[1]: Started sshd@0-10.0.0.93:22-10.0.0.1:58352.service - OpenSSH per-connection server daemon (10.0.0.1:58352). Dec 13 01:36:37.252743 sshd[1636]: Accepted publickey for core from 10.0.0.1 port 58352 ssh2: RSA SHA256:yVKhZEHbC7ylZ7bY3Y8pwdh1t/xp6Vz/y3yLFfd9j+Q Dec 13 01:36:37.254324 sshd[1636]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:36:37.266822 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 13 01:36:37.278426 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 13 01:36:37.280173 systemd-logind[1505]: New session 1 of user core. Dec 13 01:36:37.287427 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 13 01:36:37.289439 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 13 01:36:37.295565 (systemd)[1642]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:36:37.361992 systemd[1642]: Queued start job for default target default.target. Dec 13 01:36:37.362385 systemd[1642]: Created slice app.slice - User Application Slice. Dec 13 01:36:37.362403 systemd[1642]: Reached target paths.target - Paths. Dec 13 01:36:37.362415 systemd[1642]: Reached target timers.target - Timers. Dec 13 01:36:37.372328 systemd[1642]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 13 01:36:37.377699 systemd[1642]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 13 01:36:37.377754 systemd[1642]: Reached target sockets.target - Sockets. Dec 13 01:36:37.377767 systemd[1642]: Reached target basic.target - Basic System. Dec 13 01:36:37.377802 systemd[1642]: Reached target default.target - Main User Target. Dec 13 01:36:37.377835 systemd[1642]: Startup finished in 77ms. Dec 13 01:36:37.378167 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 13 01:36:37.379435 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 13 01:36:37.433485 systemd[1]: Started sshd@1-10.0.0.93:22-10.0.0.1:58362.service - OpenSSH per-connection server daemon (10.0.0.1:58362). Dec 13 01:36:37.461938 sshd[1654]: Accepted publickey for core from 10.0.0.1 port 58362 ssh2: RSA SHA256:yVKhZEHbC7ylZ7bY3Y8pwdh1t/xp6Vz/y3yLFfd9j+Q Dec 13 01:36:37.463034 sshd[1654]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:36:37.466793 systemd-logind[1505]: New session 2 of user core. Dec 13 01:36:37.483582 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 13 01:36:37.536071 sshd[1654]: pam_unix(sshd:session): session closed for user core Dec 13 01:36:37.552542 systemd[1]: Started sshd@2-10.0.0.93:22-10.0.0.1:58366.service - OpenSSH per-connection server daemon (10.0.0.1:58366). Dec 13 01:36:37.552918 systemd[1]: sshd@1-10.0.0.93:22-10.0.0.1:58362.service: Deactivated successfully. Dec 13 01:36:37.554951 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 01:36:37.555732 systemd-logind[1505]: Session 2 logged out. Waiting for processes to exit. Dec 13 01:36:37.556780 systemd-logind[1505]: Removed session 2. Dec 13 01:36:37.580607 sshd[1659]: Accepted publickey for core from 10.0.0.1 port 58366 ssh2: RSA SHA256:yVKhZEHbC7ylZ7bY3Y8pwdh1t/xp6Vz/y3yLFfd9j+Q Dec 13 01:36:37.581779 sshd[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:36:37.585742 systemd-logind[1505]: New session 3 of user core. Dec 13 01:36:37.595508 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 13 01:36:37.643701 sshd[1659]: pam_unix(sshd:session): session closed for user core Dec 13 01:36:37.652492 systemd[1]: Started sshd@3-10.0.0.93:22-10.0.0.1:58370.service - OpenSSH per-connection server daemon (10.0.0.1:58370). Dec 13 01:36:37.652855 systemd[1]: sshd@2-10.0.0.93:22-10.0.0.1:58366.service: Deactivated successfully. Dec 13 01:36:37.655937 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 01:36:37.656293 systemd-logind[1505]: Session 3 logged out. Waiting for processes to exit. Dec 13 01:36:37.657181 systemd-logind[1505]: Removed session 3. Dec 13 01:36:37.682157 sshd[1667]: Accepted publickey for core from 10.0.0.1 port 58370 ssh2: RSA SHA256:yVKhZEHbC7ylZ7bY3Y8pwdh1t/xp6Vz/y3yLFfd9j+Q Dec 13 01:36:37.683694 sshd[1667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:36:37.688088 systemd-logind[1505]: New session 4 of user core. Dec 13 01:36:37.699504 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 13 01:36:37.750651 sshd[1667]: pam_unix(sshd:session): session closed for user core Dec 13 01:36:37.761552 systemd[1]: Started sshd@4-10.0.0.93:22-10.0.0.1:58386.service - OpenSSH per-connection server daemon (10.0.0.1:58386). Dec 13 01:36:37.761898 systemd[1]: sshd@3-10.0.0.93:22-10.0.0.1:58370.service: Deactivated successfully. Dec 13 01:36:37.763535 systemd-logind[1505]: Session 4 logged out. Waiting for processes to exit. Dec 13 01:36:37.764103 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 01:36:37.765714 systemd-logind[1505]: Removed session 4. Dec 13 01:36:37.789096 sshd[1675]: Accepted publickey for core from 10.0.0.1 port 58386 ssh2: RSA SHA256:yVKhZEHbC7ylZ7bY3Y8pwdh1t/xp6Vz/y3yLFfd9j+Q Dec 13 01:36:37.790160 sshd[1675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 13 01:36:37.793468 systemd-logind[1505]: New session 5 of user core. Dec 13 01:36:37.806449 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 13 01:36:37.872296 sudo[1682]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Dec 13 01:36:37.872587 sudo[1682]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 13 01:36:37.894509 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Dec 13 01:36:37.909442 systemd[1]: coreos-metadata.service: Deactivated successfully. Dec 13 01:36:37.909673 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Dec 13 01:36:38.395982 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Dec 13 01:36:38.411456 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 13 01:36:38.427489 systemd[1]: Reloading requested from client PID 1736 ('systemctl') (unit session-5.scope)... Dec 13 01:36:38.427505 systemd[1]: Reloading... Dec 13 01:36:38.479264 zram_generator::config[1771]: No configuration found. Dec 13 01:36:38.598075 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. Dec 13 01:36:38.646056 systemd[1]: Reloading finished in 218 ms. Dec 13 01:36:38.680143 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Dec 13 01:36:38.680206 systemd[1]: kubelet.service: Failed with result 'signal'. Dec 13 01:36:38.680459 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Dec 13 01:36:38.682626 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Dec 13 01:36:38.766382 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Dec 13 01:36:38.770017 (kubelet)[1832]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Dec 13 01:36:38.806264 kubelet[1832]: 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. Dec 13 01:36:38.806264 kubelet[1832]: 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. Dec 13 01:36:38.806264 kubelet[1832]: 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. Dec 13 01:36:38.806551 kubelet[1832]: I1213 01:36:38.806314 1832 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Dec 13 01:36:40.325929 kubelet[1832]: I1213 01:36:40.325873 1832 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Dec 13 01:36:40.325929 kubelet[1832]: I1213 01:36:40.325902 1832 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Dec 13 01:36:40.328284 kubelet[1832]: I1213 01:36:40.328252 1832 server.go:919] "Client rotation is on, will bootstrap in background" Dec 13 01:36:40.351581 kubelet[1832]: I1213 01:36:40.351522 1832 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Dec 13 01:36:40.363091 kubelet[1832]: I1213 01:36:40.363064 1832 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Dec 13 01:36:40.363937 kubelet[1832]: I1213 01:36:40.363859 1832 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Dec 13 01:36:40.364084 kubelet[1832]: I1213 01:36:40.364066 1832 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Dec 13 01:36:40.364159 kubelet[1832]: I1213 01:36:40.364089 1832 topology_manager.go:138] "Creating topology manager with none policy" Dec 13 01:36:40.364159 kubelet[1832]: I1213 01:36:40.364099 1832 container_manager_linux.go:301] "Creating device plugin manager" Dec 13 01:36:40.364256 kubelet[1832]: I1213 01:36:40.364219 1832 state_mem.go:36] "Initialized new in-memory state store" Dec 13 01:36:40.373868 kubelet[1832]: I1213 01:36:40.373829 1832 kubelet.go:396] "Attempting to sync node with API server" Dec 13 01:36:40.373868 kubelet[1832]: I1213 01:36:40.373860 1832 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Dec 13 01:36:40.373978 kubelet[1832]: I1213 01:36:40.373882 1832 kubelet.go:312] "Adding apiserver pod source" Dec 13 01:36:40.373978 kubelet[1832]: I1213 01:36:40.373906 1832 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Dec 13 01:36:40.373978 kubelet[1832]: E1213 01:36:40.373942 1832 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:40.374033 kubelet[1832]: E1213 01:36:40.373993 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:40.381706 kubelet[1832]: I1213 01:36:40.381462 1832 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Dec 13 01:36:40.381979 kubelet[1832]: I1213 01:36:40.381950 1832 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Dec 13 01:36:40.385520 kubelet[1832]: W1213 01:36:40.385490 1832 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Node: nodes "10.0.0.93" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Dec 13 01:36:40.385671 kubelet[1832]: E1213 01:36:40.385526 1832 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.93" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Dec 13 01:36:40.385671 kubelet[1832]: W1213 01:36:40.385508 1832 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Dec 13 01:36:40.385671 kubelet[1832]: E1213 01:36:40.385567 1832 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: 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 Dec 13 01:36:40.385671 kubelet[1832]: W1213 01:36:40.385552 1832 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Dec 13 01:36:40.386781 kubelet[1832]: I1213 01:36:40.386544 1832 server.go:1256] "Started kubelet" Dec 13 01:36:40.387486 kubelet[1832]: I1213 01:36:40.386868 1832 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Dec 13 01:36:40.387486 kubelet[1832]: I1213 01:36:40.387142 1832 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Dec 13 01:36:40.387486 kubelet[1832]: I1213 01:36:40.387188 1832 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Dec 13 01:36:40.389601 kubelet[1832]: I1213 01:36:40.389572 1832 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Dec 13 01:36:40.390054 kubelet[1832]: I1213 01:36:40.390030 1832 server.go:461] "Adding debug handlers to kubelet server" Dec 13 01:36:40.392111 kubelet[1832]: I1213 01:36:40.392076 1832 volume_manager.go:291] "Starting Kubelet Volume Manager" Dec 13 01:36:40.394364 kubelet[1832]: I1213 01:36:40.394339 1832 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Dec 13 01:36:40.394746 kubelet[1832]: I1213 01:36:40.394718 1832 factory.go:221] Registration of the systemd container factory successfully Dec 13 01:36:40.394927 kubelet[1832]: I1213 01:36:40.394905 1832 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 Dec 13 01:36:40.395423 kubelet[1832]: I1213 01:36:40.394913 1832 reconciler_new.go:29] "Reconciler: start to sync state" Dec 13 01:36:40.397552 kubelet[1832]: E1213 01:36:40.397521 1832 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Dec 13 01:36:40.398478 kubelet[1832]: I1213 01:36:40.398461 1832 factory.go:221] Registration of the containerd container factory successfully Dec 13 01:36:40.401900 kubelet[1832]: E1213 01:36:40.401867 1832 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.93\" not found" node="10.0.0.93" Dec 13 01:36:40.418073 kubelet[1832]: I1213 01:36:40.418052 1832 cpu_manager.go:214] "Starting CPU manager" policy="none" Dec 13 01:36:40.418184 kubelet[1832]: I1213 01:36:40.418176 1832 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Dec 13 01:36:40.418250 kubelet[1832]: I1213 01:36:40.418242 1832 state_mem.go:36] "Initialized new in-memory state store" Dec 13 01:36:40.483427 kubelet[1832]: I1213 01:36:40.483379 1832 policy_none.go:49] "None policy: Start" Dec 13 01:36:40.484267 kubelet[1832]: I1213 01:36:40.484241 1832 memory_manager.go:170] "Starting memorymanager" policy="None" Dec 13 01:36:40.484324 kubelet[1832]: I1213 01:36:40.484286 1832 state_mem.go:35] "Initializing new in-memory state store" Dec 13 01:36:40.492256 kubelet[1832]: I1213 01:36:40.491814 1832 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Dec 13 01:36:40.492256 kubelet[1832]: I1213 01:36:40.492077 1832 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Dec 13 01:36:40.493562 kubelet[1832]: I1213 01:36:40.493496 1832 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.93" Dec 13 01:36:40.494264 kubelet[1832]: E1213 01:36:40.494205 1832 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.93\" not found" Dec 13 01:36:40.499884 kubelet[1832]: I1213 01:36:40.499851 1832 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.93" Dec 13 01:36:40.507884 kubelet[1832]: E1213 01:36:40.507834 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:40.516102 kubelet[1832]: I1213 01:36:40.516073 1832 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Dec 13 01:36:40.517342 kubelet[1832]: I1213 01:36:40.517316 1832 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Dec 13 01:36:40.517342 kubelet[1832]: I1213 01:36:40.517349 1832 status_manager.go:217] "Starting to sync pod status with apiserver" Dec 13 01:36:40.517442 kubelet[1832]: I1213 01:36:40.517369 1832 kubelet.go:2329] "Starting kubelet main sync loop" Dec 13 01:36:40.517442 kubelet[1832]: E1213 01:36:40.517419 1832 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Dec 13 01:36:40.608541 kubelet[1832]: E1213 01:36:40.608421 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:40.709102 kubelet[1832]: E1213 01:36:40.709068 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:40.809759 kubelet[1832]: E1213 01:36:40.809712 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:40.910337 kubelet[1832]: E1213 01:36:40.910231 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.010861 kubelet[1832]: E1213 01:36:41.010824 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.111905 kubelet[1832]: E1213 01:36:41.111872 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.212749 kubelet[1832]: E1213 01:36:41.212648 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.292701 sudo[1682]: pam_unix(sudo:session): session closed for user root Dec 13 01:36:41.294526 sshd[1675]: pam_unix(sshd:session): session closed for user core Dec 13 01:36:41.298607 systemd[1]: sshd@4-10.0.0.93:22-10.0.0.1:58386.service: Deactivated successfully. Dec 13 01:36:41.300412 systemd-logind[1505]: Session 5 logged out. Waiting for processes to exit. Dec 13 01:36:41.300484 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 01:36:41.302556 systemd-logind[1505]: Removed session 5. Dec 13 01:36:41.313500 kubelet[1832]: E1213 01:36:41.313454 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.330885 kubelet[1832]: I1213 01:36:41.330851 1832 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Dec 13 01:36:41.331236 kubelet[1832]: W1213 01:36:41.330995 1832 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.Service ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 01:36:41.331236 kubelet[1832]: W1213 01:36:41.331025 1832 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 01:36:41.331236 kubelet[1832]: W1213 01:36:41.331053 1832 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Dec 13 01:36:41.374903 kubelet[1832]: E1213 01:36:41.374863 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:41.413992 kubelet[1832]: E1213 01:36:41.413954 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.515164 kubelet[1832]: E1213 01:36:41.514387 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.614732 kubelet[1832]: E1213 01:36:41.614679 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.715470 kubelet[1832]: E1213 01:36:41.715426 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.815954 kubelet[1832]: E1213 01:36:41.815845 1832 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.93\" not found" Dec 13 01:36:41.917098 kubelet[1832]: I1213 01:36:41.917047 1832 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Dec 13 01:36:41.917416 containerd[1526]: time="2024-12-13T01:36:41.917371599Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Dec 13 01:36:41.918003 kubelet[1832]: I1213 01:36:41.917801 1832 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Dec 13 01:36:42.375543 kubelet[1832]: E1213 01:36:42.375489 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:42.375543 kubelet[1832]: I1213 01:36:42.375509 1832 apiserver.go:52] "Watching apiserver" Dec 13 01:36:42.382560 kubelet[1832]: I1213 01:36:42.382489 1832 topology_manager.go:215] "Topology Admit Handler" podUID="aeb5ba06-09fa-4d1e-a489-0800cb9d2616" podNamespace="kube-flannel" podName="kube-flannel-ds-8t4l6" Dec 13 01:36:42.386242 kubelet[1832]: I1213 01:36:42.386130 1832 topology_manager.go:215] "Topology Admit Handler" podUID="5709f1df-589a-4c26-ab1d-02a05a9d7fde" podNamespace="kube-system" podName="kube-proxy-ttgw7" Dec 13 01:36:42.395663 kubelet[1832]: I1213 01:36:42.395623 1832 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Dec 13 01:36:42.409045 kubelet[1832]: I1213 01:36:42.408930 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-xtables-lock\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409045 kubelet[1832]: I1213 01:36:42.408974 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/5709f1df-589a-4c26-ab1d-02a05a9d7fde-kube-proxy\") pod \"kube-proxy-ttgw7\" (UID: \"5709f1df-589a-4c26-ab1d-02a05a9d7fde\") " pod="kube-system/kube-proxy-ttgw7" Dec 13 01:36:42.409045 kubelet[1832]: I1213 01:36:42.409003 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-run\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409045 kubelet[1832]: I1213 01:36:42.409027 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-cni-plugin\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409045 kubelet[1832]: I1213 01:36:42.409045 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-cni\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409301 kubelet[1832]: I1213 01:36:42.409063 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/5709f1df-589a-4c26-ab1d-02a05a9d7fde-lib-modules\") pod \"kube-proxy-ttgw7\" (UID: \"5709f1df-589a-4c26-ab1d-02a05a9d7fde\") " pod="kube-system/kube-proxy-ttgw7" Dec 13 01:36:42.409301 kubelet[1832]: I1213 01:36:42.409082 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zdtzf\" (UniqueName: \"kubernetes.io/projected/5709f1df-589a-4c26-ab1d-02a05a9d7fde-kube-api-access-zdtzf\") pod \"kube-proxy-ttgw7\" (UID: \"5709f1df-589a-4c26-ab1d-02a05a9d7fde\") " pod="kube-system/kube-proxy-ttgw7" Dec 13 01:36:42.409301 kubelet[1832]: I1213 01:36:42.409102 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-flannel-cfg\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409301 kubelet[1832]: I1213 01:36:42.409121 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rg47n\" (UniqueName: \"kubernetes.io/projected/aeb5ba06-09fa-4d1e-a489-0800cb9d2616-kube-api-access-rg47n\") pod \"kube-flannel-ds-8t4l6\" (UID: \"aeb5ba06-09fa-4d1e-a489-0800cb9d2616\") " pod="kube-flannel/kube-flannel-ds-8t4l6" Dec 13 01:36:42.409301 kubelet[1832]: I1213 01:36:42.409139 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/5709f1df-589a-4c26-ab1d-02a05a9d7fde-xtables-lock\") pod \"kube-proxy-ttgw7\" (UID: \"5709f1df-589a-4c26-ab1d-02a05a9d7fde\") " pod="kube-system/kube-proxy-ttgw7" Dec 13 01:36:42.690587 kubelet[1832]: E1213 01:36:42.690482 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:42.691257 kubelet[1832]: E1213 01:36:42.690807 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:42.691338 containerd[1526]: time="2024-12-13T01:36:42.691285642Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ttgw7,Uid:5709f1df-589a-4c26-ab1d-02a05a9d7fde,Namespace:kube-system,Attempt:0,}" Dec 13 01:36:42.692057 containerd[1526]: time="2024-12-13T01:36:42.691820307Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8t4l6,Uid:aeb5ba06-09fa-4d1e-a489-0800cb9d2616,Namespace:kube-flannel,Attempt:0,}" Dec 13 01:36:43.234425 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2047140814.mount: Deactivated successfully. Dec 13 01:36:43.240346 containerd[1526]: time="2024-12-13T01:36:43.240289216Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 13 01:36:43.241096 containerd[1526]: time="2024-12-13T01:36:43.241073858Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 13 01:36:43.241586 containerd[1526]: time="2024-12-13T01:36:43.241558063Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Dec 13 01:36:43.241992 containerd[1526]: time="2024-12-13T01:36:43.241962071Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Dec 13 01:36:43.242618 containerd[1526]: time="2024-12-13T01:36:43.242589220Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 13 01:36:43.245753 containerd[1526]: time="2024-12-13T01:36:43.245720453Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 554.354984ms" Dec 13 01:36:43.246962 containerd[1526]: time="2024-12-13T01:36:43.246891694Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Dec 13 01:36:43.248154 containerd[1526]: time="2024-12-13T01:36:43.248103234Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 556.19733ms" Dec 13 01:36:43.360813 containerd[1526]: time="2024-12-13T01:36:43.360562899Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:36:43.360813 containerd[1526]: time="2024-12-13T01:36:43.360622059Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:36:43.360813 containerd[1526]: time="2024-12-13T01:36:43.360639227Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:43.361061 containerd[1526]: time="2024-12-13T01:36:43.360938939Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:36:43.362274 containerd[1526]: time="2024-12-13T01:36:43.361374098Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:43.362380 containerd[1526]: time="2024-12-13T01:36:43.361308450Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:36:43.362380 containerd[1526]: time="2024-12-13T01:36:43.361330655Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:43.362380 containerd[1526]: time="2024-12-13T01:36:43.361419194Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:43.376307 kubelet[1832]: E1213 01:36:43.376278 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:43.476371 containerd[1526]: time="2024-12-13T01:36:43.476333454Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8t4l6,Uid:aeb5ba06-09fa-4d1e-a489-0800cb9d2616,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\"" Dec 13 01:36:43.477221 kubelet[1832]: E1213 01:36:43.477192 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:43.478996 containerd[1526]: time="2024-12-13T01:36:43.478779708Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Dec 13 01:36:43.479989 containerd[1526]: time="2024-12-13T01:36:43.479948651Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-ttgw7,Uid:5709f1df-589a-4c26-ab1d-02a05a9d7fde,Namespace:kube-system,Attempt:0,} returns sandbox id \"54bbff06576f035b2d6ae4873799d74228c021a8200efee3765004535465d937\"" Dec 13 01:36:43.480523 kubelet[1832]: E1213 01:36:43.480503 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:44.376504 kubelet[1832]: E1213 01:36:44.376456 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:44.556309 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1522085320.mount: Deactivated successfully. Dec 13 01:36:44.578036 containerd[1526]: time="2024-12-13T01:36:44.577988982Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:44.578720 containerd[1526]: time="2024-12-13T01:36:44.578679041Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Dec 13 01:36:44.580090 containerd[1526]: time="2024-12-13T01:36:44.580065482Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:44.585065 containerd[1526]: time="2024-12-13T01:36:44.584837117Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:44.586242 containerd[1526]: time="2024-12-13T01:36:44.586170290Z" 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.107352146s" Dec 13 01:36:44.586242 containerd[1526]: time="2024-12-13T01:36:44.586205399Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Dec 13 01:36:44.586973 containerd[1526]: time="2024-12-13T01:36:44.586951263Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.12\"" Dec 13 01:36:44.588125 containerd[1526]: time="2024-12-13T01:36:44.588084774Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Dec 13 01:36:44.598854 containerd[1526]: time="2024-12-13T01:36:44.598815789Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed\"" Dec 13 01:36:44.599382 containerd[1526]: time="2024-12-13T01:36:44.599360138Z" level=info msg="StartContainer for \"19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed\"" Dec 13 01:36:44.656676 containerd[1526]: time="2024-12-13T01:36:44.656579895Z" level=info msg="StartContainer for \"19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed\" returns successfully" Dec 13 01:36:44.694391 containerd[1526]: time="2024-12-13T01:36:44.694278439Z" level=info msg="shim disconnected" id=19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed namespace=k8s.io Dec 13 01:36:44.694391 containerd[1526]: time="2024-12-13T01:36:44.694353126Z" level=warning msg="cleaning up after shim disconnected" id=19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed namespace=k8s.io Dec 13 01:36:44.694391 containerd[1526]: time="2024-12-13T01:36:44.694362668Z" level=info msg="cleaning up dead shim" namespace=k8s.io Dec 13 01:36:45.377770 kubelet[1832]: E1213 01:36:45.377468 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:45.528085 kubelet[1832]: E1213 01:36:45.527678 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:45.556438 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-19d764ab586878789b05baa2b0b0483e045da02c67a38d5c8882868bba146bed-rootfs.mount: Deactivated successfully. Dec 13 01:36:45.745787 containerd[1526]: time="2024-12-13T01:36:45.745364396Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:45.746443 containerd[1526]: time="2024-12-13T01:36:45.745996928Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.12: active requests=0, bytes read=25273979" Dec 13 01:36:45.746766 containerd[1526]: time="2024-12-13T01:36:45.746712534Z" level=info msg="ImageCreate event name:\"sha256:768ee8cfd9311233d038d18430c18136e1ae4dd2e6de40fcf1c670bba2da6d06\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:45.748833 containerd[1526]: time="2024-12-13T01:36:45.748781308Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bc761494b78fa152a759457f42bc9b86ee9d18f5929bb127bd5f72f8e2112c39\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:45.749559 containerd[1526]: time="2024-12-13T01:36:45.749393805Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.12\" with image id \"sha256:768ee8cfd9311233d038d18430c18136e1ae4dd2e6de40fcf1c670bba2da6d06\", repo tag \"registry.k8s.io/kube-proxy:v1.29.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:bc761494b78fa152a759457f42bc9b86ee9d18f5929bb127bd5f72f8e2112c39\", size \"25272996\" in 1.162378009s" Dec 13 01:36:45.749559 containerd[1526]: time="2024-12-13T01:36:45.749428644Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.12\" returns image reference \"sha256:768ee8cfd9311233d038d18430c18136e1ae4dd2e6de40fcf1c670bba2da6d06\"" Dec 13 01:36:45.749944 containerd[1526]: time="2024-12-13T01:36:45.749926688Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Dec 13 01:36:45.751176 containerd[1526]: time="2024-12-13T01:36:45.750994385Z" level=info msg="CreateContainer within sandbox \"54bbff06576f035b2d6ae4873799d74228c021a8200efee3765004535465d937\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Dec 13 01:36:45.765204 containerd[1526]: time="2024-12-13T01:36:45.765157019Z" level=info msg="CreateContainer within sandbox \"54bbff06576f035b2d6ae4873799d74228c021a8200efee3765004535465d937\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"91ffc46f3ecbd5f77aa965d1d71899dc7a7d90f7a30acc8ecc05118e717e3215\"" Dec 13 01:36:45.765700 containerd[1526]: time="2024-12-13T01:36:45.765664315Z" level=info msg="StartContainer for \"91ffc46f3ecbd5f77aa965d1d71899dc7a7d90f7a30acc8ecc05118e717e3215\"" Dec 13 01:36:45.812089 containerd[1526]: time="2024-12-13T01:36:45.811613179Z" level=info msg="StartContainer for \"91ffc46f3ecbd5f77aa965d1d71899dc7a7d90f7a30acc8ecc05118e717e3215\" returns successfully" Dec 13 01:36:46.378550 kubelet[1832]: E1213 01:36:46.378498 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:46.530507 kubelet[1832]: E1213 01:36:46.530482 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:46.878474 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3440509275.mount: Deactivated successfully. Dec 13 01:36:47.378982 kubelet[1832]: E1213 01:36:47.378768 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:47.382102 containerd[1526]: time="2024-12-13T01:36:47.382057174Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:47.382922 containerd[1526]: time="2024-12-13T01:36:47.382656960Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Dec 13 01:36:47.383638 containerd[1526]: time="2024-12-13T01:36:47.383572809Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:47.386554 containerd[1526]: time="2024-12-13T01:36:47.386523447Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:47.387879 containerd[1526]: time="2024-12-13T01:36:47.387776292Z" 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.637762598s" Dec 13 01:36:47.387879 containerd[1526]: time="2024-12-13T01:36:47.387816267Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Dec 13 01:36:47.389860 containerd[1526]: time="2024-12-13T01:36:47.389830726Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Dec 13 01:36:47.403391 containerd[1526]: time="2024-12-13T01:36:47.403335728Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"eb2aac85b6346502c14def043eedff02aa172fef64733cedb8268a46f32e1c0d\"" Dec 13 01:36:47.403865 containerd[1526]: time="2024-12-13T01:36:47.403819044Z" level=info msg="StartContainer for \"eb2aac85b6346502c14def043eedff02aa172fef64733cedb8268a46f32e1c0d\"" Dec 13 01:36:47.454245 containerd[1526]: time="2024-12-13T01:36:47.454107920Z" level=info msg="StartContainer for \"eb2aac85b6346502c14def043eedff02aa172fef64733cedb8268a46f32e1c0d\" returns successfully" Dec 13 01:36:47.519442 kubelet[1832]: I1213 01:36:47.519192 1832 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Dec 13 01:36:47.535524 kubelet[1832]: E1213 01:36:47.535457 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:47.541221 kubelet[1832]: E1213 01:36:47.535797 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:47.556678 kubelet[1832]: I1213 01:36:47.556635 1832 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-ttgw7" podStartSLOduration=5.288099461 podStartE2EDuration="7.556577073s" podCreationTimestamp="2024-12-13 01:36:40 +0000 UTC" firstStartedPulling="2024-12-13 01:36:43.481203594 +0000 UTC m=+4.708021823" lastFinishedPulling="2024-12-13 01:36:45.749681207 +0000 UTC m=+6.976499435" observedRunningTime="2024-12-13 01:36:46.548013531 +0000 UTC m=+7.774831759" watchObservedRunningTime="2024-12-13 01:36:47.556577073 +0000 UTC m=+8.783395301" Dec 13 01:36:47.675968 containerd[1526]: time="2024-12-13T01:36:47.675821184Z" level=info msg="shim disconnected" id=eb2aac85b6346502c14def043eedff02aa172fef64733cedb8268a46f32e1c0d namespace=k8s.io Dec 13 01:36:47.675968 containerd[1526]: time="2024-12-13T01:36:47.675883738Z" level=warning msg="cleaning up after shim disconnected" id=eb2aac85b6346502c14def043eedff02aa172fef64733cedb8268a46f32e1c0d namespace=k8s.io Dec 13 01:36:47.675968 containerd[1526]: time="2024-12-13T01:36:47.675895027Z" level=info msg="cleaning up dead shim" namespace=k8s.io Dec 13 01:36:47.688335 containerd[1526]: time="2024-12-13T01:36:47.688181937Z" level=warning msg="cleanup warnings time=\"2024-12-13T01:36:47Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Dec 13 01:36:48.379877 kubelet[1832]: E1213 01:36:48.379801 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:48.539345 kubelet[1832]: E1213 01:36:48.539313 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:48.543172 containerd[1526]: time="2024-12-13T01:36:48.542127553Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Dec 13 01:36:48.562268 containerd[1526]: time="2024-12-13T01:36:48.562210565Z" level=info msg="CreateContainer within sandbox \"f377df88ee5c1fe8f81832e661c1c111b4db2207f39022ccda65e0645ec5e696\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"2c395bb2ddbcd376f5c50728f9fb8d725fface51e96bfae4b0960fb18525ba65\"" Dec 13 01:36:48.563732 containerd[1526]: time="2024-12-13T01:36:48.562829538Z" level=info msg="StartContainer for \"2c395bb2ddbcd376f5c50728f9fb8d725fface51e96bfae4b0960fb18525ba65\"" Dec 13 01:36:48.623025 containerd[1526]: time="2024-12-13T01:36:48.622973692Z" level=info msg="StartContainer for \"2c395bb2ddbcd376f5c50728f9fb8d725fface51e96bfae4b0960fb18525ba65\" returns successfully" Dec 13 01:36:49.380782 kubelet[1832]: E1213 01:36:49.380720 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:49.543316 kubelet[1832]: E1213 01:36:49.542770 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:49.552974 kubelet[1832]: I1213 01:36:49.552754 1832 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-8t4l6" podStartSLOduration=5.642926046 podStartE2EDuration="9.552710085s" podCreationTimestamp="2024-12-13 01:36:40 +0000 UTC" firstStartedPulling="2024-12-13 01:36:43.47824279 +0000 UTC m=+4.705061018" lastFinishedPulling="2024-12-13 01:36:47.388026869 +0000 UTC m=+8.614845057" observedRunningTime="2024-12-13 01:36:49.552625441 +0000 UTC m=+10.779443710" watchObservedRunningTime="2024-12-13 01:36:49.552710085 +0000 UTC m=+10.779528314" Dec 13 01:36:49.694786 systemd-networkd[1225]: flannel.1: Link UP Dec 13 01:36:49.694797 systemd-networkd[1225]: flannel.1: Gained carrier Dec 13 01:36:50.381831 kubelet[1832]: E1213 01:36:50.381766 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:50.543670 kubelet[1832]: E1213 01:36:50.543639 1832 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Dec 13 01:36:51.298427 systemd-networkd[1225]: flannel.1: Gained IPv6LL Dec 13 01:36:51.382086 kubelet[1832]: E1213 01:36:51.382042 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:52.162538 kubelet[1832]: I1213 01:36:52.162481 1832 topology_manager.go:215] "Topology Admit Handler" podUID="3fcfa63b-da73-4402-ada9-8172cab74f75" podNamespace="default" podName="nginx-deployment-6d5f899847-lrz8k" Dec 13 01:36:52.276702 kubelet[1832]: I1213 01:36:52.276666 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xfz5r\" (UniqueName: \"kubernetes.io/projected/3fcfa63b-da73-4402-ada9-8172cab74f75-kube-api-access-xfz5r\") pod \"nginx-deployment-6d5f899847-lrz8k\" (UID: \"3fcfa63b-da73-4402-ada9-8172cab74f75\") " pod="default/nginx-deployment-6d5f899847-lrz8k" Dec 13 01:36:52.382570 kubelet[1832]: E1213 01:36:52.382535 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:52.466301 containerd[1526]: time="2024-12-13T01:36:52.466158325Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-lrz8k,Uid:3fcfa63b-da73-4402-ada9-8172cab74f75,Namespace:default,Attempt:0,}" Dec 13 01:36:52.501650 systemd-networkd[1225]: cni0: Link UP Dec 13 01:36:52.501657 systemd-networkd[1225]: cni0: Gained carrier Dec 13 01:36:52.501889 systemd-networkd[1225]: cni0: Lost carrier Dec 13 01:36:52.509762 systemd-networkd[1225]: veth1362f6a3: Link UP Dec 13 01:36:52.510622 kernel: cni0: port 1(veth1362f6a3) entered blocking state Dec 13 01:36:52.510664 kernel: cni0: port 1(veth1362f6a3) entered disabled state Dec 13 01:36:52.510681 kernel: veth1362f6a3: entered allmulticast mode Dec 13 01:36:52.511645 kernel: veth1362f6a3: entered promiscuous mode Dec 13 01:36:52.511694 kernel: cni0: port 1(veth1362f6a3) entered blocking state Dec 13 01:36:52.512512 kernel: cni0: port 1(veth1362f6a3) entered forwarding state Dec 13 01:36:52.513303 kernel: cni0: port 1(veth1362f6a3) entered disabled state Dec 13 01:36:52.523486 kernel: cni0: port 1(veth1362f6a3) entered blocking state Dec 13 01:36:52.523559 kernel: cni0: port 1(veth1362f6a3) entered forwarding state Dec 13 01:36:52.523470 systemd-networkd[1225]: veth1362f6a3: Gained carrier Dec 13 01:36:52.523689 systemd-networkd[1225]: cni0: Gained carrier Dec 13 01:36:52.524955 containerd[1526]: 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"} Dec 13 01:36:52.524955 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Dec 13 01:36:52.545685 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T01:36:52.545544361Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:36:52.545685 containerd[1526]: time="2024-12-13T01:36:52.545587418Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:36:52.545685 containerd[1526]: time="2024-12-13T01:36:52.545598203Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:52.545886 containerd[1526]: time="2024-12-13T01:36:52.545663830Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:52.569198 systemd-resolved[1434]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 01:36:52.585740 containerd[1526]: time="2024-12-13T01:36:52.585674543Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-lrz8k,Uid:3fcfa63b-da73-4402-ada9-8172cab74f75,Namespace:default,Attempt:0,} returns sandbox id \"1c55d773d021f474ae2b7ef2ab49c256b03ce2a74e2b62e0dafe54d13c015b41\"" Dec 13 01:36:52.587381 containerd[1526]: time="2024-12-13T01:36:52.587305894Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 13 01:36:53.383184 kubelet[1832]: E1213 01:36:53.383138 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:53.858388 systemd-networkd[1225]: cni0: Gained IPv6LL Dec 13 01:36:53.986324 systemd-networkd[1225]: veth1362f6a3: Gained IPv6LL Dec 13 01:36:54.384365 kubelet[1832]: E1213 01:36:54.384302 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:54.650339 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3711843995.mount: Deactivated successfully. Dec 13 01:36:55.246246 containerd[1526]: time="2024-12-13T01:36:55.245905274Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:55.248208 containerd[1526]: time="2024-12-13T01:36:55.248129069Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67696939" Dec 13 01:36:55.251269 containerd[1526]: time="2024-12-13T01:36:55.251159160Z" level=info msg="ImageCreate event name:\"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:55.327911 containerd[1526]: time="2024-12-13T01:36:55.327621231Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:e04edf30a4ea4c5a4107110797c72d3ee8a654415f00acd4019be17218afd9a1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:36:55.329152 containerd[1526]: time="2024-12-13T01:36:55.329107833Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e04edf30a4ea4c5a4107110797c72d3ee8a654415f00acd4019be17218afd9a1\", size \"67696817\" in 2.741764583s" Dec 13 01:36:55.329152 containerd[1526]: time="2024-12-13T01:36:55.329146732Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\"" Dec 13 01:36:55.330661 containerd[1526]: time="2024-12-13T01:36:55.330617711Z" level=info msg="CreateContainer within sandbox \"1c55d773d021f474ae2b7ef2ab49c256b03ce2a74e2b62e0dafe54d13c015b41\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Dec 13 01:36:55.371845 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1017928826.mount: Deactivated successfully. Dec 13 01:36:55.372468 containerd[1526]: time="2024-12-13T01:36:55.372417450Z" level=info msg="CreateContainer within sandbox \"1c55d773d021f474ae2b7ef2ab49c256b03ce2a74e2b62e0dafe54d13c015b41\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"edb1d4dd243665f7f41bff7085b0690b78d80f8bdda572682bf25efd95ec9828\"" Dec 13 01:36:55.373107 containerd[1526]: time="2024-12-13T01:36:55.373075122Z" level=info msg="StartContainer for \"edb1d4dd243665f7f41bff7085b0690b78d80f8bdda572682bf25efd95ec9828\"" Dec 13 01:36:55.384861 kubelet[1832]: E1213 01:36:55.384830 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:55.418780 containerd[1526]: time="2024-12-13T01:36:55.418734003Z" level=info msg="StartContainer for \"edb1d4dd243665f7f41bff7085b0690b78d80f8bdda572682bf25efd95ec9828\" returns successfully" Dec 13 01:36:56.385741 kubelet[1832]: E1213 01:36:56.385678 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:57.386859 kubelet[1832]: E1213 01:36:57.386811 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:58.387647 kubelet[1832]: E1213 01:36:58.387598 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:36:58.969804 kubelet[1832]: I1213 01:36:58.969758 1832 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-lrz8k" podStartSLOduration=4.227102494 podStartE2EDuration="6.969704231s" podCreationTimestamp="2024-12-13 01:36:52 +0000 UTC" firstStartedPulling="2024-12-13 01:36:52.586778267 +0000 UTC m=+13.813596495" lastFinishedPulling="2024-12-13 01:36:55.329380004 +0000 UTC m=+16.556198232" observedRunningTime="2024-12-13 01:36:55.564723442 +0000 UTC m=+16.791541670" watchObservedRunningTime="2024-12-13 01:36:58.969704231 +0000 UTC m=+20.196522459" Dec 13 01:36:58.969960 kubelet[1832]: I1213 01:36:58.969887 1832 topology_manager.go:215] "Topology Admit Handler" podUID="555c891c-0a8b-4011-bd0e-396490506f07" podNamespace="default" podName="nfs-server-provisioner-0" Dec 13 01:36:59.015427 kubelet[1832]: I1213 01:36:59.015319 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pgt2q\" (UniqueName: \"kubernetes.io/projected/555c891c-0a8b-4011-bd0e-396490506f07-kube-api-access-pgt2q\") pod \"nfs-server-provisioner-0\" (UID: \"555c891c-0a8b-4011-bd0e-396490506f07\") " pod="default/nfs-server-provisioner-0" Dec 13 01:36:59.015427 kubelet[1832]: I1213 01:36:59.015371 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/555c891c-0a8b-4011-bd0e-396490506f07-data\") pod \"nfs-server-provisioner-0\" (UID: \"555c891c-0a8b-4011-bd0e-396490506f07\") " pod="default/nfs-server-provisioner-0" Dec 13 01:36:59.272719 containerd[1526]: time="2024-12-13T01:36:59.272616326Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:555c891c-0a8b-4011-bd0e-396490506f07,Namespace:default,Attempt:0,}" Dec 13 01:36:59.288075 systemd-networkd[1225]: veth5f56ac5f: Link UP Dec 13 01:36:59.289352 kernel: cni0: port 2(veth5f56ac5f) entered blocking state Dec 13 01:36:59.289442 kernel: cni0: port 2(veth5f56ac5f) entered disabled state Dec 13 01:36:59.289464 kernel: veth5f56ac5f: entered allmulticast mode Dec 13 01:36:59.290226 kernel: veth5f56ac5f: entered promiscuous mode Dec 13 01:36:59.293631 kernel: cni0: port 2(veth5f56ac5f) entered blocking state Dec 13 01:36:59.293692 kernel: cni0: port 2(veth5f56ac5f) entered forwarding state Dec 13 01:36:59.293698 systemd-networkd[1225]: veth5f56ac5f: Gained carrier Dec 13 01:36:59.295511 containerd[1526]: 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"} Dec 13 01:36:59.295511 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Dec 13 01:36:59.310722 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T01:36:59.310613629Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:36:59.311327 containerd[1526]: time="2024-12-13T01:36:59.311138093Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:36:59.311327 containerd[1526]: time="2024-12-13T01:36:59.311157630Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:59.311327 containerd[1526]: time="2024-12-13T01:36:59.311264485Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:36:59.331109 systemd-resolved[1434]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 01:36:59.348797 containerd[1526]: time="2024-12-13T01:36:59.348759062Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:555c891c-0a8b-4011-bd0e-396490506f07,Namespace:default,Attempt:0,} returns sandbox id \"52bd4735ff6acd0571e6bc1a9796a4116afde1b14917b995771e3b7d71947dc0\"" Dec 13 01:36:59.350337 containerd[1526]: time="2024-12-13T01:36:59.350297664Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Dec 13 01:36:59.388622 kubelet[1832]: E1213 01:36:59.388575 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:00.126746 systemd[1]: run-containerd-runc-k8s.io-52bd4735ff6acd0571e6bc1a9796a4116afde1b14917b995771e3b7d71947dc0-runc.Pqk0PH.mount: Deactivated successfully. Dec 13 01:37:00.375397 kubelet[1832]: E1213 01:37:00.374644 1832 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:00.386397 systemd-networkd[1225]: veth5f56ac5f: Gained IPv6LL Dec 13 01:37:00.388753 kubelet[1832]: E1213 01:37:00.388712 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:01.021718 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1303133457.mount: Deactivated successfully. Dec 13 01:37:01.389424 kubelet[1832]: E1213 01:37:01.389303 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:02.282739 containerd[1526]: time="2024-12-13T01:37:02.282671356Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:37:02.283236 containerd[1526]: time="2024-12-13T01:37:02.283188463Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Dec 13 01:37:02.284107 containerd[1526]: time="2024-12-13T01:37:02.284072667Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:37:02.287242 containerd[1526]: time="2024-12-13T01:37:02.287164420Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:37:02.288441 containerd[1526]: time="2024-12-13T01:37:02.288403595Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 2.938050366s" Dec 13 01:37:02.288509 containerd[1526]: time="2024-12-13T01:37:02.288441858Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Dec 13 01:37:02.290475 containerd[1526]: time="2024-12-13T01:37:02.290432838Z" level=info msg="CreateContainer within sandbox \"52bd4735ff6acd0571e6bc1a9796a4116afde1b14917b995771e3b7d71947dc0\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Dec 13 01:37:02.302704 containerd[1526]: time="2024-12-13T01:37:02.302647841Z" level=info msg="CreateContainer within sandbox \"52bd4735ff6acd0571e6bc1a9796a4116afde1b14917b995771e3b7d71947dc0\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"54a036d6dc6b444d89cb4030bbde299fe824df149cd2ecfc5a926fd64a98b6ee\"" Dec 13 01:37:02.303254 containerd[1526]: time="2024-12-13T01:37:02.303162346Z" level=info msg="StartContainer for \"54a036d6dc6b444d89cb4030bbde299fe824df149cd2ecfc5a926fd64a98b6ee\"" Dec 13 01:37:02.349287 containerd[1526]: time="2024-12-13T01:37:02.349205568Z" level=info msg="StartContainer for \"54a036d6dc6b444d89cb4030bbde299fe824df149cd2ecfc5a926fd64a98b6ee\" returns successfully" Dec 13 01:37:02.390483 kubelet[1832]: E1213 01:37:02.390427 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:03.390772 kubelet[1832]: E1213 01:37:03.390724 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:04.391641 kubelet[1832]: E1213 01:37:04.391599 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:05.392566 kubelet[1832]: E1213 01:37:05.392514 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:06.393221 kubelet[1832]: E1213 01:37:06.393175 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:07.394073 kubelet[1832]: E1213 01:37:07.394024 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:08.394606 kubelet[1832]: E1213 01:37:08.394563 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:09.394912 kubelet[1832]: E1213 01:37:09.394867 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:10.395252 kubelet[1832]: E1213 01:37:10.395200 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:11.395416 kubelet[1832]: E1213 01:37:11.395365 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:12.396228 kubelet[1832]: E1213 01:37:12.396186 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:12.622077 kubelet[1832]: I1213 01:37:12.622041 1832 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.682966045 podStartE2EDuration="14.62199929s" podCreationTimestamp="2024-12-13 01:36:58 +0000 UTC" firstStartedPulling="2024-12-13 01:36:59.349737608 +0000 UTC m=+20.576555836" lastFinishedPulling="2024-12-13 01:37:02.288770853 +0000 UTC m=+23.515589081" observedRunningTime="2024-12-13 01:37:02.576492181 +0000 UTC m=+23.803310409" watchObservedRunningTime="2024-12-13 01:37:12.62199929 +0000 UTC m=+33.848817519" Dec 13 01:37:12.622318 kubelet[1832]: I1213 01:37:12.622299 1832 topology_manager.go:215] "Topology Admit Handler" podUID="bef88b42-41d3-4466-8657-c2abdaf34345" podNamespace="default" podName="test-pod-1" Dec 13 01:37:12.682620 kubelet[1832]: I1213 01:37:12.682458 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5zb8m\" (UniqueName: \"kubernetes.io/projected/bef88b42-41d3-4466-8657-c2abdaf34345-kube-api-access-5zb8m\") pod \"test-pod-1\" (UID: \"bef88b42-41d3-4466-8657-c2abdaf34345\") " pod="default/test-pod-1" Dec 13 01:37:12.682620 kubelet[1832]: I1213 01:37:12.682511 1832 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-e1b611da-87ae-4686-a2b2-441c5b9fcf17\" (UniqueName: \"kubernetes.io/nfs/bef88b42-41d3-4466-8657-c2abdaf34345-pvc-e1b611da-87ae-4686-a2b2-441c5b9fcf17\") pod \"test-pod-1\" (UID: \"bef88b42-41d3-4466-8657-c2abdaf34345\") " pod="default/test-pod-1" Dec 13 01:37:12.805340 kernel: FS-Cache: Loaded Dec 13 01:37:12.832385 kernel: RPC: Registered named UNIX socket transport module. Dec 13 01:37:12.832457 kernel: RPC: Registered udp transport module. Dec 13 01:37:12.832475 kernel: RPC: Registered tcp transport module. Dec 13 01:37:12.832491 kernel: RPC: Registered tcp-with-tls transport module. Dec 13 01:37:12.833379 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 13 01:37:13.013284 kernel: NFS: Registering the id_resolver key type Dec 13 01:37:13.013387 kernel: Key type id_resolver registered Dec 13 01:37:13.013407 kernel: Key type id_legacy registered Dec 13 01:37:13.042097 nfsidmap[2851]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Dec 13 01:37:13.045889 nfsidmap[2854]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Dec 13 01:37:13.225961 containerd[1526]: time="2024-12-13T01:37:13.225905022Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:bef88b42-41d3-4466-8657-c2abdaf34345,Namespace:default,Attempt:0,}" Dec 13 01:37:13.242426 kernel: cni0: port 3(vetha442a163) entered blocking state Dec 13 01:37:13.242521 kernel: cni0: port 3(vetha442a163) entered disabled state Dec 13 01:37:13.242543 kernel: vetha442a163: entered allmulticast mode Dec 13 01:37:13.241250 systemd-networkd[1225]: vetha442a163: Link UP Dec 13 01:37:13.243302 kernel: vetha442a163: entered promiscuous mode Dec 13 01:37:13.247238 kernel: cni0: port 3(vetha442a163) entered blocking state Dec 13 01:37:13.247451 kernel: cni0: port 3(vetha442a163) entered forwarding state Dec 13 01:37:13.247294 systemd-networkd[1225]: vetha442a163: Gained carrier Dec 13 01:37:13.248873 containerd[1526]: 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"} Dec 13 01:37:13.248873 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Dec 13 01:37:13.265571 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2024-12-13T01:37:13.265465234Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Dec 13 01:37:13.265571 containerd[1526]: time="2024-12-13T01:37:13.265519536Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Dec 13 01:37:13.265571 containerd[1526]: time="2024-12-13T01:37:13.265531140Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:37:13.265774 containerd[1526]: time="2024-12-13T01:37:13.265628579Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Dec 13 01:37:13.284973 systemd-resolved[1434]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Dec 13 01:37:13.302703 containerd[1526]: time="2024-12-13T01:37:13.302594989Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:bef88b42-41d3-4466-8657-c2abdaf34345,Namespace:default,Attempt:0,} returns sandbox id \"379e6ca7d4821ffa07129ce8d09089d6ca71cfaf90035d2716852ebb350088b1\"" Dec 13 01:37:13.304154 containerd[1526]: time="2024-12-13T01:37:13.304117161Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Dec 13 01:37:13.396788 kubelet[1832]: E1213 01:37:13.396727 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:13.562680 containerd[1526]: time="2024-12-13T01:37:13.562542132Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Dec 13 01:37:13.563538 containerd[1526]: time="2024-12-13T01:37:13.563483110Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Dec 13 01:37:13.566546 containerd[1526]: time="2024-12-13T01:37:13.566412967Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e04edf30a4ea4c5a4107110797c72d3ee8a654415f00acd4019be17218afd9a1\", size \"67696817\" in 262.25411ms" Dec 13 01:37:13.566546 containerd[1526]: time="2024-12-13T01:37:13.566453463Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d5cb91e7550dca840aad69277b6dbccf8dc3739757998181746daf777a8bd9de\"" Dec 13 01:37:13.568394 containerd[1526]: time="2024-12-13T01:37:13.568275715Z" level=info msg="CreateContainer within sandbox \"379e6ca7d4821ffa07129ce8d09089d6ca71cfaf90035d2716852ebb350088b1\" for container &ContainerMetadata{Name:test,Attempt:0,}" Dec 13 01:37:13.578234 containerd[1526]: time="2024-12-13T01:37:13.578167489Z" level=info msg="CreateContainer within sandbox \"379e6ca7d4821ffa07129ce8d09089d6ca71cfaf90035d2716852ebb350088b1\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8a7f3357bf68ba895e6225dd4a6100b14efce6c9f941bc827fc0329c7bb9b6fa\"" Dec 13 01:37:13.578831 containerd[1526]: time="2024-12-13T01:37:13.578801664Z" level=info msg="StartContainer for \"8a7f3357bf68ba895e6225dd4a6100b14efce6c9f941bc827fc0329c7bb9b6fa\"" Dec 13 01:37:13.628631 containerd[1526]: time="2024-12-13T01:37:13.628590464Z" level=info msg="StartContainer for \"8a7f3357bf68ba895e6225dd4a6100b14efce6c9f941bc827fc0329c7bb9b6fa\" returns successfully" Dec 13 01:37:14.397646 kubelet[1832]: E1213 01:37:14.397592 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:14.599759 kubelet[1832]: I1213 01:37:14.599713 1832 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.336702817 podStartE2EDuration="15.599670494s" podCreationTimestamp="2024-12-13 01:36:59 +0000 UTC" firstStartedPulling="2024-12-13 01:37:13.303844731 +0000 UTC m=+34.530662919" lastFinishedPulling="2024-12-13 01:37:13.566812368 +0000 UTC m=+34.793630596" observedRunningTime="2024-12-13 01:37:14.599600548 +0000 UTC m=+35.826418776" watchObservedRunningTime="2024-12-13 01:37:14.599670494 +0000 UTC m=+35.826488682" Dec 13 01:37:15.298593 systemd-networkd[1225]: vetha442a163: Gained IPv6LL Dec 13 01:37:15.398330 kubelet[1832]: E1213 01:37:15.398280 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Dec 13 01:37:16.146409 update_engine[1513]: I20241213 01:37:16.146322 1513 update_attempter.cc:509] Updating boot flags... Dec 13 01:37:16.170236 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 46 scanned by (udev-worker) (2891) Dec 13 01:37:16.188267 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 46 scanned by (udev-worker) (2891) Dec 13 01:37:16.399789 kubelet[1832]: E1213 01:37:16.399660 1832 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"