Aug 13 07:09:48.221886 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Aug 13 07:09:48.221929 kernel: Linux version 6.6.100-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Tue Aug 12 21:42:02 -00 2025 Aug 13 07:09:48.221954 kernel: KASLR disabled due to lack of seed Aug 13 07:09:48.221970 kernel: efi: EFI v2.7 by EDK II Aug 13 07:09:48.221986 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a731a98 MEMRESERVE=0x78557598 Aug 13 07:09:48.222001 kernel: secureboot: Secure boot disabled Aug 13 07:09:48.222019 kernel: ACPI: Early table checksum verification disabled Aug 13 07:09:48.222034 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Aug 13 07:09:48.222050 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Aug 13 07:09:48.222065 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Aug 13 07:09:48.222085 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Aug 13 07:09:48.222101 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Aug 13 07:09:48.222117 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Aug 13 07:09:48.222133 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Aug 13 07:09:48.222151 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Aug 13 07:09:48.222171 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Aug 13 07:09:48.222188 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Aug 13 07:09:48.222204 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Aug 13 07:09:48.222220 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Aug 13 07:09:48.222237 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Aug 13 07:09:48.222253 kernel: printk: bootconsole [uart0] enabled Aug 13 07:09:48.222269 kernel: NUMA: Failed to initialise from firmware Aug 13 07:09:48.222286 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Aug 13 07:09:48.222302 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Aug 13 07:09:48.222318 kernel: Zone ranges: Aug 13 07:09:48.222334 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Aug 13 07:09:48.222355 kernel: DMA32 empty Aug 13 07:09:48.222371 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Aug 13 07:09:48.222387 kernel: Movable zone start for each node Aug 13 07:09:48.222425 kernel: Early memory node ranges Aug 13 07:09:48.222444 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Aug 13 07:09:48.222461 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Aug 13 07:09:48.222477 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Aug 13 07:09:48.222493 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Aug 13 07:09:48.222510 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Aug 13 07:09:48.222526 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Aug 13 07:09:48.222542 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Aug 13 07:09:48.222558 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Aug 13 07:09:48.222580 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Aug 13 07:09:48.222597 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Aug 13 07:09:48.222620 kernel: psci: probing for conduit method from ACPI. Aug 13 07:09:48.222638 kernel: psci: PSCIv1.0 detected in firmware. Aug 13 07:09:48.222655 kernel: psci: Using standard PSCI v0.2 function IDs Aug 13 07:09:48.222676 kernel: psci: Trusted OS migration not required Aug 13 07:09:48.222693 kernel: psci: SMC Calling Convention v1.1 Aug 13 07:09:48.222710 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Aug 13 07:09:48.222728 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Aug 13 07:09:48.222745 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Aug 13 07:09:48.222762 kernel: pcpu-alloc: [0] 0 [0] 1 Aug 13 07:09:48.222779 kernel: Detected PIPT I-cache on CPU0 Aug 13 07:09:48.222796 kernel: CPU features: detected: GIC system register CPU interface Aug 13 07:09:48.222813 kernel: CPU features: detected: Spectre-v2 Aug 13 07:09:48.222830 kernel: CPU features: detected: Spectre-v3a Aug 13 07:09:48.222847 kernel: CPU features: detected: Spectre-BHB Aug 13 07:09:48.222868 kernel: CPU features: detected: ARM erratum 1742098 Aug 13 07:09:48.222885 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Aug 13 07:09:48.222902 kernel: alternatives: applying boot alternatives Aug 13 07:09:48.222921 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=c44ba8b4c0c81c1bcadc13a1606b9de202ee4e4226c47e1c865eaa5fc436b169 Aug 13 07:09:48.222939 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 13 07:09:48.222957 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 13 07:09:48.222974 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 13 07:09:48.222991 kernel: Fallback order for Node 0: 0 Aug 13 07:09:48.223008 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Aug 13 07:09:48.223025 kernel: Policy zone: Normal Aug 13 07:09:48.223041 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 13 07:09:48.223063 kernel: software IO TLB: area num 2. Aug 13 07:09:48.223080 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Aug 13 07:09:48.223097 kernel: Memory: 3821112K/4030464K available (10368K kernel code, 2186K rwdata, 8104K rodata, 38400K init, 897K bss, 209352K reserved, 0K cma-reserved) Aug 13 07:09:48.223115 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 13 07:09:48.223132 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 13 07:09:48.223170 kernel: rcu: RCU event tracing is enabled. Aug 13 07:09:48.223189 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Aug 13 07:09:48.223207 kernel: Trampoline variant of Tasks RCU enabled. Aug 13 07:09:48.223224 kernel: Tracing variant of Tasks RCU enabled. Aug 13 07:09:48.223242 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 13 07:09:48.223259 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 13 07:09:48.226436 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 13 07:09:48.226463 kernel: GICv3: 96 SPIs implemented Aug 13 07:09:48.226480 kernel: GICv3: 0 Extended SPIs implemented Aug 13 07:09:48.226497 kernel: Root IRQ handler: gic_handle_irq Aug 13 07:09:48.226514 kernel: GICv3: GICv3 features: 16 PPIs Aug 13 07:09:48.226531 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Aug 13 07:09:48.226548 kernel: ITS [mem 0x10080000-0x1009ffff] Aug 13 07:09:48.226565 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Aug 13 07:09:48.226582 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Aug 13 07:09:48.226600 kernel: GICv3: using LPI property table @0x00000004000d0000 Aug 13 07:09:48.226617 kernel: ITS: Using hypervisor restricted LPI range [128] Aug 13 07:09:48.226634 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Aug 13 07:09:48.226659 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 13 07:09:48.226676 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Aug 13 07:09:48.226693 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Aug 13 07:09:48.226711 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Aug 13 07:09:48.226728 kernel: Console: colour dummy device 80x25 Aug 13 07:09:48.226745 kernel: printk: console [tty1] enabled Aug 13 07:09:48.226763 kernel: ACPI: Core revision 20230628 Aug 13 07:09:48.226781 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Aug 13 07:09:48.226798 kernel: pid_max: default: 32768 minimum: 301 Aug 13 07:09:48.226816 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Aug 13 07:09:48.226838 kernel: landlock: Up and running. Aug 13 07:09:48.226855 kernel: SELinux: Initializing. Aug 13 07:09:48.226873 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 07:09:48.226890 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 07:09:48.226908 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Aug 13 07:09:48.226926 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Aug 13 07:09:48.226943 kernel: rcu: Hierarchical SRCU implementation. Aug 13 07:09:48.226961 kernel: rcu: Max phase no-delay instances is 400. Aug 13 07:09:48.226979 kernel: Platform MSI: ITS@0x10080000 domain created Aug 13 07:09:48.227000 kernel: PCI/MSI: ITS@0x10080000 domain created Aug 13 07:09:48.227018 kernel: Remapping and enabling EFI services. Aug 13 07:09:48.227035 kernel: smp: Bringing up secondary CPUs ... Aug 13 07:09:48.227052 kernel: Detected PIPT I-cache on CPU1 Aug 13 07:09:48.227070 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Aug 13 07:09:48.227087 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Aug 13 07:09:48.227105 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Aug 13 07:09:48.227122 kernel: smp: Brought up 1 node, 2 CPUs Aug 13 07:09:48.227153 kernel: SMP: Total of 2 processors activated. Aug 13 07:09:48.227180 kernel: CPU features: detected: 32-bit EL0 Support Aug 13 07:09:48.227198 kernel: CPU features: detected: 32-bit EL1 Support Aug 13 07:09:48.227227 kernel: CPU features: detected: CRC32 instructions Aug 13 07:09:48.227249 kernel: CPU: All CPU(s) started at EL1 Aug 13 07:09:48.227268 kernel: alternatives: applying system-wide alternatives Aug 13 07:09:48.227286 kernel: devtmpfs: initialized Aug 13 07:09:48.227304 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 13 07:09:48.227322 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 13 07:09:48.227341 kernel: pinctrl core: initialized pinctrl subsystem Aug 13 07:09:48.227363 kernel: SMBIOS 3.0.0 present. Aug 13 07:09:48.227381 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Aug 13 07:09:48.227417 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 13 07:09:48.227439 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 13 07:09:48.227458 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 13 07:09:48.227477 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 13 07:09:48.227495 kernel: audit: initializing netlink subsys (disabled) Aug 13 07:09:48.227519 kernel: audit: type=2000 audit(0.220:1): state=initialized audit_enabled=0 res=1 Aug 13 07:09:48.227538 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 13 07:09:48.227556 kernel: cpuidle: using governor menu Aug 13 07:09:48.227575 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 13 07:09:48.227594 kernel: ASID allocator initialised with 65536 entries Aug 13 07:09:48.227612 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 13 07:09:48.227631 kernel: Serial: AMBA PL011 UART driver Aug 13 07:09:48.227649 kernel: Modules: 17728 pages in range for non-PLT usage Aug 13 07:09:48.227667 kernel: Modules: 509248 pages in range for PLT usage Aug 13 07:09:48.227690 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 13 07:09:48.227708 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 13 07:09:48.227726 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 13 07:09:48.227744 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 13 07:09:48.227762 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 13 07:09:48.227780 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 13 07:09:48.227798 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 13 07:09:48.227817 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 13 07:09:48.227835 kernel: ACPI: Added _OSI(Module Device) Aug 13 07:09:48.227856 kernel: ACPI: Added _OSI(Processor Device) Aug 13 07:09:48.227875 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 13 07:09:48.227893 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 13 07:09:48.227911 kernel: ACPI: Interpreter enabled Aug 13 07:09:48.227929 kernel: ACPI: Using GIC for interrupt routing Aug 13 07:09:48.227947 kernel: ACPI: MCFG table detected, 1 entries Aug 13 07:09:48.227965 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Aug 13 07:09:48.228266 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 13 07:09:48.229563 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 13 07:09:48.229789 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 13 07:09:48.229994 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Aug 13 07:09:48.230196 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Aug 13 07:09:48.230221 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Aug 13 07:09:48.230240 kernel: acpiphp: Slot [1] registered Aug 13 07:09:48.230259 kernel: acpiphp: Slot [2] registered Aug 13 07:09:48.230277 kernel: acpiphp: Slot [3] registered Aug 13 07:09:48.230296 kernel: acpiphp: Slot [4] registered Aug 13 07:09:48.230325 kernel: acpiphp: Slot [5] registered Aug 13 07:09:48.230343 kernel: acpiphp: Slot [6] registered Aug 13 07:09:48.230361 kernel: acpiphp: Slot [7] registered Aug 13 07:09:48.230379 kernel: acpiphp: Slot [8] registered Aug 13 07:09:48.230420 kernel: acpiphp: Slot [9] registered Aug 13 07:09:48.230444 kernel: acpiphp: Slot [10] registered Aug 13 07:09:48.230463 kernel: acpiphp: Slot [11] registered Aug 13 07:09:48.230482 kernel: acpiphp: Slot [12] registered Aug 13 07:09:48.230500 kernel: acpiphp: Slot [13] registered Aug 13 07:09:48.230525 kernel: acpiphp: Slot [14] registered Aug 13 07:09:48.230543 kernel: acpiphp: Slot [15] registered Aug 13 07:09:48.230584 kernel: acpiphp: Slot [16] registered Aug 13 07:09:48.230624 kernel: acpiphp: Slot [17] registered Aug 13 07:09:48.230664 kernel: acpiphp: Slot [18] registered Aug 13 07:09:48.230685 kernel: acpiphp: Slot [19] registered Aug 13 07:09:48.230703 kernel: acpiphp: Slot [20] registered Aug 13 07:09:48.230722 kernel: acpiphp: Slot [21] registered Aug 13 07:09:48.230740 kernel: acpiphp: Slot [22] registered Aug 13 07:09:48.230758 kernel: acpiphp: Slot [23] registered Aug 13 07:09:48.230783 kernel: acpiphp: Slot [24] registered Aug 13 07:09:48.230802 kernel: acpiphp: Slot [25] registered Aug 13 07:09:48.230820 kernel: acpiphp: Slot [26] registered Aug 13 07:09:48.230838 kernel: acpiphp: Slot [27] registered Aug 13 07:09:48.230856 kernel: acpiphp: Slot [28] registered Aug 13 07:09:48.230874 kernel: acpiphp: Slot [29] registered Aug 13 07:09:48.230891 kernel: acpiphp: Slot [30] registered Aug 13 07:09:48.230909 kernel: acpiphp: Slot [31] registered Aug 13 07:09:48.230927 kernel: PCI host bridge to bus 0000:00 Aug 13 07:09:48.231185 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Aug 13 07:09:48.231380 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 13 07:09:48.234575 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Aug 13 07:09:48.234828 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Aug 13 07:09:48.235071 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Aug 13 07:09:48.235335 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Aug 13 07:09:48.241246 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Aug 13 07:09:48.241524 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Aug 13 07:09:48.241738 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Aug 13 07:09:48.242120 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Aug 13 07:09:48.242343 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Aug 13 07:09:48.243008 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Aug 13 07:09:48.243232 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Aug 13 07:09:48.243494 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Aug 13 07:09:48.243724 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Aug 13 07:09:48.243924 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Aug 13 07:09:48.244156 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Aug 13 07:09:48.244367 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Aug 13 07:09:48.255528 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Aug 13 07:09:48.255774 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Aug 13 07:09:48.255975 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Aug 13 07:09:48.256163 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 13 07:09:48.256352 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Aug 13 07:09:48.256379 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 13 07:09:48.256429 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 13 07:09:48.256454 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 13 07:09:48.256476 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 13 07:09:48.256496 kernel: iommu: Default domain type: Translated Aug 13 07:09:48.256525 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 13 07:09:48.256545 kernel: efivars: Registered efivars operations Aug 13 07:09:48.256565 kernel: vgaarb: loaded Aug 13 07:09:48.256586 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 13 07:09:48.256606 kernel: VFS: Disk quotas dquot_6.6.0 Aug 13 07:09:48.256625 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 13 07:09:48.256645 kernel: pnp: PnP ACPI init Aug 13 07:09:48.256899 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Aug 13 07:09:48.256933 kernel: pnp: PnP ACPI: found 1 devices Aug 13 07:09:48.256952 kernel: NET: Registered PF_INET protocol family Aug 13 07:09:48.256971 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 13 07:09:48.256990 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 13 07:09:48.257009 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 13 07:09:48.257028 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 13 07:09:48.257046 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 13 07:09:48.257065 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 13 07:09:48.257084 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 07:09:48.257107 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 07:09:48.257126 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 13 07:09:48.257145 kernel: PCI: CLS 0 bytes, default 64 Aug 13 07:09:48.257163 kernel: kvm [1]: HYP mode not available Aug 13 07:09:48.257181 kernel: Initialise system trusted keyrings Aug 13 07:09:48.257200 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 13 07:09:48.257219 kernel: Key type asymmetric registered Aug 13 07:09:48.257237 kernel: Asymmetric key parser 'x509' registered Aug 13 07:09:48.257255 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 13 07:09:48.257278 kernel: io scheduler mq-deadline registered Aug 13 07:09:48.257296 kernel: io scheduler kyber registered Aug 13 07:09:48.257314 kernel: io scheduler bfq registered Aug 13 07:09:48.257613 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Aug 13 07:09:48.257645 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 13 07:09:48.257664 kernel: ACPI: button: Power Button [PWRB] Aug 13 07:09:48.257683 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Aug 13 07:09:48.257702 kernel: ACPI: button: Sleep Button [SLPB] Aug 13 07:09:48.257721 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 13 07:09:48.257748 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Aug 13 07:09:48.257967 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Aug 13 07:09:48.257994 kernel: printk: console [ttyS0] disabled Aug 13 07:09:48.258013 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Aug 13 07:09:48.258032 kernel: printk: console [ttyS0] enabled Aug 13 07:09:48.258050 kernel: printk: bootconsole [uart0] disabled Aug 13 07:09:48.258068 kernel: thunder_xcv, ver 1.0 Aug 13 07:09:48.258087 kernel: thunder_bgx, ver 1.0 Aug 13 07:09:48.258105 kernel: nicpf, ver 1.0 Aug 13 07:09:48.258130 kernel: nicvf, ver 1.0 Aug 13 07:09:48.258366 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 13 07:09:48.258592 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-08-13T07:09:47 UTC (1755068987) Aug 13 07:09:48.258619 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 13 07:09:48.258638 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Aug 13 07:09:48.258657 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 13 07:09:48.258676 kernel: watchdog: Hard watchdog permanently disabled Aug 13 07:09:48.258694 kernel: NET: Registered PF_INET6 protocol family Aug 13 07:09:48.258719 kernel: Segment Routing with IPv6 Aug 13 07:09:48.258738 kernel: In-situ OAM (IOAM) with IPv6 Aug 13 07:09:48.258756 kernel: NET: Registered PF_PACKET protocol family Aug 13 07:09:48.258774 kernel: Key type dns_resolver registered Aug 13 07:09:48.258792 kernel: registered taskstats version 1 Aug 13 07:09:48.258810 kernel: Loading compiled-in X.509 certificates Aug 13 07:09:48.258829 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.100-flatcar: b805f03ae64b71ea1aa3cf76d07ec816116f6d0c' Aug 13 07:09:48.258847 kernel: Key type .fscrypt registered Aug 13 07:09:48.258865 kernel: Key type fscrypt-provisioning registered Aug 13 07:09:48.258888 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 13 07:09:48.258907 kernel: ima: Allocated hash algorithm: sha1 Aug 13 07:09:48.258926 kernel: ima: No architecture policies found Aug 13 07:09:48.258944 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 13 07:09:48.258963 kernel: clk: Disabling unused clocks Aug 13 07:09:48.258981 kernel: Freeing unused kernel memory: 38400K Aug 13 07:09:48.259000 kernel: Run /init as init process Aug 13 07:09:48.259018 kernel: with arguments: Aug 13 07:09:48.259036 kernel: /init Aug 13 07:09:48.259058 kernel: with environment: Aug 13 07:09:48.259077 kernel: HOME=/ Aug 13 07:09:48.259095 kernel: TERM=linux Aug 13 07:09:48.259113 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 13 07:09:48.259133 systemd[1]: Successfully made /usr/ read-only. Aug 13 07:09:48.259182 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Aug 13 07:09:48.259205 systemd[1]: Detected virtualization amazon. Aug 13 07:09:48.259230 systemd[1]: Detected architecture arm64. Aug 13 07:09:48.259251 systemd[1]: Running in initrd. Aug 13 07:09:48.259270 systemd[1]: No hostname configured, using default hostname. Aug 13 07:09:48.259291 systemd[1]: Hostname set to . Aug 13 07:09:48.259385 systemd[1]: Initializing machine ID from VM UUID. Aug 13 07:09:48.259494 systemd[1]: Queued start job for default target initrd.target. Aug 13 07:09:48.259517 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 07:09:48.259539 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 07:09:48.259561 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 13 07:09:48.259591 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 07:09:48.259612 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 13 07:09:48.259634 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 13 07:09:48.259657 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 13 07:09:48.259678 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 13 07:09:48.259698 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 07:09:48.259723 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 07:09:48.259743 systemd[1]: Reached target paths.target - Path Units. Aug 13 07:09:48.259763 systemd[1]: Reached target slices.target - Slice Units. Aug 13 07:09:48.259783 systemd[1]: Reached target swap.target - Swaps. Aug 13 07:09:48.259803 systemd[1]: Reached target timers.target - Timer Units. Aug 13 07:09:48.259823 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 07:09:48.259843 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 07:09:48.259863 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 13 07:09:48.259883 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 13 07:09:48.259907 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 07:09:48.259928 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 07:09:48.259948 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 07:09:48.259968 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 07:09:48.259988 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 13 07:09:48.260008 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 07:09:48.260027 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 13 07:09:48.260047 systemd[1]: Starting systemd-fsck-usr.service... Aug 13 07:09:48.260067 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 07:09:48.260092 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 07:09:48.260112 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 07:09:48.260132 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 13 07:09:48.260152 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 07:09:48.260173 systemd[1]: Finished systemd-fsck-usr.service. Aug 13 07:09:48.260240 systemd-journald[250]: Collecting audit messages is disabled. Aug 13 07:09:48.260284 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 07:09:48.260305 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 13 07:09:48.260329 kernel: Bridge firewalling registered Aug 13 07:09:48.260349 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 07:09:48.260370 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 07:09:48.260390 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 07:09:48.261477 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 07:09:48.261501 systemd-journald[250]: Journal started Aug 13 07:09:48.261546 systemd-journald[250]: Runtime Journal (/run/log/journal/ec25dd48f12096c2da8dafe6741c1689) is 8M, max 75.3M, 67.3M free. Aug 13 07:09:48.189036 systemd-modules-load[251]: Inserted module 'overlay' Aug 13 07:09:48.233514 systemd-modules-load[251]: Inserted module 'br_netfilter' Aug 13 07:09:48.284166 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 07:09:48.284236 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 07:09:48.294895 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 07:09:48.320700 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 07:09:48.330472 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 07:09:48.338956 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 07:09:48.342461 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 07:09:48.360836 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 13 07:09:48.367747 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 07:09:48.395666 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 07:09:48.405816 dracut-cmdline[287]: dracut-dracut-053 Aug 13 07:09:48.405816 dracut-cmdline[287]: 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=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=c44ba8b4c0c81c1bcadc13a1606b9de202ee4e4226c47e1c865eaa5fc436b169 Aug 13 07:09:48.500966 systemd-resolved[294]: Positive Trust Anchors: Aug 13 07:09:48.500995 systemd-resolved[294]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 07:09:48.501056 systemd-resolved[294]: 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 Aug 13 07:09:48.576315 kernel: SCSI subsystem initialized Aug 13 07:09:48.576358 kernel: Loading iSCSI transport class v2.0-870. Aug 13 07:09:48.576383 kernel: iscsi: registered transport (tcp) Aug 13 07:09:48.579937 kernel: iscsi: registered transport (qla4xxx) Aug 13 07:09:48.580011 kernel: QLogic iSCSI HBA Driver Aug 13 07:09:48.704698 kernel: random: crng init done Aug 13 07:09:48.705013 systemd-resolved[294]: Defaulting to hostname 'linux'. Aug 13 07:09:48.712610 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 07:09:48.722796 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 07:09:48.733435 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 13 07:09:48.748459 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 13 07:09:48.782147 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 13 07:09:48.782230 kernel: device-mapper: uevent: version 1.0.3 Aug 13 07:09:48.784200 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 13 07:09:48.849445 kernel: raid6: neonx8 gen() 6653 MB/s Aug 13 07:09:48.866431 kernel: raid6: neonx4 gen() 6625 MB/s Aug 13 07:09:48.883432 kernel: raid6: neonx2 gen() 5491 MB/s Aug 13 07:09:48.900431 kernel: raid6: neonx1 gen() 3965 MB/s Aug 13 07:09:48.917430 kernel: raid6: int64x8 gen() 3643 MB/s Aug 13 07:09:48.934430 kernel: raid6: int64x4 gen() 3730 MB/s Aug 13 07:09:48.951431 kernel: raid6: int64x2 gen() 3621 MB/s Aug 13 07:09:48.969365 kernel: raid6: int64x1 gen() 2764 MB/s Aug 13 07:09:48.969417 kernel: raid6: using algorithm neonx8 gen() 6653 MB/s Aug 13 07:09:48.987353 kernel: raid6: .... xor() 4666 MB/s, rmw enabled Aug 13 07:09:48.987393 kernel: raid6: using neon recovery algorithm Aug 13 07:09:48.995761 kernel: xor: measuring software checksum speed Aug 13 07:09:48.995823 kernel: 8regs : 12936 MB/sec Aug 13 07:09:48.996912 kernel: 32regs : 13037 MB/sec Aug 13 07:09:48.998195 kernel: arm64_neon : 9580 MB/sec Aug 13 07:09:48.998227 kernel: xor: using function: 32regs (13037 MB/sec) Aug 13 07:09:49.083451 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 13 07:09:49.101752 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 13 07:09:49.113725 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 07:09:49.158391 systemd-udevd[472]: Using default interface naming scheme 'v255'. Aug 13 07:09:49.171671 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 07:09:49.185627 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 13 07:09:49.214081 dracut-pre-trigger[478]: rd.md=0: removing MD RAID activation Aug 13 07:09:49.269280 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 07:09:49.281704 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 07:09:49.407281 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 07:09:49.427648 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 13 07:09:49.481284 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 13 07:09:49.492142 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 07:09:49.496841 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 07:09:49.499923 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 07:09:49.516896 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 13 07:09:49.560377 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 13 07:09:49.624184 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 13 07:09:49.624250 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Aug 13 07:09:49.633834 kernel: ena 0000:00:05.0: ENA device version: 0.10 Aug 13 07:09:49.634177 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Aug 13 07:09:49.645436 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:e0:74:31:9c:73 Aug 13 07:09:49.649734 (udev-worker)[525]: Network interface NamePolicy= disabled on kernel command line. Aug 13 07:09:49.649937 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 07:09:49.650163 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 07:09:49.679093 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 07:09:49.690330 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Aug 13 07:09:49.690368 kernel: nvme nvme0: pci function 0000:00:04.0 Aug 13 07:09:49.683529 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 07:09:49.683832 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 07:09:49.696327 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 07:09:49.706438 kernel: nvme nvme0: 2/0/0 default/read/poll queues Aug 13 07:09:49.710913 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 07:09:49.715226 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 13 07:09:49.722919 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 13 07:09:49.722968 kernel: GPT:9289727 != 16777215 Aug 13 07:09:49.722994 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 13 07:09:49.726443 kernel: GPT:9289727 != 16777215 Aug 13 07:09:49.728497 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 13 07:09:49.730443 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 13 07:09:49.742153 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 07:09:49.755684 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 07:09:49.788737 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 07:09:49.824499 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by (udev-worker) (518) Aug 13 07:09:49.863387 kernel: BTRFS: device fsid 66ef7c2c-768e-46b2-8baa-a2b24df44a90 devid 1 transid 42 /dev/nvme0n1p3 scanned by (udev-worker) (517) Aug 13 07:09:49.962067 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Aug 13 07:09:50.005081 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Aug 13 07:09:50.029363 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Aug 13 07:09:50.053357 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Aug 13 07:09:50.056258 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Aug 13 07:09:50.072729 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 13 07:09:50.082781 disk-uuid[661]: Primary Header is updated. Aug 13 07:09:50.082781 disk-uuid[661]: Secondary Entries is updated. Aug 13 07:09:50.082781 disk-uuid[661]: Secondary Header is updated. Aug 13 07:09:50.098454 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 13 07:09:50.119441 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 13 07:09:51.129427 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 13 07:09:51.133809 disk-uuid[662]: The operation has completed successfully. Aug 13 07:09:51.322659 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 13 07:09:51.322885 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 13 07:09:51.409652 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 13 07:09:51.420372 sh[921]: Success Aug 13 07:09:51.446081 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 13 07:09:51.536776 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 13 07:09:51.555622 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 13 07:09:51.564782 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 13 07:09:51.589877 kernel: BTRFS info (device dm-0): first mount of filesystem 66ef7c2c-768e-46b2-8baa-a2b24df44a90 Aug 13 07:09:51.589942 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 13 07:09:51.591920 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 13 07:09:51.591956 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 13 07:09:51.594419 kernel: BTRFS info (device dm-0): using free space tree Aug 13 07:09:51.687445 kernel: BTRFS info (device dm-0): enabling ssd optimizations Aug 13 07:09:51.700943 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 13 07:09:51.707939 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 13 07:09:51.720636 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 13 07:09:51.725606 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 13 07:09:51.769296 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 5832a3b0-f866-4304-b935-a4d38424b8f9 Aug 13 07:09:51.769380 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 13 07:09:51.770682 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 13 07:09:51.777454 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 13 07:09:51.784476 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 5832a3b0-f866-4304-b935-a4d38424b8f9 Aug 13 07:09:51.789105 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 13 07:09:51.801285 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 13 07:09:51.912914 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 07:09:51.925695 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 07:09:52.001379 systemd-networkd[1111]: lo: Link UP Aug 13 07:09:52.001425 systemd-networkd[1111]: lo: Gained carrier Aug 13 07:09:52.010069 systemd-networkd[1111]: Enumeration completed Aug 13 07:09:52.010745 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 07:09:52.011015 systemd-networkd[1111]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 07:09:52.011023 systemd-networkd[1111]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 07:09:52.014609 systemd[1]: Reached target network.target - Network. Aug 13 07:09:52.035312 systemd-networkd[1111]: eth0: Link UP Aug 13 07:09:52.035325 systemd-networkd[1111]: eth0: Gained carrier Aug 13 07:09:52.035343 systemd-networkd[1111]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 07:09:52.057480 systemd-networkd[1111]: eth0: DHCPv4 address 172.31.18.206/20, gateway 172.31.16.1 acquired from 172.31.16.1 Aug 13 07:09:52.216496 ignition[1019]: Ignition 2.20.0 Aug 13 07:09:52.216525 ignition[1019]: Stage: fetch-offline Aug 13 07:09:52.220654 ignition[1019]: no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:52.220693 ignition[1019]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:52.226739 ignition[1019]: Ignition finished successfully Aug 13 07:09:52.228481 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 07:09:52.242775 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 13 07:09:52.270343 ignition[1123]: Ignition 2.20.0 Aug 13 07:09:52.270366 ignition[1123]: Stage: fetch Aug 13 07:09:52.271076 ignition[1123]: no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:52.271103 ignition[1123]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:52.271787 ignition[1123]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:52.298222 ignition[1123]: PUT result: OK Aug 13 07:09:52.309006 ignition[1123]: parsed url from cmdline: "" Aug 13 07:09:52.309144 ignition[1123]: no config URL provided Aug 13 07:09:52.309164 ignition[1123]: reading system config file "/usr/lib/ignition/user.ign" Aug 13 07:09:52.309215 ignition[1123]: no config at "/usr/lib/ignition/user.ign" Aug 13 07:09:52.309249 ignition[1123]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:52.320631 ignition[1123]: PUT result: OK Aug 13 07:09:52.320933 ignition[1123]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Aug 13 07:09:52.325989 ignition[1123]: GET result: OK Aug 13 07:09:52.326325 ignition[1123]: parsing config with SHA512: 7bd0b3df17b5950cbef934665f997a9c2cddbf62b626021f73896c58344c4f0610fecc5c25dfabc6fcb9b768344e0f15322477602093b587bc62976256105838 Aug 13 07:09:52.335630 unknown[1123]: fetched base config from "system" Aug 13 07:09:52.335972 unknown[1123]: fetched base config from "system" Aug 13 07:09:52.336647 ignition[1123]: fetch: fetch complete Aug 13 07:09:52.335986 unknown[1123]: fetched user config from "aws" Aug 13 07:09:52.336659 ignition[1123]: fetch: fetch passed Aug 13 07:09:52.336750 ignition[1123]: Ignition finished successfully Aug 13 07:09:52.351464 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 13 07:09:52.366659 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 13 07:09:52.401369 ignition[1130]: Ignition 2.20.0 Aug 13 07:09:52.401390 ignition[1130]: Stage: kargs Aug 13 07:09:52.402009 ignition[1130]: no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:52.402036 ignition[1130]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:52.402182 ignition[1130]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:52.407448 ignition[1130]: PUT result: OK Aug 13 07:09:52.418750 ignition[1130]: kargs: kargs passed Aug 13 07:09:52.418907 ignition[1130]: Ignition finished successfully Aug 13 07:09:52.423991 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 13 07:09:52.434745 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 13 07:09:52.463548 ignition[1136]: Ignition 2.20.0 Aug 13 07:09:52.463570 ignition[1136]: Stage: disks Aug 13 07:09:52.464119 ignition[1136]: no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:52.464144 ignition[1136]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:52.464285 ignition[1136]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:52.476477 ignition[1136]: PUT result: OK Aug 13 07:09:52.481421 ignition[1136]: disks: disks passed Aug 13 07:09:52.481516 ignition[1136]: Ignition finished successfully Aug 13 07:09:52.486579 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 13 07:09:52.492558 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 13 07:09:52.495948 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 13 07:09:52.505061 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 07:09:52.507732 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 07:09:52.514182 systemd[1]: Reached target basic.target - Basic System. Aug 13 07:09:52.527763 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 13 07:09:52.569673 systemd-fsck[1145]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 13 07:09:52.577227 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 13 07:09:52.590632 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 13 07:09:52.685462 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 4e885a6c-f4f3-43a5-b152-e0e8bd6b099d r/w with ordered data mode. Quota mode: none. Aug 13 07:09:52.687743 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 13 07:09:52.695909 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 13 07:09:52.713579 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 07:09:52.725697 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 13 07:09:52.728479 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 13 07:09:52.728567 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 13 07:09:52.747384 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by mount (1164) Aug 13 07:09:52.728648 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 07:09:52.760263 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 5832a3b0-f866-4304-b935-a4d38424b8f9 Aug 13 07:09:52.760330 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 13 07:09:52.760358 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 13 07:09:52.766451 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 13 07:09:52.772842 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 07:09:52.778200 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 13 07:09:52.792753 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 13 07:09:53.239487 initrd-setup-root[1188]: cut: /sysroot/etc/passwd: No such file or directory Aug 13 07:09:53.250560 initrd-setup-root[1195]: cut: /sysroot/etc/group: No such file or directory Aug 13 07:09:53.266633 initrd-setup-root[1202]: cut: /sysroot/etc/shadow: No such file or directory Aug 13 07:09:53.277331 initrd-setup-root[1209]: cut: /sysroot/etc/gshadow: No such file or directory Aug 13 07:09:53.455689 systemd-networkd[1111]: eth0: Gained IPv6LL Aug 13 07:09:53.642579 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 13 07:09:53.652785 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 13 07:09:53.657995 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 13 07:09:53.676803 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 13 07:09:53.685719 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 5832a3b0-f866-4304-b935-a4d38424b8f9 Aug 13 07:09:53.718752 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 13 07:09:53.729814 ignition[1278]: INFO : Ignition 2.20.0 Aug 13 07:09:53.729814 ignition[1278]: INFO : Stage: mount Aug 13 07:09:53.734586 ignition[1278]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:53.734586 ignition[1278]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:53.734586 ignition[1278]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:53.743174 ignition[1278]: INFO : PUT result: OK Aug 13 07:09:53.748075 ignition[1278]: INFO : mount: mount passed Aug 13 07:09:53.750992 ignition[1278]: INFO : Ignition finished successfully Aug 13 07:09:53.750625 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 13 07:09:53.762615 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 13 07:09:53.794831 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 07:09:53.821436 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1288) Aug 13 07:09:53.825762 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 5832a3b0-f866-4304-b935-a4d38424b8f9 Aug 13 07:09:53.825809 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 13 07:09:53.827086 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 13 07:09:53.832433 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 13 07:09:53.835885 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 07:09:53.873161 ignition[1305]: INFO : Ignition 2.20.0 Aug 13 07:09:53.875337 ignition[1305]: INFO : Stage: files Aug 13 07:09:53.875337 ignition[1305]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:53.875337 ignition[1305]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:53.875337 ignition[1305]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:53.886687 ignition[1305]: INFO : PUT result: OK Aug 13 07:09:53.892240 ignition[1305]: DEBUG : files: compiled without relabeling support, skipping Aug 13 07:09:53.897309 ignition[1305]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 13 07:09:53.897309 ignition[1305]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 13 07:09:53.929865 ignition[1305]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 13 07:09:53.935469 ignition[1305]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 13 07:09:53.940081 unknown[1305]: wrote ssh authorized keys file for user: core Aug 13 07:09:53.943043 ignition[1305]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 13 07:09:53.947448 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/opt/helm-v3.13.2-linux-arm64.tar.gz" Aug 13 07:09:53.953597 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): GET https://get.helm.sh/helm-v3.13.2-linux-arm64.tar.gz: attempt #1 Aug 13 07:09:54.021213 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): GET result: OK Aug 13 07:09:54.333177 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/opt/helm-v3.13.2-linux-arm64.tar.gz" Aug 13 07:09:54.333177 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/home/core/nginx.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/home/core/nginx.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/home/core/nfs-pod.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/home/core/nfs-pod.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/home/core/nfs-pvc.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/home/core/nfs-pvc.yaml" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(8): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(8): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(9): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(9): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(a): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Aug 13 07:09:54.344970 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(a): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Aug 13 07:09:54.684794 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(a): GET result: OK Aug 13 07:09:55.060503 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(a): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Aug 13 07:09:55.060503 ignition[1305]: INFO : files: op(b): [started] processing unit "prepare-helm.service" Aug 13 07:09:55.083844 ignition[1305]: INFO : files: op(b): op(c): [started] writing unit "prepare-helm.service" at "/sysroot/etc/systemd/system/prepare-helm.service" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: op(b): op(c): [finished] writing unit "prepare-helm.service" at "/sysroot/etc/systemd/system/prepare-helm.service" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: op(b): [finished] processing unit "prepare-helm.service" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: op(d): [started] setting preset to enabled for "prepare-helm.service" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: op(d): [finished] setting preset to enabled for "prepare-helm.service" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 13 07:09:55.088316 ignition[1305]: INFO : files: files passed Aug 13 07:09:55.088316 ignition[1305]: INFO : Ignition finished successfully Aug 13 07:09:55.102988 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 13 07:09:55.134314 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 13 07:09:55.142949 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 13 07:09:55.153162 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 13 07:09:55.155885 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 13 07:09:55.188582 initrd-setup-root-after-ignition[1334]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 07:09:55.188582 initrd-setup-root-after-ignition[1334]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 13 07:09:55.198527 initrd-setup-root-after-ignition[1338]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 07:09:55.204958 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 07:09:55.208178 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 13 07:09:55.220863 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 13 07:09:55.284225 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 13 07:09:55.286569 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 13 07:09:55.293063 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 13 07:09:55.295799 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 13 07:09:55.298215 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 13 07:09:55.312777 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 13 07:09:55.341206 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 07:09:55.354812 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 13 07:09:55.380025 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 13 07:09:55.380383 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 07:09:55.381204 systemd[1]: Stopped target timers.target - Timer Units. Aug 13 07:09:55.381562 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 13 07:09:55.381791 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 07:09:55.383044 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 13 07:09:55.383489 systemd[1]: Stopped target basic.target - Basic System. Aug 13 07:09:55.383842 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 13 07:09:55.384218 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 07:09:55.384972 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 13 07:09:55.385372 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 13 07:09:55.385765 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 07:09:55.386154 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 13 07:09:55.386553 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 13 07:09:55.386897 systemd[1]: Stopped target swap.target - Swaps. Aug 13 07:09:55.387291 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 13 07:09:55.387520 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 13 07:09:55.388941 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 13 07:09:55.389776 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 07:09:55.390509 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 13 07:09:55.435912 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 07:09:55.439472 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 13 07:09:55.439702 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 13 07:09:55.490370 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 13 07:09:55.490673 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 07:09:55.496312 systemd[1]: ignition-files.service: Deactivated successfully. Aug 13 07:09:55.496565 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 13 07:09:55.515790 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 13 07:09:55.518199 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 13 07:09:55.518643 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 07:09:55.535827 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 13 07:09:55.541630 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 13 07:09:55.546652 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 07:09:55.551058 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 13 07:09:55.551330 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 07:09:55.572692 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 13 07:09:55.572887 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 13 07:09:55.595036 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 13 07:09:55.599667 ignition[1359]: INFO : Ignition 2.20.0 Aug 13 07:09:55.599667 ignition[1359]: INFO : Stage: umount Aug 13 07:09:55.604570 ignition[1359]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 07:09:55.604570 ignition[1359]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 13 07:09:55.604570 ignition[1359]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 13 07:09:55.615965 ignition[1359]: INFO : PUT result: OK Aug 13 07:09:55.620918 ignition[1359]: INFO : umount: umount passed Aug 13 07:09:55.620553 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 13 07:09:55.630240 ignition[1359]: INFO : Ignition finished successfully Aug 13 07:09:55.620784 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 13 07:09:55.636980 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 13 07:09:55.637353 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 13 07:09:55.644370 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 13 07:09:55.644609 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 13 07:09:55.651573 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 13 07:09:55.651655 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 13 07:09:55.654266 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 13 07:09:55.654343 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 13 07:09:55.656880 systemd[1]: Stopped target network.target - Network. Aug 13 07:09:55.659181 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 13 07:09:55.659268 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 07:09:55.664557 systemd[1]: Stopped target paths.target - Path Units. Aug 13 07:09:55.666866 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 13 07:09:55.671178 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 07:09:55.674006 systemd[1]: Stopped target slices.target - Slice Units. Aug 13 07:09:55.676588 systemd[1]: Stopped target sockets.target - Socket Units. Aug 13 07:09:55.704964 systemd[1]: iscsid.socket: Deactivated successfully. Aug 13 07:09:55.705048 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 07:09:55.707445 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 13 07:09:55.707514 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 07:09:55.709917 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 13 07:09:55.710003 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 13 07:09:55.712721 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 13 07:09:55.712797 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 13 07:09:55.715533 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 13 07:09:55.715610 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 13 07:09:55.718496 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 13 07:09:55.721285 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 13 07:09:55.752077 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 13 07:09:55.752318 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 13 07:09:55.767169 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 13 07:09:55.771031 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 13 07:09:55.771159 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 07:09:55.783042 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 13 07:09:55.787361 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 13 07:09:55.787613 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 13 07:09:55.798167 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 13 07:09:55.799019 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 13 07:09:55.799138 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 13 07:09:55.815647 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 13 07:09:55.817829 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 13 07:09:55.817963 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 07:09:55.821789 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 13 07:09:55.821893 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 13 07:09:55.827900 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 13 07:09:55.827989 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 13 07:09:55.840189 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 07:09:55.842320 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 13 07:09:55.876266 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 13 07:09:55.876652 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 07:09:55.889232 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 13 07:09:55.889336 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 13 07:09:55.892343 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 13 07:09:55.892426 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 07:09:55.895264 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 13 07:09:55.895351 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 13 07:09:55.900802 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 13 07:09:55.900912 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 13 07:09:55.904745 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 07:09:55.904856 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 07:09:55.930168 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 13 07:09:55.932899 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 13 07:09:55.933035 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 07:09:55.940185 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 07:09:55.942551 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 07:09:55.963472 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 13 07:09:55.964308 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 13 07:09:55.976996 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 13 07:09:55.978449 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 13 07:09:55.983024 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 13 07:09:55.995772 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 13 07:09:56.010318 systemd[1]: Switching root. Aug 13 07:09:56.062457 systemd-journald[250]: Journal stopped Aug 13 07:09:58.780267 systemd-journald[250]: Received SIGTERM from PID 1 (systemd). Aug 13 07:09:58.780446 kernel: SELinux: policy capability network_peer_controls=1 Aug 13 07:09:58.780501 kernel: SELinux: policy capability open_perms=1 Aug 13 07:09:58.780534 kernel: SELinux: policy capability extended_socket_class=1 Aug 13 07:09:58.780567 kernel: SELinux: policy capability always_check_network=0 Aug 13 07:09:58.783587 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 13 07:09:58.783652 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 13 07:09:58.783688 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 13 07:09:58.783740 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 13 07:09:58.783792 kernel: audit: type=1403 audit(1755068996.475:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 13 07:09:58.783865 systemd[1]: Successfully loaded SELinux policy in 95.613ms. Aug 13 07:09:58.783932 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 24.183ms. Aug 13 07:09:58.783967 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Aug 13 07:09:58.783999 systemd[1]: Detected virtualization amazon. Aug 13 07:09:58.784033 systemd[1]: Detected architecture arm64. Aug 13 07:09:58.784066 systemd[1]: Detected first boot. Aug 13 07:09:58.784099 systemd[1]: Initializing machine ID from VM UUID. Aug 13 07:09:58.784130 zram_generator::config[1403]: No configuration found. Aug 13 07:09:58.784171 kernel: NET: Registered PF_VSOCK protocol family Aug 13 07:09:58.784201 systemd[1]: Populated /etc with preset unit settings. Aug 13 07:09:58.784234 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 13 07:09:58.784265 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 13 07:09:58.784305 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 13 07:09:58.784334 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 13 07:09:58.784365 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 13 07:09:58.784450 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 13 07:09:58.784501 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 13 07:09:58.784542 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 13 07:09:58.784598 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 13 07:09:58.784647 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 13 07:09:58.784700 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 13 07:09:58.784760 systemd[1]: Created slice user.slice - User and Session Slice. Aug 13 07:09:58.784810 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 07:09:58.784857 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 07:09:58.784898 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 13 07:09:58.784943 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 13 07:09:58.784999 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 13 07:09:58.785046 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 07:09:58.785103 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 13 07:09:58.785158 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 07:09:58.785218 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 13 07:09:58.785264 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 13 07:09:58.785314 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 13 07:09:58.785363 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 13 07:09:58.785437 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 07:09:58.785493 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 07:09:58.785550 systemd[1]: Reached target slices.target - Slice Units. Aug 13 07:09:58.785602 systemd[1]: Reached target swap.target - Swaps. Aug 13 07:09:58.785647 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 13 07:09:58.785693 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 13 07:09:58.785740 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 13 07:09:58.785795 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 07:09:58.785852 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 07:09:58.785899 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 07:09:58.785951 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 13 07:09:58.785999 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 13 07:09:58.786035 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 13 07:09:58.786092 systemd[1]: Mounting media.mount - External Media Directory... Aug 13 07:09:58.786139 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 13 07:09:58.786194 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 13 07:09:58.786241 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 13 07:09:58.786306 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). Aug 13 07:09:58.786354 systemd[1]: Reached target machines.target - Containers. Aug 13 07:09:58.786412 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 13 07:09:58.786469 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 07:09:58.786528 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 07:09:58.786575 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 13 07:09:58.786627 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 07:09:58.786677 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 07:09:58.786759 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 07:09:58.786803 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 13 07:09:58.786851 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 07:09:58.786922 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 13 07:09:58.786976 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 13 07:09:58.787032 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 13 07:09:58.787082 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 13 07:09:58.787116 systemd[1]: Stopped systemd-fsck-usr.service. Aug 13 07:09:58.787147 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Aug 13 07:09:58.787180 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 07:09:58.787209 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 07:09:58.787237 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 07:09:58.787268 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 13 07:09:58.787302 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 13 07:09:58.787331 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 07:09:58.787362 systemd[1]: verity-setup.service: Deactivated successfully. Aug 13 07:09:58.787440 systemd[1]: Stopped verity-setup.service. Aug 13 07:09:58.787501 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 13 07:09:58.787542 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 13 07:09:58.787575 systemd[1]: Mounted media.mount - External Media Directory. Aug 13 07:09:58.787604 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 13 07:09:58.787633 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 13 07:09:58.787662 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 13 07:09:58.787693 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 07:09:58.787722 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 13 07:09:58.787751 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 13 07:09:58.787782 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 07:09:58.787819 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 07:09:58.787851 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 07:09:58.787880 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 07:09:58.787908 kernel: fuse: init (API version 7.39) Aug 13 07:09:58.787939 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 13 07:09:58.787974 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 07:09:58.788003 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 13 07:09:58.788032 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 13 07:09:58.788060 kernel: loop: module loaded Aug 13 07:09:58.788090 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 13 07:09:58.788121 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 13 07:09:58.788151 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 07:09:58.788181 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 07:09:58.788210 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 13 07:09:58.788243 kernel: ACPI: bus type drm_connector registered Aug 13 07:09:58.788283 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 13 07:09:58.788314 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 13 07:09:58.788344 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 07:09:58.788373 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 13 07:09:58.790551 systemd-journald[1482]: Collecting audit messages is disabled. Aug 13 07:09:58.790645 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 13 07:09:58.790700 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 13 07:09:58.790746 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 07:09:58.790788 systemd-journald[1482]: Journal started Aug 13 07:09:58.790854 systemd-journald[1482]: Runtime Journal (/run/log/journal/ec25dd48f12096c2da8dafe6741c1689) is 8M, max 75.3M, 67.3M free. Aug 13 07:09:57.985179 systemd[1]: Queued start job for default target multi-user.target. Aug 13 07:09:58.000098 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Aug 13 07:09:58.000965 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 13 07:09:58.816600 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 13 07:09:58.816680 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 07:09:58.828825 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 13 07:09:58.828917 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 07:09:58.846459 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 07:09:58.871141 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 13 07:09:58.893214 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 13 07:09:58.893351 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 07:09:58.907270 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 07:09:58.909229 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 07:09:58.913455 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 07:09:58.923655 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 13 07:09:58.929813 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 13 07:09:58.934242 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 13 07:09:58.939034 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 13 07:09:59.015878 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 13 07:09:59.026624 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 07:09:59.054001 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 13 07:09:59.060884 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 13 07:09:59.070458 kernel: loop0: detected capacity change from 0 to 203944 Aug 13 07:09:59.114369 systemd-journald[1482]: Time spent on flushing to /var/log/journal/ec25dd48f12096c2da8dafe6741c1689 is 32.044ms for 920 entries. Aug 13 07:09:59.114369 systemd-journald[1482]: System Journal (/var/log/journal/ec25dd48f12096c2da8dafe6741c1689) is 8M, max 195.6M, 187.6M free. Aug 13 07:09:59.159823 systemd-journald[1482]: Received client request to flush runtime journal. Aug 13 07:09:59.120640 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 07:09:59.140858 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 13 07:09:59.143355 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 13 07:09:59.162041 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 13 07:09:59.191782 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 13 07:09:59.229444 kernel: loop1: detected capacity change from 0 to 53784 Aug 13 07:09:59.236617 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 07:09:59.253799 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 13 07:09:59.259962 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 13 07:09:59.282187 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 07:09:59.319392 kernel: loop2: detected capacity change from 0 to 113512 Aug 13 07:09:59.317660 udevadm[1555]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 13 07:09:59.343754 systemd-tmpfiles[1558]: ACLs are not supported, ignoring. Aug 13 07:09:59.343785 systemd-tmpfiles[1558]: ACLs are not supported, ignoring. Aug 13 07:09:59.352961 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 07:09:59.460455 kernel: loop3: detected capacity change from 0 to 123192 Aug 13 07:09:59.582526 kernel: loop4: detected capacity change from 0 to 203944 Aug 13 07:09:59.610506 kernel: loop5: detected capacity change from 0 to 53784 Aug 13 07:09:59.633549 kernel: loop6: detected capacity change from 0 to 113512 Aug 13 07:09:59.646432 kernel: loop7: detected capacity change from 0 to 123192 Aug 13 07:09:59.658331 (sd-merge)[1563]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Aug 13 07:09:59.661606 (sd-merge)[1563]: Merged extensions into '/usr'. Aug 13 07:09:59.670643 systemd[1]: Reload requested from client PID 1519 ('systemd-sysext') (unit systemd-sysext.service)... Aug 13 07:09:59.670801 systemd[1]: Reloading... Aug 13 07:09:59.811597 zram_generator::config[1588]: No configuration found. Aug 13 07:10:00.203378 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. Aug 13 07:10:00.329376 ldconfig[1515]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 07:10:00.352384 systemd[1]: Reloading finished in 680 ms. Aug 13 07:10:00.380672 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 13 07:10:00.385080 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 13 07:10:00.388821 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 13 07:10:00.409950 systemd[1]: Starting ensure-sysext.service... Aug 13 07:10:00.417727 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 07:10:00.434727 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 07:10:00.467237 systemd[1]: Reload requested from client PID 1644 ('systemctl') (unit ensure-sysext.service)... Aug 13 07:10:00.467279 systemd[1]: Reloading... Aug 13 07:10:00.483554 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 13 07:10:00.484103 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 13 07:10:00.486954 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 13 07:10:00.489197 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 13 07:10:00.489966 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 13 07:10:00.515689 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 07:10:00.515715 systemd-tmpfiles[1645]: Skipping /boot Aug 13 07:10:00.555707 systemd-udevd[1646]: Using default interface naming scheme 'v255'. Aug 13 07:10:00.563556 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 07:10:00.563589 systemd-tmpfiles[1645]: Skipping /boot Aug 13 07:10:00.620445 zram_generator::config[1675]: No configuration found. Aug 13 07:10:00.823127 (udev-worker)[1692]: Network interface NamePolicy= disabled on kernel command line. Aug 13 07:10:01.086496 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. Aug 13 07:10:01.135426 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 42 scanned by (udev-worker) (1705) Aug 13 07:10:01.333448 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 13 07:10:01.334206 systemd[1]: Reloading finished in 866 ms. Aug 13 07:10:01.352659 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 07:10:01.390484 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 07:10:01.438134 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 13 07:10:01.448909 systemd[1]: Finished ensure-sysext.service. Aug 13 07:10:01.506855 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Aug 13 07:10:01.517742 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 13 07:10:01.532756 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 13 07:10:01.538676 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 07:10:01.542695 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 13 07:10:01.561803 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 07:10:01.567217 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 07:10:01.575731 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 07:10:01.586860 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 07:10:01.590043 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 07:10:01.596254 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 13 07:10:01.599571 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Aug 13 07:10:01.605742 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 13 07:10:01.616729 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 07:10:01.626687 lvm[1846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 13 07:10:01.627580 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 07:10:01.630328 systemd[1]: Reached target time-set.target - System Time Set. Aug 13 07:10:01.642868 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 13 07:10:01.649111 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 07:10:01.654625 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 07:10:01.655522 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 07:10:01.684819 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 13 07:10:01.713301 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 07:10:01.713874 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 07:10:01.717312 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 07:10:01.733181 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 07:10:01.736747 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 07:10:01.744344 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 07:10:01.746031 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 07:10:01.747563 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 07:10:01.756615 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 13 07:10:01.775154 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 13 07:10:01.791715 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 13 07:10:01.807238 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 13 07:10:01.815582 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 13 07:10:01.820881 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 07:10:01.828731 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 13 07:10:01.875310 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 13 07:10:01.879646 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). Aug 13 07:10:01.886938 augenrules[1890]: No rules Aug 13 07:10:01.888969 systemd[1]: audit-rules.service: Deactivated successfully. Aug 13 07:10:01.890833 lvm[1885]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 13 07:10:01.891468 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 13 07:10:01.899285 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 13 07:10:01.939909 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 13 07:10:01.966374 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 13 07:10:01.971482 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 07:10:02.105776 systemd-resolved[1859]: Positive Trust Anchors: Aug 13 07:10:02.106287 systemd-resolved[1859]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 07:10:02.106356 systemd-resolved[1859]: 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 Aug 13 07:10:02.110880 systemd-networkd[1858]: lo: Link UP Aug 13 07:10:02.110901 systemd-networkd[1858]: lo: Gained carrier Aug 13 07:10:02.113845 systemd-networkd[1858]: Enumeration completed Aug 13 07:10:02.114218 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 07:10:02.119734 systemd-networkd[1858]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 07:10:02.119759 systemd-networkd[1858]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 07:10:02.122925 systemd-resolved[1859]: Defaulting to hostname 'linux'. Aug 13 07:10:02.124265 systemd-networkd[1858]: eth0: Link UP Aug 13 07:10:02.124699 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 13 07:10:02.126154 systemd-networkd[1858]: eth0: Gained carrier Aug 13 07:10:02.126197 systemd-networkd[1858]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 07:10:02.137725 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 13 07:10:02.141814 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 07:10:02.145142 systemd[1]: Reached target network.target - Network. Aug 13 07:10:02.147592 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 07:10:02.150435 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 07:10:02.154880 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 13 07:10:02.158773 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 13 07:10:02.162852 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 13 07:10:02.165834 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 13 07:10:02.169127 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 13 07:10:02.172520 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 13 07:10:02.172563 systemd[1]: Reached target paths.target - Path Units. Aug 13 07:10:02.175218 systemd[1]: Reached target timers.target - Timer Units. Aug 13 07:10:02.179212 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 13 07:10:02.186258 systemd-networkd[1858]: eth0: DHCPv4 address 172.31.18.206/20, gateway 172.31.16.1 acquired from 172.31.16.1 Aug 13 07:10:02.189784 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 13 07:10:02.201521 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 13 07:10:02.205060 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 13 07:10:02.208220 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 13 07:10:02.215565 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 13 07:10:02.219588 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 13 07:10:02.225482 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 13 07:10:02.228769 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 13 07:10:02.232691 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 07:10:02.235941 systemd[1]: Reached target basic.target - Basic System. Aug 13 07:10:02.238519 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 13 07:10:02.238603 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 13 07:10:02.248533 systemd[1]: Starting containerd.service - containerd container runtime... Aug 13 07:10:02.255765 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 13 07:10:02.265720 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 13 07:10:02.288135 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 13 07:10:02.297048 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 13 07:10:02.299862 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). Aug 13 07:10:02.304195 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 13 07:10:02.311736 systemd[1]: Started ntpd.service - Network Time Service. Aug 13 07:10:02.323699 systemd[1]: Starting prepare-helm.service - Unpack helm to /opt/bin... Aug 13 07:10:02.340640 jq[1917]: false Aug 13 07:10:02.335675 systemd[1]: Starting setup-oem.service - Setup OEM... Aug 13 07:10:02.343717 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 13 07:10:02.355778 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 13 07:10:02.366703 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 13 07:10:02.371385 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 13 07:10:02.372307 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 13 07:10:02.376728 systemd[1]: Starting update-engine.service - Update Engine... Aug 13 07:10:02.388659 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 13 07:10:02.399376 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 13 07:10:02.400065 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 13 07:10:02.428199 dbus-daemon[1916]: [system] SELinux support is enabled Aug 13 07:10:02.429493 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 13 07:10:02.435782 dbus-daemon[1916]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1858 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Aug 13 07:10:02.446802 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 13 07:10:02.448530 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 13 07:10:02.452521 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). Aug 13 07:10:02.452584 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 13 07:10:02.457314 dbus-daemon[1916]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 13 07:10:02.459805 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). Aug 13 07:10:02.459861 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 13 07:10:02.478724 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 13 07:10:02.545092 jq[1928]: true Aug 13 07:10:02.582005 extend-filesystems[1918]: Found loop4 Aug 13 07:10:02.586755 extend-filesystems[1918]: Found loop5 Aug 13 07:10:02.586755 extend-filesystems[1918]: Found loop6 Aug 13 07:10:02.586755 extend-filesystems[1918]: Found loop7 Aug 13 07:10:02.586755 extend-filesystems[1918]: Found nvme0n1 Aug 13 07:10:02.586755 extend-filesystems[1918]: Found nvme0n1p1 Aug 13 07:10:02.603587 coreos-metadata[1915]: Aug 13 07:10:02.603 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Aug 13 07:10:02.609529 coreos-metadata[1915]: Aug 13 07:10:02.607 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Aug 13 07:10:02.613695 coreos-metadata[1915]: Aug 13 07:10:02.613 INFO Fetch successful Aug 13 07:10:02.613695 coreos-metadata[1915]: Aug 13 07:10:02.613 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Aug 13 07:10:02.613695 coreos-metadata[1915]: Aug 13 07:10:02.613 INFO Fetch successful Aug 13 07:10:02.613695 coreos-metadata[1915]: Aug 13 07:10:02.613 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Aug 13 07:10:02.613929 extend-filesystems[1918]: Found nvme0n1p2 Aug 13 07:10:02.613929 extend-filesystems[1918]: Found nvme0n1p3 Aug 13 07:10:02.613929 extend-filesystems[1918]: Found usr Aug 13 07:10:02.623611 extend-filesystems[1918]: Found nvme0n1p4 Aug 13 07:10:02.623611 extend-filesystems[1918]: Found nvme0n1p6 Aug 13 07:10:02.623611 extend-filesystems[1918]: Found nvme0n1p7 Aug 13 07:10:02.623611 extend-filesystems[1918]: Found nvme0n1p9 Aug 13 07:10:02.623611 extend-filesystems[1918]: Checking size of /dev/nvme0n1p9 Aug 13 07:10:02.621701 ntpd[1920]: ntpd 4.2.8p17@1.4004-o Tue Aug 12 20:57:50 UTC 2025 (1): Starting Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: ntpd 4.2.8p17@1.4004-o Tue Aug 12 20:57:50 UTC 2025 (1): Starting Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: ---------------------------------------------------- Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: ntp-4 is maintained by Network Time Foundation, Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: corporation. Support and training for ntp-4 are Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: available at https://www.nwtime.org/support Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: ---------------------------------------------------- Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: proto: precision = 0.096 usec (-23) Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: basedate set to 2025-07-31 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: gps base set to 2025-08-03 (week 2378) Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listen and drop on 0 v6wildcard [::]:123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listen normally on 2 lo 127.0.0.1:123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listen normally on 3 eth0 172.31.18.206:123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listen normally on 4 lo [::1]:123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: bind(21) AF_INET6 fe80::4e0:74ff:fe31:9c73%2#123 flags 0x11 failed: Cannot assign requested address Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: unable to create socket on eth0 (5) for fe80::4e0:74ff:fe31:9c73%2#123 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: failed to init interface for address fe80::4e0:74ff:fe31:9c73%2 Aug 13 07:10:02.650529 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: Listening on routing socket on fd #21 for interface updates Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.622 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.622 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.642 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetch failed with 404: resource not found Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.645 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.649 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.649 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.649 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.649 INFO Fetch successful Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.649 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Aug 13 07:10:02.661052 coreos-metadata[1915]: Aug 13 07:10:02.655 INFO Fetch successful Aug 13 07:10:02.621747 ntpd[1920]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 13 07:10:02.651951 (ntainerd)[1948]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 13 07:10:02.621766 ntpd[1920]: ---------------------------------------------------- Aug 13 07:10:02.677032 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 13 07:10:02.677032 ntpd[1920]: 13 Aug 07:10:02 ntpd[1920]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 13 07:10:02.621784 ntpd[1920]: ntp-4 is maintained by Network Time Foundation, Aug 13 07:10:02.677200 systemd[1]: Finished setup-oem.service - Setup OEM. Aug 13 07:10:02.621802 ntpd[1920]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 13 07:10:02.621819 ntpd[1920]: corporation. Support and training for ntp-4 are Aug 13 07:10:02.621836 ntpd[1920]: available at https://www.nwtime.org/support Aug 13 07:10:02.621853 ntpd[1920]: ---------------------------------------------------- Aug 13 07:10:02.630100 ntpd[1920]: proto: precision = 0.096 usec (-23) Aug 13 07:10:02.635101 ntpd[1920]: basedate set to 2025-07-31 Aug 13 07:10:02.635133 ntpd[1920]: gps base set to 2025-08-03 (week 2378) Aug 13 07:10:02.641109 ntpd[1920]: Listen and drop on 0 v6wildcard [::]:123 Aug 13 07:10:02.641187 ntpd[1920]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 13 07:10:02.642321 ntpd[1920]: Listen normally on 2 lo 127.0.0.1:123 Aug 13 07:10:02.642430 ntpd[1920]: Listen normally on 3 eth0 172.31.18.206:123 Aug 13 07:10:02.642513 ntpd[1920]: Listen normally on 4 lo [::1]:123 Aug 13 07:10:02.642603 ntpd[1920]: bind(21) AF_INET6 fe80::4e0:74ff:fe31:9c73%2#123 flags 0x11 failed: Cannot assign requested address Aug 13 07:10:02.642642 ntpd[1920]: unable to create socket on eth0 (5) for fe80::4e0:74ff:fe31:9c73%2#123 Aug 13 07:10:02.642669 ntpd[1920]: failed to init interface for address fe80::4e0:74ff:fe31:9c73%2 Aug 13 07:10:02.642729 ntpd[1920]: Listening on routing socket on fd #21 for interface updates Aug 13 07:10:02.667107 ntpd[1920]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 13 07:10:02.667159 ntpd[1920]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 13 07:10:02.699448 tar[1931]: linux-arm64/helm Aug 13 07:10:02.699322 systemd[1]: motdgen.service: Deactivated successfully. Aug 13 07:10:02.699849 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 13 07:10:02.717431 jq[1954]: true Aug 13 07:10:02.752548 update_engine[1927]: I20250813 07:10:02.750514 1927 main.cc:92] Flatcar Update Engine starting Aug 13 07:10:02.768538 systemd[1]: Started update-engine.service - Update Engine. Aug 13 07:10:02.777116 extend-filesystems[1918]: Resized partition /dev/nvme0n1p9 Aug 13 07:10:02.791777 update_engine[1927]: I20250813 07:10:02.788604 1927 update_check_scheduler.cc:74] Next update check in 11m19s Aug 13 07:10:02.796350 extend-filesystems[1977]: resize2fs 1.47.1 (20-May-2024) Aug 13 07:10:02.803630 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 13 07:10:02.810665 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 13 07:10:02.817393 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 13 07:10:02.840984 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Aug 13 07:10:02.945635 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Aug 13 07:10:02.964760 extend-filesystems[1977]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Aug 13 07:10:02.964760 extend-filesystems[1977]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 13 07:10:02.964760 extend-filesystems[1977]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Aug 13 07:10:02.992491 extend-filesystems[1918]: Resized filesystem in /dev/nvme0n1p9 Aug 13 07:10:02.989029 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 13 07:10:03.003270 bash[1991]: Updated "/home/core/.ssh/authorized_keys" Aug 13 07:10:02.989477 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 13 07:10:03.010243 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 13 07:10:03.022195 systemd-logind[1926]: Watching system buttons on /dev/input/event0 (Power Button) Aug 13 07:10:03.022255 systemd-logind[1926]: Watching system buttons on /dev/input/event1 (Sleep Button) Aug 13 07:10:03.035462 systemd-logind[1926]: New seat seat0. Aug 13 07:10:03.051846 systemd[1]: Starting sshkeys.service... Aug 13 07:10:03.053851 systemd[1]: Started systemd-logind.service - User Login Management. Aug 13 07:10:03.071424 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 42 scanned by (udev-worker) (1692) Aug 13 07:10:03.122022 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Aug 13 07:10:03.133090 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Aug 13 07:10:03.267571 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 13 07:10:03.310092 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 13 07:10:03.318696 dbus-daemon[1916]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 13 07:10:03.325609 dbus-daemon[1916]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1938 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Aug 13 07:10:03.361784 coreos-metadata[2030]: Aug 13 07:10:03.361 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Aug 13 07:10:03.367859 coreos-metadata[2030]: Aug 13 07:10:03.367 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Aug 13 07:10:03.363036 locksmithd[1980]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 13 07:10:03.364169 systemd[1]: Starting polkit.service - Authorization Manager... Aug 13 07:10:03.371092 coreos-metadata[2030]: Aug 13 07:10:03.369 INFO Fetch successful Aug 13 07:10:03.371092 coreos-metadata[2030]: Aug 13 07:10:03.369 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Aug 13 07:10:03.373438 coreos-metadata[2030]: Aug 13 07:10:03.371 INFO Fetch successful Aug 13 07:10:03.376511 unknown[2030]: wrote ssh authorized keys file for user: core Aug 13 07:10:03.478487 polkitd[2057]: Started polkitd version 121 Aug 13 07:10:03.489835 update-ssh-keys[2066]: Updated "/home/core/.ssh/authorized_keys" Aug 13 07:10:03.492922 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Aug 13 07:10:03.526991 systemd[1]: Finished sshkeys.service. Aug 13 07:10:03.533826 polkitd[2057]: Loading rules from directory /etc/polkit-1/rules.d Aug 13 07:10:03.533946 polkitd[2057]: Loading rules from directory /usr/share/polkit-1/rules.d Aug 13 07:10:03.549585 polkitd[2057]: Finished loading, compiling and executing 2 rules Aug 13 07:10:03.559041 dbus-daemon[1916]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 13 07:10:03.562514 systemd[1]: Started polkit.service - Authorization Manager. Aug 13 07:10:03.564128 polkitd[2057]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Aug 13 07:10:03.569859 systemd-networkd[1858]: eth0: Gained IPv6LL Aug 13 07:10:03.577820 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 13 07:10:03.585331 systemd[1]: Reached target network-online.target - Network is Online. Aug 13 07:10:03.599034 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Aug 13 07:10:03.609129 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:03.617902 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 13 07:10:03.658028 containerd[1948]: time="2025-08-13T07:10:03.657898344Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Aug 13 07:10:03.729812 systemd-hostnamed[1938]: Hostname set to (transient) Aug 13 07:10:03.732760 systemd-resolved[1859]: System hostname changed to 'ip-172-31-18-206'. Aug 13 07:10:03.857217 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 07:10:03.880583 containerd[1948]: time="2025-08-13T07:10:03.879247105Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.885752 containerd[1948]: time="2025-08-13T07:10:03.885671809Z" 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.100-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 13 07:10:03.885752 containerd[1948]: time="2025-08-13T07:10:03.885743557Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 13 07:10:03.885920 containerd[1948]: time="2025-08-13T07:10:03.885783373Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 13 07:10:03.886144 containerd[1948]: time="2025-08-13T07:10:03.886102381Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 13 07:10:03.886218 containerd[1948]: time="2025-08-13T07:10:03.886149613Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.886612 containerd[1948]: time="2025-08-13T07:10:03.886274965Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 07:10:03.886612 containerd[1948]: time="2025-08-13T07:10:03.886316077Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.887851 containerd[1948]: time="2025-08-13T07:10:03.886707541Z" 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 Aug 13 07:10:03.887851 containerd[1948]: time="2025-08-13T07:10:03.886743025Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.887851 containerd[1948]: time="2025-08-13T07:10:03.886773877Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 07:10:03.887851 containerd[1948]: time="2025-08-13T07:10:03.886799305Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.887851 containerd[1948]: time="2025-08-13T07:10:03.886966969Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.891161 containerd[1948]: time="2025-08-13T07:10:03.887388529Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 13 07:10:03.891161 containerd[1948]: time="2025-08-13T07:10:03.890362957Z" 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 Aug 13 07:10:03.891161 containerd[1948]: time="2025-08-13T07:10:03.890421601Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 13 07:10:03.891161 containerd[1948]: time="2025-08-13T07:10:03.890650093Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 13 07:10:03.891161 containerd[1948]: time="2025-08-13T07:10:03.890749357Z" level=info msg="metadata content store policy set" policy=shared Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897490021Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897594949Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897629017Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897664249Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897698353Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.897981037Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898418569Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898590541Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898621153Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898657405Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898688065Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898720813Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898753441Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.899426 containerd[1948]: time="2025-08-13T07:10:03.898785601Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898817173Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898855237Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898884301Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898910053Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898947817Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.898977589Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899022241Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899057185Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899085181Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899115829Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899145265Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899175757Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899205133Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900050 containerd[1948]: time="2025-08-13T07:10:03.899238793Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900625 containerd[1948]: time="2025-08-13T07:10:03.899266189Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900625 containerd[1948]: time="2025-08-13T07:10:03.899293249Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900625 containerd[1948]: time="2025-08-13T07:10:03.899321209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.900625 containerd[1948]: time="2025-08-13T07:10:03.899360329Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901222525Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901289833Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901459789Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901842121Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901882033Z" 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 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901906753Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901937869Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901960585Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.901990645Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.902014393Z" level=info msg="NRI interface is disabled by configuration." Aug 13 07:10:03.904444 containerd[1948]: time="2025-08-13T07:10:03.902038213Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 13 07:10:03.905946 containerd[1948]: time="2025-08-13T07:10:03.902677417Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 13 07:10:03.905946 containerd[1948]: time="2025-08-13T07:10:03.905394349Z" level=info msg="Connect containerd service" Aug 13 07:10:03.905946 containerd[1948]: time="2025-08-13T07:10:03.905528425Z" level=info msg="using legacy CRI server" Aug 13 07:10:03.905946 containerd[1948]: time="2025-08-13T07:10:03.905572273Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 13 07:10:03.907085 containerd[1948]: time="2025-08-13T07:10:03.906897781Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 13 07:10:03.911297 containerd[1948]: time="2025-08-13T07:10:03.910642813Z" 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" Aug 13 07:10:03.911297 containerd[1948]: time="2025-08-13T07:10:03.911213233Z" level=info msg="Start subscribing containerd event" Aug 13 07:10:03.911621 containerd[1948]: time="2025-08-13T07:10:03.911594257Z" level=info msg="Start recovering state" Aug 13 07:10:03.913660 containerd[1948]: time="2025-08-13T07:10:03.913185589Z" level=info msg="Start event monitor" Aug 13 07:10:03.913660 containerd[1948]: time="2025-08-13T07:10:03.913229113Z" level=info msg="Start snapshots syncer" Aug 13 07:10:03.913660 containerd[1948]: time="2025-08-13T07:10:03.913455169Z" level=info msg="Start cni network conf syncer for default" Aug 13 07:10:03.913660 containerd[1948]: time="2025-08-13T07:10:03.913477477Z" level=info msg="Start streaming server" Aug 13 07:10:03.916391 containerd[1948]: time="2025-08-13T07:10:03.916079581Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 13 07:10:03.916391 containerd[1948]: time="2025-08-13T07:10:03.916338661Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: Initializing new seelog logger Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: New Seelog Logger Creation Complete Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 processing appconfig overrides Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.920435 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 processing appconfig overrides Aug 13 07:10:03.921509 systemd[1]: Started containerd.service - containerd container runtime. Aug 13 07:10:03.923295 containerd[1948]: time="2025-08-13T07:10:03.921719005Z" level=info msg="containerd successfully booted in 0.276921s" Aug 13 07:10:03.925857 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.926207 amazon-ssm-agent[2105]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.926455 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 processing appconfig overrides Aug 13 07:10:03.927908 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO Proxy environment variables: Aug 13 07:10:03.934071 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.936433 amazon-ssm-agent[2105]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 13 07:10:03.936433 amazon-ssm-agent[2105]: 2025/08/13 07:10:03 processing appconfig overrides Aug 13 07:10:04.028495 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO https_proxy: Aug 13 07:10:04.128960 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO http_proxy: Aug 13 07:10:04.227812 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO no_proxy: Aug 13 07:10:04.325788 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO Checking if agent identity type OnPrem can be assumed Aug 13 07:10:04.424390 amazon-ssm-agent[2105]: 2025-08-13 07:10:03 INFO Checking if agent identity type EC2 can be assumed Aug 13 07:10:04.523592 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO Agent will take identity from EC2 Aug 13 07:10:04.622684 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 13 07:10:04.722459 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 13 07:10:04.732178 tar[1931]: linux-arm64/LICENSE Aug 13 07:10:04.732178 tar[1931]: linux-arm64/README.md Aug 13 07:10:04.756067 systemd[1]: Finished prepare-helm.service - Unpack helm to /opt/bin. Aug 13 07:10:04.821493 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 13 07:10:04.922415 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Aug 13 07:10:05.021198 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Aug 13 07:10:05.122417 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] Starting Core Agent Aug 13 07:10:05.181025 sshd_keygen[1958]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 13 07:10:05.223419 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [amazon-ssm-agent] registrar detected. Attempting registration Aug 13 07:10:05.227337 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 13 07:10:05.244465 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 13 07:10:05.260132 systemd[1]: Started sshd@0-172.31.18.206:22-139.178.89.65:34016.service - OpenSSH per-connection server daemon (139.178.89.65:34016). Aug 13 07:10:05.287715 systemd[1]: issuegen.service: Deactivated successfully. Aug 13 07:10:05.288814 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 13 07:10:05.306808 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 13 07:10:05.322569 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [Registrar] Starting registrar module Aug 13 07:10:05.353077 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 13 07:10:05.363982 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 13 07:10:05.373934 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 13 07:10:05.380151 systemd[1]: Reached target getty.target - Login Prompts. Aug 13 07:10:05.420298 amazon-ssm-agent[2105]: 2025-08-13 07:10:04 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Aug 13 07:10:05.420298 amazon-ssm-agent[2105]: 2025-08-13 07:10:05 INFO [EC2Identity] EC2 registration was successful. Aug 13 07:10:05.420536 amazon-ssm-agent[2105]: 2025-08-13 07:10:05 INFO [CredentialRefresher] credentialRefresher has started Aug 13 07:10:05.420536 amazon-ssm-agent[2105]: 2025-08-13 07:10:05 INFO [CredentialRefresher] Starting credentials refresher loop Aug 13 07:10:05.420536 amazon-ssm-agent[2105]: 2025-08-13 07:10:05 INFO EC2RoleProvider Successfully connected with instance profile role credentials Aug 13 07:10:05.423629 amazon-ssm-agent[2105]: 2025-08-13 07:10:05 INFO [CredentialRefresher] Next credential rotation will be in 30.841656085066667 minutes Aug 13 07:10:05.534196 sshd[2154]: Accepted publickey for core from 139.178.89.65 port 34016 ssh2: RSA SHA256:kl385O+gvTNa2B8dD83aJA1cQPxemPxPqb+SBclx8Ls Aug 13 07:10:05.537016 sshd-session[2154]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 07:10:05.550244 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 13 07:10:05.563881 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 13 07:10:05.593494 systemd-logind[1926]: New session 1 of user core. Aug 13 07:10:05.605494 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 13 07:10:05.624007 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 13 07:10:05.628731 ntpd[1920]: Listen normally on 6 eth0 [fe80::4e0:74ff:fe31:9c73%2]:123 Aug 13 07:10:05.629217 ntpd[1920]: 13 Aug 07:10:05 ntpd[1920]: Listen normally on 6 eth0 [fe80::4e0:74ff:fe31:9c73%2]:123 Aug 13 07:10:05.648319 (systemd)[2165]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 13 07:10:05.654942 systemd-logind[1926]: New session c1 of user core. Aug 13 07:10:05.954291 systemd[2165]: Queued start job for default target default.target. Aug 13 07:10:05.957793 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:05.960880 systemd[2165]: Created slice app.slice - User Application Slice. Aug 13 07:10:05.960954 systemd[2165]: Reached target paths.target - Paths. Aug 13 07:10:05.961032 systemd[2165]: Reached target timers.target - Timers. Aug 13 07:10:05.967205 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 07:10:05.978714 systemd[2165]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 07:10:05.979444 (kubelet)[2176]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 13 07:10:06.002893 systemd[2165]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 07:10:06.003160 systemd[2165]: Reached target sockets.target - Sockets. Aug 13 07:10:06.003242 systemd[2165]: Reached target basic.target - Basic System. Aug 13 07:10:06.003335 systemd[2165]: Reached target default.target - Main User Target. Aug 13 07:10:06.003393 systemd[2165]: Startup finished in 334ms. Aug 13 07:10:06.003588 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 13 07:10:06.023940 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 13 07:10:06.031182 systemd[1]: Startup finished in 1.103s (kernel) + 8.635s (initrd) + 9.651s (userspace) = 19.390s. Aug 13 07:10:06.205932 systemd[1]: Started sshd@1-172.31.18.206:22-139.178.89.65:34026.service - OpenSSH per-connection server daemon (139.178.89.65:34026). Aug 13 07:10:06.400506 sshd[2190]: Accepted publickey for core from 139.178.89.65 port 34026 ssh2: RSA SHA256:kl385O+gvTNa2B8dD83aJA1cQPxemPxPqb+SBclx8Ls Aug 13 07:10:06.403619 sshd-session[2190]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 07:10:06.413641 systemd-logind[1926]: New session 2 of user core. Aug 13 07:10:06.422965 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 13 07:10:06.453667 amazon-ssm-agent[2105]: 2025-08-13 07:10:06 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Aug 13 07:10:06.557103 amazon-ssm-agent[2105]: 2025-08-13 07:10:06 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2194) started Aug 13 07:10:06.568556 sshd[2193]: Connection closed by 139.178.89.65 port 34026 Aug 13 07:10:06.568316 sshd-session[2190]: pam_unix(sshd:session): session closed for user core Aug 13 07:10:06.578378 systemd[1]: sshd@1-172.31.18.206:22-139.178.89.65:34026.service: Deactivated successfully. Aug 13 07:10:06.585323 systemd[1]: session-2.scope: Deactivated successfully. Aug 13 07:10:06.592786 systemd-logind[1926]: Session 2 logged out. Waiting for processes to exit. Aug 13 07:10:06.614080 systemd[1]: Started sshd@2-172.31.18.206:22-139.178.89.65:34030.service - OpenSSH per-connection server daemon (139.178.89.65:34030). Aug 13 07:10:06.617467 systemd-logind[1926]: Removed session 2. Aug 13 07:10:06.659480 amazon-ssm-agent[2105]: 2025-08-13 07:10:06 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Aug 13 07:10:06.823683 sshd[2204]: Accepted publickey for core from 139.178.89.65 port 34030 ssh2: RSA SHA256:kl385O+gvTNa2B8dD83aJA1cQPxemPxPqb+SBclx8Ls Aug 13 07:10:06.826756 sshd-session[2204]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 07:10:06.836735 systemd-logind[1926]: New session 3 of user core. Aug 13 07:10:06.845699 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 07:10:06.957338 kubelet[2176]: E0813 07:10:06.957245 2176 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Aug 13 07:10:06.961526 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 13 07:10:06.961868 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 13 07:10:06.963583 sshd[2210]: Connection closed by 139.178.89.65 port 34030 Aug 13 07:10:06.964192 systemd[1]: kubelet.service: Consumed 1.444s CPU time, 260.4M memory peak. Aug 13 07:10:06.964499 sshd-session[2204]: pam_unix(sshd:session): session closed for user core Aug 13 07:10:06.971956 systemd[1]: sshd@2-172.31.18.206:22-139.178.89.65:34030.service: Deactivated successfully. Aug 13 07:10:06.975824 systemd[1]: session-3.scope: Deactivated successfully. Aug 13 07:10:06.978289 systemd-logind[1926]: Session 3 logged out. Waiting for processes to exit. Aug 13 07:10:06.980015 systemd-logind[1926]: Removed session 3. Aug 13 07:10:07.008841 systemd[1]: Started sshd@3-172.31.18.206:22-139.178.89.65:34046.service - OpenSSH per-connection server daemon (139.178.89.65:34046). Aug 13 07:10:07.188481 sshd[2218]: Accepted publickey for core from 139.178.89.65 port 34046 ssh2: RSA SHA256:kl385O+gvTNa2B8dD83aJA1cQPxemPxPqb+SBclx8Ls Aug 13 07:10:07.190873 sshd-session[2218]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 07:10:07.198917 systemd-logind[1926]: New session 4 of user core. Aug 13 07:10:07.209752 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 07:10:07.333816 sshd[2220]: Connection closed by 139.178.89.65 port 34046 Aug 13 07:10:07.334642 sshd-session[2218]: pam_unix(sshd:session): session closed for user core Aug 13 07:10:07.339969 systemd[1]: sshd@3-172.31.18.206:22-139.178.89.65:34046.service: Deactivated successfully. Aug 13 07:10:07.344283 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 07:10:07.347194 systemd-logind[1926]: Session 4 logged out. Waiting for processes to exit. Aug 13 07:10:07.349521 systemd-logind[1926]: Removed session 4. Aug 13 07:10:07.380871 systemd[1]: Started sshd@4-172.31.18.206:22-139.178.89.65:34056.service - OpenSSH per-connection server daemon (139.178.89.65:34056). Aug 13 07:10:07.556239 sshd[2227]: Accepted publickey for core from 139.178.89.65 port 34056 ssh2: RSA SHA256:kl385O+gvTNa2B8dD83aJA1cQPxemPxPqb+SBclx8Ls Aug 13 07:10:07.558843 sshd-session[2227]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 07:10:07.567505 systemd-logind[1926]: New session 5 of user core. Aug 13 07:10:07.571660 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 07:10:07.718706 sudo[2230]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 13 07:10:07.719353 sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 07:10:08.488101 (dockerd)[2246]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Aug 13 07:10:08.488611 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 13 07:10:08.997440 dockerd[2246]: time="2025-08-13T07:10:08.996888726Z" level=info msg="Starting up" Aug 13 07:10:09.317542 systemd[1]: var-lib-docker-metacopy\x2dcheck4016420687-merged.mount: Deactivated successfully. Aug 13 07:10:09.333944 dockerd[2246]: time="2025-08-13T07:10:09.333892408Z" level=info msg="Loading containers: start." Aug 13 07:10:09.590426 kernel: Initializing XFRM netlink socket Aug 13 07:10:09.622798 (udev-worker)[2269]: Network interface NamePolicy= disabled on kernel command line. Aug 13 07:10:09.263767 systemd-resolved[1859]: Clock change detected. Flushing caches. Aug 13 07:10:09.272490 systemd-journald[1482]: Time jumped backwards, rotating. Aug 13 07:10:09.361693 systemd-networkd[1858]: docker0: Link UP Aug 13 07:10:09.399830 dockerd[2246]: time="2025-08-13T07:10:09.399757792Z" level=info msg="Loading containers: done." Aug 13 07:10:09.427220 dockerd[2246]: time="2025-08-13T07:10:09.424354000Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Aug 13 07:10:09.427220 dockerd[2246]: time="2025-08-13T07:10:09.424489660Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Aug 13 07:10:09.427220 dockerd[2246]: time="2025-08-13T07:10:09.424859332Z" level=info msg="Daemon has completed initialization" Aug 13 07:10:09.427191 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4026559176-merged.mount: Deactivated successfully. Aug 13 07:10:09.491456 dockerd[2246]: time="2025-08-13T07:10:09.490358225Z" level=info msg="API listen on /run/docker.sock" Aug 13 07:10:09.490486 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 13 07:10:10.578511 containerd[1948]: time="2025-08-13T07:10:10.578111502Z" level=info msg="PullImage \"registry.k8s.io/kube-apiserver:v1.31.11\"" Aug 13 07:10:11.193298 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4266867010.mount: Deactivated successfully. Aug 13 07:10:12.509966 containerd[1948]: time="2025-08-13T07:10:12.509903300Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-apiserver:v1.31.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:12.512801 containerd[1948]: time="2025-08-13T07:10:12.512740280Z" level=info msg="stop pulling image registry.k8s.io/kube-apiserver:v1.31.11: active requests=0, bytes read=25651813" Aug 13 07:10:12.515219 containerd[1948]: time="2025-08-13T07:10:12.515145848Z" level=info msg="ImageCreate event name:\"sha256:00a68b619a4bfa14c989a2181a7aa0726a5cb1272a7f65394e6a594ad6eade27\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:12.521257 containerd[1948]: time="2025-08-13T07:10:12.521158148Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-apiserver@sha256:a3d1c4440817725a1b503a7ccce94f3dce2b208ebf257b405dc2d97817df3dde\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:12.523564 containerd[1948]: time="2025-08-13T07:10:12.523293092Z" level=info msg="Pulled image \"registry.k8s.io/kube-apiserver:v1.31.11\" with image id \"sha256:00a68b619a4bfa14c989a2181a7aa0726a5cb1272a7f65394e6a594ad6eade27\", repo tag \"registry.k8s.io/kube-apiserver:v1.31.11\", repo digest \"registry.k8s.io/kube-apiserver@sha256:a3d1c4440817725a1b503a7ccce94f3dce2b208ebf257b405dc2d97817df3dde\", size \"25648613\" in 1.945123174s" Aug 13 07:10:12.523564 containerd[1948]: time="2025-08-13T07:10:12.523352432Z" level=info msg="PullImage \"registry.k8s.io/kube-apiserver:v1.31.11\" returns image reference \"sha256:00a68b619a4bfa14c989a2181a7aa0726a5cb1272a7f65394e6a594ad6eade27\"" Aug 13 07:10:12.526052 containerd[1948]: time="2025-08-13T07:10:12.525762884Z" level=info msg="PullImage \"registry.k8s.io/kube-controller-manager:v1.31.11\"" Aug 13 07:10:13.912377 containerd[1948]: time="2025-08-13T07:10:13.912120275Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-controller-manager:v1.31.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:13.914372 containerd[1948]: time="2025-08-13T07:10:13.914304299Z" level=info msg="stop pulling image registry.k8s.io/kube-controller-manager:v1.31.11: active requests=0, bytes read=22460283" Aug 13 07:10:13.915264 containerd[1948]: time="2025-08-13T07:10:13.914787143Z" level=info msg="ImageCreate event name:\"sha256:5c5dc52b837451e0fe6108fdfb9cfa431191ce227ce71d103dec8a8c655c4e71\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:13.920254 containerd[1948]: time="2025-08-13T07:10:13.920202131Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-controller-manager@sha256:0f19de157f3d251f5ddeb6e9d026895bc55cb02592874b326fa345c57e5e2848\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:13.922655 containerd[1948]: time="2025-08-13T07:10:13.922449707Z" level=info msg="Pulled image \"registry.k8s.io/kube-controller-manager:v1.31.11\" with image id \"sha256:5c5dc52b837451e0fe6108fdfb9cfa431191ce227ce71d103dec8a8c655c4e71\", repo tag \"registry.k8s.io/kube-controller-manager:v1.31.11\", repo digest \"registry.k8s.io/kube-controller-manager@sha256:0f19de157f3d251f5ddeb6e9d026895bc55cb02592874b326fa345c57e5e2848\", size \"23996073\" in 1.396633471s" Aug 13 07:10:13.922655 containerd[1948]: time="2025-08-13T07:10:13.922502483Z" level=info msg="PullImage \"registry.k8s.io/kube-controller-manager:v1.31.11\" returns image reference \"sha256:5c5dc52b837451e0fe6108fdfb9cfa431191ce227ce71d103dec8a8c655c4e71\"" Aug 13 07:10:13.923621 containerd[1948]: time="2025-08-13T07:10:13.923302187Z" level=info msg="PullImage \"registry.k8s.io/kube-scheduler:v1.31.11\"" Aug 13 07:10:15.030958 containerd[1948]: time="2025-08-13T07:10:15.030902492Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-scheduler:v1.31.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:15.034244 containerd[1948]: time="2025-08-13T07:10:15.034183220Z" level=info msg="stop pulling image registry.k8s.io/kube-scheduler:v1.31.11: active requests=0, bytes read=17125089" Aug 13 07:10:15.035728 containerd[1948]: time="2025-08-13T07:10:15.035670716Z" level=info msg="ImageCreate event name:\"sha256:89be0efdc4ab1793b9b1b05e836e33dc50f5b2911b57609b315b58608b2d3746\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:15.040644 containerd[1948]: time="2025-08-13T07:10:15.040593188Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-scheduler@sha256:1a9b59b3bfa6c1f1911f6f865a795620c461d079e413061bb71981cadd67f39d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:15.042929 containerd[1948]: time="2025-08-13T07:10:15.042868844Z" level=info msg="Pulled image \"registry.k8s.io/kube-scheduler:v1.31.11\" with image id \"sha256:89be0efdc4ab1793b9b1b05e836e33dc50f5b2911b57609b315b58608b2d3746\", repo tag \"registry.k8s.io/kube-scheduler:v1.31.11\", repo digest \"registry.k8s.io/kube-scheduler@sha256:1a9b59b3bfa6c1f1911f6f865a795620c461d079e413061bb71981cadd67f39d\", size \"18660897\" in 1.119512273s" Aug 13 07:10:15.043033 containerd[1948]: time="2025-08-13T07:10:15.042935984Z" level=info msg="PullImage \"registry.k8s.io/kube-scheduler:v1.31.11\" returns image reference \"sha256:89be0efdc4ab1793b9b1b05e836e33dc50f5b2911b57609b315b58608b2d3746\"" Aug 13 07:10:15.043799 containerd[1948]: time="2025-08-13T07:10:15.043761224Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.11\"" Aug 13 07:10:16.246893 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3710982686.mount: Deactivated successfully. Aug 13 07:10:16.740810 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Aug 13 07:10:16.748884 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:16.779155 containerd[1948]: time="2025-08-13T07:10:16.779073697Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:16.782622 containerd[1948]: time="2025-08-13T07:10:16.782261785Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.11: active requests=0, bytes read=26915993" Aug 13 07:10:16.785578 containerd[1948]: time="2025-08-13T07:10:16.785130625Z" level=info msg="ImageCreate event name:\"sha256:7d1e7db6660181423f98acbe3a495b3fe5cec9b85cdef245540cc2cb3b180ab0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:16.792072 containerd[1948]: time="2025-08-13T07:10:16.791985337Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:a31da847792c5e7e92e91b78da1ad21d693e4b2b48d0e9f4610c8764dc2a5d79\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:16.793994 containerd[1948]: time="2025-08-13T07:10:16.793256593Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.11\" with image id \"sha256:7d1e7db6660181423f98acbe3a495b3fe5cec9b85cdef245540cc2cb3b180ab0\", repo tag \"registry.k8s.io/kube-proxy:v1.31.11\", repo digest \"registry.k8s.io/kube-proxy@sha256:a31da847792c5e7e92e91b78da1ad21d693e4b2b48d0e9f4610c8764dc2a5d79\", size \"26915012\" in 1.748655297s" Aug 13 07:10:16.794645 containerd[1948]: time="2025-08-13T07:10:16.794589781Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.11\" returns image reference \"sha256:7d1e7db6660181423f98acbe3a495b3fe5cec9b85cdef245540cc2cb3b180ab0\"" Aug 13 07:10:16.795606 containerd[1948]: time="2025-08-13T07:10:16.795283717Z" level=info msg="PullImage \"registry.k8s.io/coredns/coredns:v1.11.3\"" Aug 13 07:10:17.100850 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:17.112990 (kubelet)[2514]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 13 07:10:17.195405 kubelet[2514]: E0813 07:10:17.195284 2514 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Aug 13 07:10:17.202012 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 13 07:10:17.202356 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 13 07:10:17.203193 systemd[1]: kubelet.service: Consumed 320ms CPU time, 109.1M memory peak. Aug 13 07:10:17.357169 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount991689512.mount: Deactivated successfully. Aug 13 07:10:18.601661 containerd[1948]: time="2025-08-13T07:10:18.600701462Z" level=info msg="ImageCreate event name:\"registry.k8s.io/coredns/coredns:v1.11.3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:18.603004 containerd[1948]: time="2025-08-13T07:10:18.602915930Z" level=info msg="stop pulling image registry.k8s.io/coredns/coredns:v1.11.3: active requests=0, bytes read=16951622" Aug 13 07:10:18.605450 containerd[1948]: time="2025-08-13T07:10:18.605375834Z" level=info msg="ImageCreate event name:\"sha256:2f6c962e7b8311337352d9fdea917da2184d9919f4da7695bc2a6517cf392fe4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:18.618211 containerd[1948]: time="2025-08-13T07:10:18.617837930Z" level=info msg="ImageCreate event name:\"registry.k8s.io/coredns/coredns@sha256:9caabbf6238b189a65d0d6e6ac138de60d6a1c419e5a341fbbb7c78382559c6e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:18.622463 containerd[1948]: time="2025-08-13T07:10:18.622407950Z" level=info msg="Pulled image \"registry.k8s.io/coredns/coredns:v1.11.3\" with image id \"sha256:2f6c962e7b8311337352d9fdea917da2184d9919f4da7695bc2a6517cf392fe4\", repo tag \"registry.k8s.io/coredns/coredns:v1.11.3\", repo digest \"registry.k8s.io/coredns/coredns@sha256:9caabbf6238b189a65d0d6e6ac138de60d6a1c419e5a341fbbb7c78382559c6e\", size \"16948420\" in 1.827070101s" Aug 13 07:10:18.622632 containerd[1948]: time="2025-08-13T07:10:18.622603658Z" level=info msg="PullImage \"registry.k8s.io/coredns/coredns:v1.11.3\" returns image reference \"sha256:2f6c962e7b8311337352d9fdea917da2184d9919f4da7695bc2a6517cf392fe4\"" Aug 13 07:10:18.623509 containerd[1948]: time="2025-08-13T07:10:18.623447882Z" level=info msg="PullImage \"registry.k8s.io/pause:3.10\"" Aug 13 07:10:19.102458 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2282609297.mount: Deactivated successfully. Aug 13 07:10:19.114965 containerd[1948]: time="2025-08-13T07:10:19.114886188Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:19.116770 containerd[1948]: time="2025-08-13T07:10:19.116707392Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Aug 13 07:10:19.119295 containerd[1948]: time="2025-08-13T07:10:19.119203140Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:19.125730 containerd[1948]: time="2025-08-13T07:10:19.125633496Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:19.127988 containerd[1948]: time="2025-08-13T07:10:19.127318956Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 503.81525ms" Aug 13 07:10:19.127988 containerd[1948]: time="2025-08-13T07:10:19.127371036Z" level=info msg="PullImage \"registry.k8s.io/pause:3.10\" returns image reference \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\"" Aug 13 07:10:19.128748 containerd[1948]: time="2025-08-13T07:10:19.128388372Z" level=info msg="PullImage \"registry.k8s.io/etcd:3.5.15-0\"" Aug 13 07:10:19.666501 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2431273464.mount: Deactivated successfully. Aug 13 07:10:21.669647 containerd[1948]: time="2025-08-13T07:10:21.668735621Z" level=info msg="ImageCreate event name:\"registry.k8s.io/etcd:3.5.15-0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:21.674110 containerd[1948]: time="2025-08-13T07:10:21.673731413Z" level=info msg="stop pulling image registry.k8s.io/etcd:3.5.15-0: active requests=0, bytes read=66406465" Aug 13 07:10:21.679340 containerd[1948]: time="2025-08-13T07:10:21.679242929Z" level=info msg="ImageCreate event name:\"sha256:27e3830e1402783674d8b594038967deea9d51f0d91b34c93c8f39d2f68af7da\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:21.692215 containerd[1948]: time="2025-08-13T07:10:21.692123621Z" level=info msg="ImageCreate event name:\"registry.k8s.io/etcd@sha256:a6dc63e6e8cfa0307d7851762fa6b629afb18f28d8aa3fab5a6e91b4af60026a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 07:10:21.696589 containerd[1948]: time="2025-08-13T07:10:21.694384373Z" level=info msg="Pulled image \"registry.k8s.io/etcd:3.5.15-0\" with image id \"sha256:27e3830e1402783674d8b594038967deea9d51f0d91b34c93c8f39d2f68af7da\", repo tag \"registry.k8s.io/etcd:3.5.15-0\", repo digest \"registry.k8s.io/etcd@sha256:a6dc63e6e8cfa0307d7851762fa6b629afb18f28d8aa3fab5a6e91b4af60026a\", size \"66535646\" in 2.565940633s" Aug 13 07:10:21.696589 containerd[1948]: time="2025-08-13T07:10:21.694444709Z" level=info msg="PullImage \"registry.k8s.io/etcd:3.5.15-0\" returns image reference \"sha256:27e3830e1402783674d8b594038967deea9d51f0d91b34c93c8f39d2f68af7da\"" Aug 13 07:10:27.240339 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 2. Aug 13 07:10:27.249693 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:27.598914 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:27.607021 (kubelet)[2661]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 13 07:10:27.690946 kubelet[2661]: E0813 07:10:27.690862 2661 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Aug 13 07:10:27.695768 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 13 07:10:27.696102 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 13 07:10:27.696992 systemd[1]: kubelet.service: Consumed 286ms CPU time, 109.2M memory peak. Aug 13 07:10:28.905898 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:28.906209 systemd[1]: kubelet.service: Consumed 286ms CPU time, 109.2M memory peak. Aug 13 07:10:28.914404 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:28.973547 systemd[1]: Reload requested from client PID 2675 ('systemctl') (unit session-5.scope)... Aug 13 07:10:28.973574 systemd[1]: Reloading... Aug 13 07:10:29.231572 zram_generator::config[2730]: No configuration found. Aug 13 07:10:29.454941 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. Aug 13 07:10:29.681849 systemd[1]: Reloading finished in 707 ms. Aug 13 07:10:29.774844 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:29.788139 (kubelet)[2775]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 13 07:10:29.793587 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:29.795962 systemd[1]: kubelet.service: Deactivated successfully. Aug 13 07:10:29.796663 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:29.796752 systemd[1]: kubelet.service: Consumed 228ms CPU time, 95.9M memory peak. Aug 13 07:10:29.805093 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:30.135784 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:30.136105 (kubelet)[2787]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 13 07:10:30.215279 kubelet[2787]: 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. Aug 13 07:10:30.215820 kubelet[2787]: 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. Aug 13 07:10:30.216992 kubelet[2787]: 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. Aug 13 07:10:30.217307 kubelet[2787]: I0813 07:10:30.217231 2787 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 13 07:10:32.423474 kubelet[2787]: I0813 07:10:32.423420 2787 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Aug 13 07:10:32.425582 kubelet[2787]: I0813 07:10:32.424136 2787 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 13 07:10:32.425582 kubelet[2787]: I0813 07:10:32.424610 2787 server.go:934] "Client rotation is on, will bootstrap in background" Aug 13 07:10:32.470790 kubelet[2787]: E0813 07:10:32.470729 2787 certificate_manager.go:562] "Unhandled Error" err="kubernetes.io/kube-apiserver-client-kubelet: Failed while requesting a signed certificate from the control plane: cannot create certificate signing request: Post \"https://172.31.18.206:6443/apis/certificates.k8s.io/v1/certificatesigningrequests\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:32.472958 kubelet[2787]: I0813 07:10:32.472724 2787 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 13 07:10:32.484991 kubelet[2787]: E0813 07:10:32.484846 2787 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Aug 13 07:10:32.484991 kubelet[2787]: I0813 07:10:32.484908 2787 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Aug 13 07:10:32.492054 kubelet[2787]: I0813 07:10:32.492006 2787 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 13 07:10:32.493841 kubelet[2787]: I0813 07:10:32.493792 2787 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Aug 13 07:10:32.494165 kubelet[2787]: I0813 07:10:32.494094 2787 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 13 07:10:32.494458 kubelet[2787]: I0813 07:10:32.494156 2787 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"ip-172-31-18-206","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Aug 13 07:10:32.494625 kubelet[2787]: I0813 07:10:32.494468 2787 topology_manager.go:138] "Creating topology manager with none policy" Aug 13 07:10:32.494625 kubelet[2787]: I0813 07:10:32.494488 2787 container_manager_linux.go:300] "Creating device plugin manager" Aug 13 07:10:32.495022 kubelet[2787]: I0813 07:10:32.494979 2787 state_mem.go:36] "Initialized new in-memory state store" Aug 13 07:10:32.500378 kubelet[2787]: I0813 07:10:32.499992 2787 kubelet.go:408] "Attempting to sync node with API server" Aug 13 07:10:32.500378 kubelet[2787]: I0813 07:10:32.500039 2787 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 13 07:10:32.500378 kubelet[2787]: I0813 07:10:32.500082 2787 kubelet.go:314] "Adding apiserver pod source" Aug 13 07:10:32.500378 kubelet[2787]: I0813 07:10:32.500112 2787 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 13 07:10:32.509267 kubelet[2787]: I0813 07:10:32.509213 2787 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Aug 13 07:10:32.510467 kubelet[2787]: I0813 07:10:32.510421 2787 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 13 07:10:32.511267 kubelet[2787]: W0813 07:10:32.510829 2787 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 13 07:10:32.512580 kubelet[2787]: I0813 07:10:32.512539 2787 server.go:1274] "Started kubelet" Aug 13 07:10:32.513000 kubelet[2787]: W0813 07:10:32.512767 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: Get "https://172.31.18.206:6443/api/v1/nodes?fieldSelector=metadata.name%3Dip-172-31-18-206&limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:32.513063 kubelet[2787]: E0813 07:10:32.513019 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: Get \"https://172.31.18.206:6443/api/v1/nodes?fieldSelector=metadata.name%3Dip-172-31-18-206&limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:32.519064 kubelet[2787]: W0813 07:10:32.518996 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: Get "https://172.31.18.206:6443/api/v1/services?fieldSelector=spec.clusterIP%21%3DNone&limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:32.519288 kubelet[2787]: E0813 07:10:32.519255 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: Get \"https://172.31.18.206:6443/api/v1/services?fieldSelector=spec.clusterIP%21%3DNone&limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:32.519695 kubelet[2787]: I0813 07:10:32.519644 2787 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Aug 13 07:10:32.520561 kubelet[2787]: I0813 07:10:32.520398 2787 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 13 07:10:32.521396 kubelet[2787]: I0813 07:10:32.521341 2787 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 13 07:10:32.525759 kubelet[2787]: E0813 07:10:32.522249 2787 event.go:368] "Unable to write event (may retry after sleeping)" err="Post \"https://172.31.18.206:6443/api/v1/namespaces/default/events\": dial tcp 172.31.18.206:6443: connect: connection refused" event="&Event{ObjectMeta:{ip-172-31-18-206.185b41fc7f31e3c7 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:ip-172-31-18-206,UID:ip-172-31-18-206,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:ip-172-31-18-206,},FirstTimestamp:2025-08-13 07:10:32.512480199 +0000 UTC m=+2.366711389,LastTimestamp:2025-08-13 07:10:32.512480199 +0000 UTC m=+2.366711389,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:ip-172-31-18-206,}" Aug 13 07:10:32.530600 kubelet[2787]: I0813 07:10:32.528691 2787 server.go:449] "Adding debug handlers to kubelet server" Aug 13 07:10:32.534204 kubelet[2787]: E0813 07:10:32.534149 2787 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 13 07:10:32.534722 kubelet[2787]: I0813 07:10:32.534693 2787 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 13 07:10:32.535670 kubelet[2787]: I0813 07:10:32.535620 2787 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Aug 13 07:10:32.539912 kubelet[2787]: I0813 07:10:32.539876 2787 volume_manager.go:289] "Starting Kubelet Volume Manager" Aug 13 07:10:32.540626 kubelet[2787]: E0813 07:10:32.540595 2787 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"ip-172-31-18-206\" not found" Aug 13 07:10:32.542680 kubelet[2787]: E0813 07:10:32.542482 2787 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.18.206:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/ip-172-31-18-206?timeout=10s\": dial tcp 172.31.18.206:6443: connect: connection refused" interval="200ms" Aug 13 07:10:32.542984 kubelet[2787]: I0813 07:10:32.542721 2787 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 Aug 13 07:10:32.543466 kubelet[2787]: I0813 07:10:32.543441 2787 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Aug 13 07:10:32.543898 kubelet[2787]: I0813 07:10:32.543873 2787 reconciler.go:26] "Reconciler: start to sync state" Aug 13 07:10:32.546795 kubelet[2787]: I0813 07:10:32.546758 2787 factory.go:221] Registration of the containerd container factory successfully Aug 13 07:10:32.546986 kubelet[2787]: I0813 07:10:32.546964 2787 factory.go:221] Registration of the systemd container factory successfully Aug 13 07:10:32.571480 kubelet[2787]: I0813 07:10:32.571394 2787 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 13 07:10:32.577129 kubelet[2787]: W0813 07:10:32.576683 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: Get "https://172.31.18.206:6443/apis/storage.k8s.io/v1/csidrivers?limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:32.577129 kubelet[2787]: E0813 07:10:32.576813 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: Get \"https://172.31.18.206:6443/apis/storage.k8s.io/v1/csidrivers?limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:32.580600 kubelet[2787]: I0813 07:10:32.580383 2787 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 13 07:10:32.580600 kubelet[2787]: I0813 07:10:32.580432 2787 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 13 07:10:32.580600 kubelet[2787]: I0813 07:10:32.580465 2787 kubelet.go:2321] "Starting kubelet main sync loop" Aug 13 07:10:32.580600 kubelet[2787]: E0813 07:10:32.580562 2787 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Aug 13 07:10:32.587164 kubelet[2787]: W0813 07:10:32.586151 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.RuntimeClass: Get "https://172.31.18.206:6443/apis/node.k8s.io/v1/runtimeclasses?limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:32.587164 kubelet[2787]: E0813 07:10:32.586255 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: Get \"https://172.31.18.206:6443/apis/node.k8s.io/v1/runtimeclasses?limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:32.595030 kubelet[2787]: I0813 07:10:32.594989 2787 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 13 07:10:32.595030 kubelet[2787]: I0813 07:10:32.595023 2787 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 13 07:10:32.595227 kubelet[2787]: I0813 07:10:32.595056 2787 state_mem.go:36] "Initialized new in-memory state store" Aug 13 07:10:32.601144 kubelet[2787]: I0813 07:10:32.601105 2787 policy_none.go:49] "None policy: Start" Aug 13 07:10:32.602458 kubelet[2787]: I0813 07:10:32.602396 2787 memory_manager.go:170] "Starting memorymanager" policy="None" Aug 13 07:10:32.602458 kubelet[2787]: I0813 07:10:32.602453 2787 state_mem.go:35] "Initializing new in-memory state store" Aug 13 07:10:32.615069 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 13 07:10:32.631603 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 13 07:10:32.638386 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 13 07:10:32.641028 kubelet[2787]: E0813 07:10:32.640968 2787 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"ip-172-31-18-206\" not found" Aug 13 07:10:32.648253 kubelet[2787]: I0813 07:10:32.648106 2787 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 13 07:10:32.649248 kubelet[2787]: I0813 07:10:32.648403 2787 eviction_manager.go:189] "Eviction manager: starting control loop" Aug 13 07:10:32.649248 kubelet[2787]: I0813 07:10:32.648435 2787 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 13 07:10:32.649248 kubelet[2787]: I0813 07:10:32.649135 2787 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 13 07:10:32.652287 kubelet[2787]: E0813 07:10:32.652236 2787 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"ip-172-31-18-206\" not found" Aug 13 07:10:32.700068 systemd[1]: Created slice kubepods-burstable-pod92a374eb991156349bf84c769068e6b9.slice - libcontainer container kubepods-burstable-pod92a374eb991156349bf84c769068e6b9.slice. Aug 13 07:10:32.728429 systemd[1]: Created slice kubepods-burstable-pod129f7ccd5d4c83b2f7bc10a9ff0b8c3d.slice - libcontainer container kubepods-burstable-pod129f7ccd5d4c83b2f7bc10a9ff0b8c3d.slice. Aug 13 07:10:32.744782 kubelet[2787]: I0813 07:10:32.744681 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-ca-certs\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:32.744782 kubelet[2787]: I0813 07:10:32.744753 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flexvolume-dir\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-flexvolume-dir\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:32.744985 kubelet[2787]: I0813 07:10:32.744794 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-usr-share-ca-certificates\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:32.744985 kubelet[2787]: I0813 07:10:32.744835 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-k8s-certs\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:32.744985 kubelet[2787]: I0813 07:10:32.744871 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-kubeconfig\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:32.744985 kubelet[2787]: I0813 07:10:32.744924 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/f8b60b4d82f810bebdaa79db78bd11b0-kubeconfig\") pod \"kube-scheduler-ip-172-31-18-206\" (UID: \"f8b60b4d82f810bebdaa79db78bd11b0\") " pod="kube-system/kube-scheduler-ip-172-31-18-206" Aug 13 07:10:32.744985 kubelet[2787]: I0813 07:10:32.744961 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-ca-certs\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:32.745213 kubelet[2787]: I0813 07:10:32.744994 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-k8s-certs\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:32.745213 kubelet[2787]: I0813 07:10:32.745035 2787 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-usr-share-ca-certificates\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:32.745480 kubelet[2787]: E0813 07:10:32.745418 2787 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.18.206:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/ip-172-31-18-206?timeout=10s\": dial tcp 172.31.18.206:6443: connect: connection refused" interval="400ms" Aug 13 07:10:32.751196 systemd[1]: Created slice kubepods-burstable-podf8b60b4d82f810bebdaa79db78bd11b0.slice - libcontainer container kubepods-burstable-podf8b60b4d82f810bebdaa79db78bd11b0.slice. Aug 13 07:10:32.753868 kubelet[2787]: I0813 07:10:32.753665 2787 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:32.755379 kubelet[2787]: E0813 07:10:32.755324 2787 kubelet_node_status.go:95] "Unable to register node with API server" err="Post \"https://172.31.18.206:6443/api/v1/nodes\": dial tcp 172.31.18.206:6443: connect: connection refused" node="ip-172-31-18-206" Aug 13 07:10:32.958187 kubelet[2787]: I0813 07:10:32.958049 2787 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:32.958907 kubelet[2787]: E0813 07:10:32.958499 2787 kubelet_node_status.go:95] "Unable to register node with API server" err="Post \"https://172.31.18.206:6443/api/v1/nodes\": dial tcp 172.31.18.206:6443: connect: connection refused" node="ip-172-31-18-206" Aug 13 07:10:33.024687 containerd[1948]: time="2025-08-13T07:10:33.024618422Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-apiserver-ip-172-31-18-206,Uid:92a374eb991156349bf84c769068e6b9,Namespace:kube-system,Attempt:0,}" Aug 13 07:10:33.045624 containerd[1948]: time="2025-08-13T07:10:33.045488534Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-controller-manager-ip-172-31-18-206,Uid:129f7ccd5d4c83b2f7bc10a9ff0b8c3d,Namespace:kube-system,Attempt:0,}" Aug 13 07:10:33.059378 containerd[1948]: time="2025-08-13T07:10:33.059312006Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-scheduler-ip-172-31-18-206,Uid:f8b60b4d82f810bebdaa79db78bd11b0,Namespace:kube-system,Attempt:0,}" Aug 13 07:10:33.146679 kubelet[2787]: E0813 07:10:33.146605 2787 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.18.206:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/ip-172-31-18-206?timeout=10s\": dial tcp 172.31.18.206:6443: connect: connection refused" interval="800ms" Aug 13 07:10:33.361964 kubelet[2787]: I0813 07:10:33.361654 2787 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:33.362642 kubelet[2787]: E0813 07:10:33.362587 2787 kubelet_node_status.go:95] "Unable to register node with API server" err="Post \"https://172.31.18.206:6443/api/v1/nodes\": dial tcp 172.31.18.206:6443: connect: connection refused" node="ip-172-31-18-206" Aug 13 07:10:33.400608 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 13 07:10:33.461052 kubelet[2787]: W0813 07:10:33.460969 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: Get "https://172.31.18.206:6443/api/v1/nodes?fieldSelector=metadata.name%3Dip-172-31-18-206&limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:33.461634 kubelet[2787]: E0813 07:10:33.461075 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: Get \"https://172.31.18.206:6443/api/v1/nodes?fieldSelector=metadata.name%3Dip-172-31-18-206&limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:33.510952 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3454961377.mount: Deactivated successfully. Aug 13 07:10:33.522648 containerd[1948]: time="2025-08-13T07:10:33.522570796Z" 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\"}" Aug 13 07:10:33.526755 containerd[1948]: time="2025-08-13T07:10:33.526663492Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Aug 13 07:10:33.538833 containerd[1948]: time="2025-08-13T07:10:33.538726408Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 07:10:33.541139 containerd[1948]: time="2025-08-13T07:10:33.541069516Z" 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\"}" Aug 13 07:10:33.542925 containerd[1948]: time="2025-08-13T07:10:33.542855524Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 13 07:10:33.544562 containerd[1948]: time="2025-08-13T07:10:33.544492108Z" 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\"}" Aug 13 07:10:33.546694 containerd[1948]: time="2025-08-13T07:10:33.546613360Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 13 07:10:33.548813 containerd[1948]: time="2025-08-13T07:10:33.548743036Z" 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\"}" Aug 13 07:10:33.550650 containerd[1948]: time="2025-08-13T07:10:33.550374160Z" 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 525.639878ms" Aug 13 07:10:33.561268 containerd[1948]: time="2025-08-13T07:10:33.561201304Z" 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 515.510642ms" Aug 13 07:10:33.562940 containerd[1948]: time="2025-08-13T07:10:33.562872868Z" 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 503.44835ms" Aug 13 07:10:33.590152 kubelet[2787]: W0813 07:10:33.589878 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.RuntimeClass: Get "https://172.31.18.206:6443/apis/node.k8s.io/v1/runtimeclasses?limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:33.590152 kubelet[2787]: E0813 07:10:33.589955 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.RuntimeClass: failed to list *v1.RuntimeClass: Get \"https://172.31.18.206:6443/apis/node.k8s.io/v1/runtimeclasses?limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:33.678159 kubelet[2787]: W0813 07:10:33.678016 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: Get "https://172.31.18.206:6443/apis/storage.k8s.io/v1/csidrivers?limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:33.679198 kubelet[2787]: E0813 07:10:33.679098 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: Get \"https://172.31.18.206:6443/apis/storage.k8s.io/v1/csidrivers?limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:33.787250 containerd[1948]: time="2025-08-13T07:10:33.787071173Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 07:10:33.787793 containerd[1948]: time="2025-08-13T07:10:33.787600277Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 07:10:33.789193 containerd[1948]: time="2025-08-13T07:10:33.788846789Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.789193 containerd[1948]: time="2025-08-13T07:10:33.789046649Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.793551 containerd[1948]: time="2025-08-13T07:10:33.793356617Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 07:10:33.794234 containerd[1948]: time="2025-08-13T07:10:33.793476065Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 07:10:33.795452 containerd[1948]: time="2025-08-13T07:10:33.794180417Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.796788 containerd[1948]: time="2025-08-13T07:10:33.796668977Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.801557 containerd[1948]: time="2025-08-13T07:10:33.801327605Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 07:10:33.802177 containerd[1948]: time="2025-08-13T07:10:33.802012373Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 07:10:33.802177 containerd[1948]: time="2025-08-13T07:10:33.802126121Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.803375 containerd[1948]: time="2025-08-13T07:10:33.803146685Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 07:10:33.809962 kubelet[2787]: W0813 07:10:33.809787 2787 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: Get "https://172.31.18.206:6443/api/v1/services?fieldSelector=spec.clusterIP%21%3DNone&limit=500&resourceVersion=0": dial tcp 172.31.18.206:6443: connect: connection refused Aug 13 07:10:33.809962 kubelet[2787]: E0813 07:10:33.809893 2787 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: Get \"https://172.31.18.206:6443/api/v1/services?fieldSelector=spec.clusterIP%21%3DNone&limit=500&resourceVersion=0\": dial tcp 172.31.18.206:6443: connect: connection refused" logger="UnhandledError" Aug 13 07:10:33.845866 systemd[1]: Started cri-containerd-e577720fd51f899bdf555a1ce38b0abae4495b7bdbd82de87ccf07834be5676a.scope - libcontainer container e577720fd51f899bdf555a1ce38b0abae4495b7bdbd82de87ccf07834be5676a. Aug 13 07:10:33.859438 systemd[1]: Started cri-containerd-703f8c9a4bac52cd939dfbdf83f970df722f5f819ba50619bd747d1f1db5fe4c.scope - libcontainer container 703f8c9a4bac52cd939dfbdf83f970df722f5f819ba50619bd747d1f1db5fe4c. Aug 13 07:10:33.882853 systemd[1]: Started cri-containerd-6eda53f1b31e38114ab456dd6fa850cf3c5759f2d8f555198f176c7abadd185e.scope - libcontainer container 6eda53f1b31e38114ab456dd6fa850cf3c5759f2d8f555198f176c7abadd185e. Aug 13 07:10:33.951189 kubelet[2787]: E0813 07:10:33.950961 2787 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.18.206:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/ip-172-31-18-206?timeout=10s\": dial tcp 172.31.18.206:6443: connect: connection refused" interval="1.6s" Aug 13 07:10:33.966819 containerd[1948]: time="2025-08-13T07:10:33.966754458Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-scheduler-ip-172-31-18-206,Uid:f8b60b4d82f810bebdaa79db78bd11b0,Namespace:kube-system,Attempt:0,} returns sandbox id \"e577720fd51f899bdf555a1ce38b0abae4495b7bdbd82de87ccf07834be5676a\"" Aug 13 07:10:33.984551 containerd[1948]: time="2025-08-13T07:10:33.984339558Z" level=info msg="CreateContainer within sandbox \"e577720fd51f899bdf555a1ce38b0abae4495b7bdbd82de87ccf07834be5676a\" for container &ContainerMetadata{Name:kube-scheduler,Attempt:0,}" Aug 13 07:10:34.001949 containerd[1948]: time="2025-08-13T07:10:34.001889402Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-apiserver-ip-172-31-18-206,Uid:92a374eb991156349bf84c769068e6b9,Namespace:kube-system,Attempt:0,} returns sandbox id \"703f8c9a4bac52cd939dfbdf83f970df722f5f819ba50619bd747d1f1db5fe4c\"" Aug 13 07:10:34.010952 containerd[1948]: time="2025-08-13T07:10:34.010799786Z" level=info msg="CreateContainer within sandbox \"703f8c9a4bac52cd939dfbdf83f970df722f5f819ba50619bd747d1f1db5fe4c\" for container &ContainerMetadata{Name:kube-apiserver,Attempt:0,}" Aug 13 07:10:34.026852 containerd[1948]: time="2025-08-13T07:10:34.026624846Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-controller-manager-ip-172-31-18-206,Uid:129f7ccd5d4c83b2f7bc10a9ff0b8c3d,Namespace:kube-system,Attempt:0,} returns sandbox id \"6eda53f1b31e38114ab456dd6fa850cf3c5759f2d8f555198f176c7abadd185e\"" Aug 13 07:10:34.034831 containerd[1948]: time="2025-08-13T07:10:34.034318947Z" level=info msg="CreateContainer within sandbox \"6eda53f1b31e38114ab456dd6fa850cf3c5759f2d8f555198f176c7abadd185e\" for container &ContainerMetadata{Name:kube-controller-manager,Attempt:0,}" Aug 13 07:10:34.034831 containerd[1948]: time="2025-08-13T07:10:34.034623543Z" level=info msg="CreateContainer within sandbox \"e577720fd51f899bdf555a1ce38b0abae4495b7bdbd82de87ccf07834be5676a\" for &ContainerMetadata{Name:kube-scheduler,Attempt:0,} returns container id \"28655d8cf6668fdcd1f392dd16a0aa331aa4e87d91a12184f96e4b851fae5db2\"" Aug 13 07:10:34.035800 containerd[1948]: time="2025-08-13T07:10:34.035754219Z" level=info msg="StartContainer for \"28655d8cf6668fdcd1f392dd16a0aa331aa4e87d91a12184f96e4b851fae5db2\"" Aug 13 07:10:34.050472 containerd[1948]: time="2025-08-13T07:10:34.050409675Z" level=info msg="CreateContainer within sandbox \"703f8c9a4bac52cd939dfbdf83f970df722f5f819ba50619bd747d1f1db5fe4c\" for &ContainerMetadata{Name:kube-apiserver,Attempt:0,} returns container id \"be621d8a7fee487c36f1da213316274d01fef20fd9e55d125178dbd78ce6768e\"" Aug 13 07:10:34.051860 containerd[1948]: time="2025-08-13T07:10:34.051746931Z" level=info msg="StartContainer for \"be621d8a7fee487c36f1da213316274d01fef20fd9e55d125178dbd78ce6768e\"" Aug 13 07:10:34.076467 containerd[1948]: time="2025-08-13T07:10:34.075981171Z" level=info msg="CreateContainer within sandbox \"6eda53f1b31e38114ab456dd6fa850cf3c5759f2d8f555198f176c7abadd185e\" for &ContainerMetadata{Name:kube-controller-manager,Attempt:0,} returns container id \"952b811aaf85d30cbd568a67b5c2cdae53de8d39ea673da538b82b37d1edc85d\"" Aug 13 07:10:34.077299 containerd[1948]: time="2025-08-13T07:10:34.077244015Z" level=info msg="StartContainer for \"952b811aaf85d30cbd568a67b5c2cdae53de8d39ea673da538b82b37d1edc85d\"" Aug 13 07:10:34.098987 systemd[1]: Started cri-containerd-28655d8cf6668fdcd1f392dd16a0aa331aa4e87d91a12184f96e4b851fae5db2.scope - libcontainer container 28655d8cf6668fdcd1f392dd16a0aa331aa4e87d91a12184f96e4b851fae5db2. Aug 13 07:10:34.132873 systemd[1]: Started cri-containerd-be621d8a7fee487c36f1da213316274d01fef20fd9e55d125178dbd78ce6768e.scope - libcontainer container be621d8a7fee487c36f1da213316274d01fef20fd9e55d125178dbd78ce6768e. Aug 13 07:10:34.168048 kubelet[2787]: I0813 07:10:34.168008 2787 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:34.169731 kubelet[2787]: E0813 07:10:34.169622 2787 kubelet_node_status.go:95] "Unable to register node with API server" err="Post \"https://172.31.18.206:6443/api/v1/nodes\": dial tcp 172.31.18.206:6443: connect: connection refused" node="ip-172-31-18-206" Aug 13 07:10:34.181814 systemd[1]: Started cri-containerd-952b811aaf85d30cbd568a67b5c2cdae53de8d39ea673da538b82b37d1edc85d.scope - libcontainer container 952b811aaf85d30cbd568a67b5c2cdae53de8d39ea673da538b82b37d1edc85d. Aug 13 07:10:34.228358 containerd[1948]: time="2025-08-13T07:10:34.228202071Z" level=info msg="StartContainer for \"28655d8cf6668fdcd1f392dd16a0aa331aa4e87d91a12184f96e4b851fae5db2\" returns successfully" Aug 13 07:10:34.277555 containerd[1948]: time="2025-08-13T07:10:34.277293640Z" level=info msg="StartContainer for \"be621d8a7fee487c36f1da213316274d01fef20fd9e55d125178dbd78ce6768e\" returns successfully" Aug 13 07:10:34.327552 containerd[1948]: time="2025-08-13T07:10:34.326477764Z" level=info msg="StartContainer for \"952b811aaf85d30cbd568a67b5c2cdae53de8d39ea673da538b82b37d1edc85d\" returns successfully" Aug 13 07:10:35.774155 kubelet[2787]: I0813 07:10:35.774105 2787 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:38.294223 kubelet[2787]: I0813 07:10:38.294164 2787 kubelet_node_status.go:75] "Successfully registered node" node="ip-172-31-18-206" Aug 13 07:10:38.364001 kubelet[2787]: E0813 07:10:38.363616 2787 event.go:359] "Server rejected event (will not retry!)" err="namespaces \"default\" not found" event="&Event{ObjectMeta:{ip-172-31-18-206.185b41fc7f31e3c7 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:ip-172-31-18-206,UID:ip-172-31-18-206,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:ip-172-31-18-206,},FirstTimestamp:2025-08-13 07:10:32.512480199 +0000 UTC m=+2.366711389,LastTimestamp:2025-08-13 07:10:32.512480199 +0000 UTC m=+2.366711389,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:ip-172-31-18-206,}" Aug 13 07:10:38.434294 kubelet[2787]: E0813 07:10:38.434233 2787 controller.go:145] "Failed to ensure lease exists, will retry" err="namespaces \"kube-node-lease\" not found" interval="3.2s" Aug 13 07:10:38.523210 kubelet[2787]: I0813 07:10:38.523148 2787 apiserver.go:52] "Watching apiserver" Aug 13 07:10:38.644594 kubelet[2787]: I0813 07:10:38.644404 2787 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Aug 13 07:10:40.633188 systemd[1]: Reload requested from client PID 3070 ('systemctl') (unit session-5.scope)... Aug 13 07:10:40.633219 systemd[1]: Reloading... Aug 13 07:10:40.903836 zram_generator::config[3118]: No configuration found. Aug 13 07:10:41.163427 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. Aug 13 07:10:41.421080 systemd[1]: Reloading finished in 787 ms. Aug 13 07:10:41.476716 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:41.490412 systemd[1]: kubelet.service: Deactivated successfully. Aug 13 07:10:41.490936 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:41.491029 systemd[1]: kubelet.service: Consumed 3.105s CPU time, 128.7M memory peak. Aug 13 07:10:41.498263 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 07:10:41.882892 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 07:10:41.893255 (kubelet)[3175]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 13 07:10:41.981097 kubelet[3175]: 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. Aug 13 07:10:41.981097 kubelet[3175]: 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. Aug 13 07:10:41.981582 kubelet[3175]: 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. Aug 13 07:10:41.981582 kubelet[3175]: I0813 07:10:41.981237 3175 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 13 07:10:41.993402 kubelet[3175]: I0813 07:10:41.992990 3175 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Aug 13 07:10:41.993402 kubelet[3175]: I0813 07:10:41.993041 3175 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 13 07:10:41.993655 kubelet[3175]: I0813 07:10:41.993453 3175 server.go:934] "Client rotation is on, will bootstrap in background" Aug 13 07:10:42.004982 kubelet[3175]: I0813 07:10:42.004869 3175 certificate_store.go:130] Loading cert/key pair from "/var/lib/kubelet/pki/kubelet-client-current.pem". Aug 13 07:10:42.014120 kubelet[3175]: I0813 07:10:42.013513 3175 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 13 07:10:42.023713 kubelet[3175]: E0813 07:10:42.023621 3175 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Aug 13 07:10:42.023921 kubelet[3175]: I0813 07:10:42.023894 3175 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Aug 13 07:10:42.031356 kubelet[3175]: I0813 07:10:42.031317 3175 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 13 07:10:42.032897 kubelet[3175]: I0813 07:10:42.031923 3175 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Aug 13 07:10:42.033462 kubelet[3175]: I0813 07:10:42.033406 3175 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 13 07:10:42.033998 kubelet[3175]: I0813 07:10:42.033603 3175 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"ip-172-31-18-206","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Aug 13 07:10:42.034499 kubelet[3175]: I0813 07:10:42.034362 3175 topology_manager.go:138] "Creating topology manager with none policy" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034620 3175 container_manager_linux.go:300] "Creating device plugin manager" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034701 3175 state_mem.go:36] "Initialized new in-memory state store" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034891 3175 kubelet.go:408] "Attempting to sync node with API server" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034917 3175 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034952 3175 kubelet.go:314] "Adding apiserver pod source" Aug 13 07:10:42.035423 kubelet[3175]: I0813 07:10:42.034980 3175 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 13 07:10:42.046748 kubelet[3175]: I0813 07:10:42.045711 3175 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Aug 13 07:10:42.046748 kubelet[3175]: I0813 07:10:42.046449 3175 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 13 07:10:42.049548 kubelet[3175]: I0813 07:10:42.048261 3175 server.go:1274] "Started kubelet" Aug 13 07:10:42.056558 kubelet[3175]: I0813 07:10:42.055405 3175 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 13 07:10:42.067588 kubelet[3175]: I0813 07:10:42.067087 3175 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Aug 13 07:10:42.074906 kubelet[3175]: I0813 07:10:42.074867 3175 server.go:449] "Adding debug handlers to kubelet server" Aug 13 07:10:42.083632 kubelet[3175]: I0813 07:10:42.081502 3175 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 13 07:10:42.086838 kubelet[3175]: I0813 07:10:42.084150 3175 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 13 07:10:42.088746 kubelet[3175]: I0813 07:10:42.088691 3175 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Aug 13 07:10:42.090173 kubelet[3175]: I0813 07:10:42.089769 3175 volume_manager.go:289] "Starting Kubelet Volume Manager" Aug 13 07:10:42.099393 kubelet[3175]: E0813 07:10:42.094809 3175 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"ip-172-31-18-206\" not found" Aug 13 07:10:42.106470 kubelet[3175]: I0813 07:10:42.106419 3175 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Aug 13 07:10:42.107867 kubelet[3175]: I0813 07:10:42.107843 3175 reconciler.go:26] "Reconciler: start to sync state" Aug 13 07:10:42.121560 kubelet[3175]: I0813 07:10:42.119423 3175 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 Aug 13 07:10:42.152073 kubelet[3175]: I0813 07:10:42.149910 3175 factory.go:221] Registration of the containerd container factory successfully Aug 13 07:10:42.152073 kubelet[3175]: I0813 07:10:42.149957 3175 factory.go:221] Registration of the systemd container factory successfully Aug 13 07:10:42.155960 kubelet[3175]: E0813 07:10:42.155913 3175 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 13 07:10:42.177480 kubelet[3175]: I0813 07:10:42.177419 3175 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 13 07:10:42.182078 sudo[2230]: pam_unix(sudo:session): session closed for user root Aug 13 07:10:42.190389 kubelet[3175]: I0813 07:10:42.188686 3175 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 13 07:10:42.190389 kubelet[3175]: I0813 07:10:42.188731 3175 status_manager.go:217] "Starting to sync pod status with apiserver" Aug 13 07:10:42.190389 kubelet[3175]: I0813 07:10:42.188764 3175 kubelet.go:2321] "Starting kubelet main sync loop" Aug 13 07:10:42.190389 kubelet[3175]: E0813 07:10:42.188858 3175 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Aug 13 07:10:42.196975 kubelet[3175]: E0813 07:10:42.196716 3175 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"ip-172-31-18-206\" not found" Aug 13 07:10:42.209261 sshd[2229]: Connection closed by 139.178.89.65 port 34056 Aug 13 07:10:42.209805 sshd-session[2227]: pam_unix(sshd:session): session closed for user core Aug 13 07:10:42.220863 systemd-logind[1926]: Session 5 logged out. Waiting for processes to exit. Aug 13 07:10:42.223355 systemd[1]: sshd@4-172.31.18.206:22-139.178.89.65:34056.service: Deactivated successfully. Aug 13 07:10:42.237243 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 07:10:42.238572 systemd[1]: session-5.scope: Consumed 8.115s CPU time, 164.1M memory peak. Aug 13 07:10:42.244478 systemd-logind[1926]: Removed session 5. Aug 13 07:10:42.289454 kubelet[3175]: E0813 07:10:42.288918 3175 kubelet.go:2345] "Skipping pod synchronization" err="container runtime status check may not have completed yet" Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.288994 3175 cpu_manager.go:214] "Starting CPU manager" policy="none" Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.289011 3175 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.289043 3175 state_mem.go:36] "Initialized new in-memory state store" Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.289284 3175 state_mem.go:88] "Updated default CPUSet" cpuSet="" Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.289304 3175 state_mem.go:96] "Updated CPUSet assignments" assignments={} Aug 13 07:10:42.289454 kubelet[3175]: I0813 07:10:42.289337 3175 policy_none.go:49] "None policy: Start" Aug 13 07:10:42.290566 kubelet[3175]: I0813 07:10:42.290436 3175 memory_manager.go:170] "Starting memorymanager" policy="None" Aug 13 07:10:42.290566 kubelet[3175]: I0813 07:10:42.290475 3175 state_mem.go:35] "Initializing new in-memory state store" Aug 13 07:10:42.290787 kubelet[3175]: I0813 07:10:42.290756 3175 state_mem.go:75] "Updated machine memory state" Aug 13 07:10:42.298440 kubelet[3175]: I0813 07:10:42.298381 3175 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 13 07:10:42.299055 kubelet[3175]: I0813 07:10:42.298703 3175 eviction_manager.go:189] "Eviction manager: starting control loop" Aug 13 07:10:42.299055 kubelet[3175]: I0813 07:10:42.298735 3175 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 13 07:10:42.300201 kubelet[3175]: I0813 07:10:42.299905 3175 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 13 07:10:42.418110 kubelet[3175]: I0813 07:10:42.417991 3175 kubelet_node_status.go:72] "Attempting to register node" node="ip-172-31-18-206" Aug 13 07:10:42.435216 kubelet[3175]: I0813 07:10:42.435047 3175 kubelet_node_status.go:111] "Node was previously registered" node="ip-172-31-18-206" Aug 13 07:10:42.435976 kubelet[3175]: I0813 07:10:42.435513 3175 kubelet_node_status.go:75] "Successfully registered node" node="ip-172-31-18-206" Aug 13 07:10:42.502799 kubelet[3175]: E0813 07:10:42.502750 3175 kubelet.go:1915] "Failed creating a mirror pod for" err="pods \"kube-apiserver-ip-172-31-18-206\" already exists" pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:42.510363 kubelet[3175]: I0813 07:10:42.510312 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-k8s-certs\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:42.510460 kubelet[3175]: I0813 07:10:42.510370 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-usr-share-ca-certificates\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:42.510460 kubelet[3175]: I0813 07:10:42.510418 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-kubeconfig\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:42.510617 kubelet[3175]: I0813 07:10:42.510455 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"usr-share-ca-certificates\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-usr-share-ca-certificates\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:42.510617 kubelet[3175]: I0813 07:10:42.510496 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kubeconfig\" (UniqueName: \"kubernetes.io/host-path/f8b60b4d82f810bebdaa79db78bd11b0-kubeconfig\") pod \"kube-scheduler-ip-172-31-18-206\" (UID: \"f8b60b4d82f810bebdaa79db78bd11b0\") " pod="kube-system/kube-scheduler-ip-172-31-18-206" Aug 13 07:10:42.510617 kubelet[3175]: I0813 07:10:42.510550 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/92a374eb991156349bf84c769068e6b9-ca-certs\") pod \"kube-apiserver-ip-172-31-18-206\" (UID: \"92a374eb991156349bf84c769068e6b9\") " pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:42.510617 kubelet[3175]: I0813 07:10:42.510587 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"ca-certs\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-ca-certs\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:42.510807 kubelet[3175]: I0813 07:10:42.510621 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flexvolume-dir\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-flexvolume-dir\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:42.510807 kubelet[3175]: I0813 07:10:42.510659 3175 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"k8s-certs\" (UniqueName: \"kubernetes.io/host-path/129f7ccd5d4c83b2f7bc10a9ff0b8c3d-k8s-certs\") pod \"kube-controller-manager-ip-172-31-18-206\" (UID: \"129f7ccd5d4c83b2f7bc10a9ff0b8c3d\") " pod="kube-system/kube-controller-manager-ip-172-31-18-206" Aug 13 07:10:43.039402 kubelet[3175]: I0813 07:10:43.039058 3175 apiserver.go:52] "Watching apiserver" Aug 13 07:10:43.107817 kubelet[3175]: I0813 07:10:43.107710 3175 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Aug 13 07:10:43.257967 kubelet[3175]: E0813 07:10:43.257904 3175 kubelet.go:1915] "Failed creating a mirror pod for" err="pods \"kube-apiserver-ip-172-31-18-206\" already exists" pod="kube-system/kube-apiserver-ip-172-31-18-206" Aug 13 07:10:43.283872 kubelet[3175]: I0813 07:10:43.283557 3175 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-apiserver-ip-172-31-18-206" podStartSLOduration=3.283534992 podStartE2EDuration="3.283534992s" podCreationTimestamp="2025-08-13 07:10:40 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2025-08-13 07:10:43.282678516 +0000 UTC m=+1.381605175" watchObservedRunningTime="2025-08-13 07:10:43.283534992 +0000 UTC m=+1.382461639" Aug 13 07:10:43.298548 kubelet[3175]: I0813 07:10:43.298160 3175 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-scheduler-ip-172-31-18-206" podStartSLOduration=1.298137001 podStartE2EDuration="1.298137001s" podCreationTimestamp="2025-08-13 07:10:42 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2025-08-13 07:10:43.297741433 +0000 UTC m=+1.396668080" watchObservedRunningTime="2025-08-13 07:10:43.298137001 +0000 UTC m=+1.397063624" Aug 13 07:10:43.313976 kubelet[3175]: I0813 07:10:43.313464 3175 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-controller-manager-ip-172-31-18-206" podStartSLOduration=1.313442209 podStartE2EDuration="1.313442209s" podCreationTimestamp="2025-08-13 07:10:42 +0000 UTC" firstStartedPulling="0001-01-01 00:00:00 +0000 UTC" lastFinishedPulling="0001-01-01 00:00:00 +0000 UTC" observedRunningTime="2025-08-13 07:10:43.311188297 +0000 UTC m=+1.410114956" watchObservedRunningTime="2025-08-13 07:10:43.313442209 +0000 UTC m=+1.412368880"