Nov 8 00:05:06.258914 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 8 00:05:06.258959 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Fri Nov 7 22:41:39 -00 2025 Nov 8 00:05:06.258984 kernel: KASLR disabled due to lack of seed Nov 8 00:05:06.259001 kernel: efi: EFI v2.7 by EDK II Nov 8 00:05:06.259017 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7affea98 MEMRESERVE=0x7852ee18 Nov 8 00:05:06.259032 kernel: ACPI: Early table checksum verification disabled Nov 8 00:05:06.259050 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 8 00:05:06.259087 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 8 00:05:06.259107 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 8 00:05:06.259123 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 8 00:05:06.259146 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 8 00:05:06.259163 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 8 00:05:06.259179 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 8 00:05:06.259196 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 8 00:05:06.259214 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 8 00:05:06.259236 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 8 00:05:06.259253 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 8 00:05:06.259270 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 8 00:05:06.259287 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 8 00:05:06.259304 kernel: printk: bootconsole [uart0] enabled Nov 8 00:05:06.259321 kernel: NUMA: Failed to initialise from firmware Nov 8 00:05:06.259338 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 8 00:05:06.259355 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 8 00:05:06.259371 kernel: Zone ranges: Nov 8 00:05:06.259389 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 8 00:05:06.259405 kernel: DMA32 empty Nov 8 00:05:06.259426 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 8 00:05:06.259443 kernel: Movable zone start for each node Nov 8 00:05:06.259460 kernel: Early memory node ranges Nov 8 00:05:06.259476 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 8 00:05:06.259493 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 8 00:05:06.259510 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 8 00:05:06.259526 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 8 00:05:06.259543 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 8 00:05:06.259560 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 8 00:05:06.259577 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 8 00:05:06.259593 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 8 00:05:06.259610 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 8 00:05:06.259631 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 8 00:05:06.259649 kernel: psci: probing for conduit method from ACPI. Nov 8 00:05:06.259673 kernel: psci: PSCIv1.0 detected in firmware. Nov 8 00:05:06.259691 kernel: psci: Using standard PSCI v0.2 function IDs Nov 8 00:05:06.259709 kernel: psci: Trusted OS migration not required Nov 8 00:05:06.259730 kernel: psci: SMC Calling Convention v1.1 Nov 8 00:05:06.259749 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Nov 8 00:05:06.259766 kernel: percpu: Embedded 31 pages/cpu s86120 r8192 d32664 u126976 Nov 8 00:05:06.259804 kernel: pcpu-alloc: s86120 r8192 d32664 u126976 alloc=31*4096 Nov 8 00:05:06.259825 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 8 00:05:06.259843 kernel: Detected PIPT I-cache on CPU0 Nov 8 00:05:06.259861 kernel: CPU features: detected: GIC system register CPU interface Nov 8 00:05:06.259879 kernel: CPU features: detected: Spectre-v2 Nov 8 00:05:06.259897 kernel: CPU features: detected: Spectre-v3a Nov 8 00:05:06.259915 kernel: CPU features: detected: Spectre-BHB Nov 8 00:05:06.259933 kernel: CPU features: detected: ARM erratum 1742098 Nov 8 00:05:06.259957 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 8 00:05:06.259975 kernel: alternatives: applying boot alternatives Nov 8 00:05:06.259996 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=653fdcb8a67e255793a721f32d76976d3ed6223b235b7c618cf75e5edffbdb68 Nov 8 00:05:06.260015 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 8 00:05:06.260033 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 8 00:05:06.260051 kernel: Fallback order for Node 0: 0 Nov 8 00:05:06.262716 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 8 00:05:06.262743 kernel: Policy zone: Normal Nov 8 00:05:06.262761 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 8 00:05:06.262779 kernel: software IO TLB: area num 2. Nov 8 00:05:06.262797 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 8 00:05:06.262826 kernel: Memory: 3820088K/4030464K available (10304K kernel code, 2180K rwdata, 8112K rodata, 39424K init, 897K bss, 210376K reserved, 0K cma-reserved) Nov 8 00:05:06.262845 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 8 00:05:06.262862 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 8 00:05:06.262881 kernel: rcu: RCU event tracing is enabled. Nov 8 00:05:06.262899 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 8 00:05:06.262918 kernel: Trampoline variant of Tasks RCU enabled. Nov 8 00:05:06.262935 kernel: Tracing variant of Tasks RCU enabled. Nov 8 00:05:06.262953 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 8 00:05:06.262971 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 8 00:05:06.262989 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 8 00:05:06.263006 kernel: GICv3: 96 SPIs implemented Nov 8 00:05:06.263028 kernel: GICv3: 0 Extended SPIs implemented Nov 8 00:05:06.263046 kernel: Root IRQ handler: gic_handle_irq Nov 8 00:05:06.263082 kernel: GICv3: GICv3 features: 16 PPIs Nov 8 00:05:06.263102 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 8 00:05:06.263120 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 8 00:05:06.263138 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 8 00:05:06.263157 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 8 00:05:06.263175 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 8 00:05:06.263192 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 8 00:05:06.263210 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 8 00:05:06.263228 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 8 00:05:06.263245 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 8 00:05:06.263270 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 8 00:05:06.263288 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 8 00:05:06.263307 kernel: Console: colour dummy device 80x25 Nov 8 00:05:06.263325 kernel: printk: console [tty1] enabled Nov 8 00:05:06.263358 kernel: ACPI: Core revision 20230628 Nov 8 00:05:06.263378 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 8 00:05:06.263397 kernel: pid_max: default: 32768 minimum: 301 Nov 8 00:05:06.263415 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 8 00:05:06.263433 kernel: landlock: Up and running. Nov 8 00:05:06.263456 kernel: SELinux: Initializing. Nov 8 00:05:06.263475 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 00:05:06.263493 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 00:05:06.263511 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 8 00:05:06.263529 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 8 00:05:06.263548 kernel: rcu: Hierarchical SRCU implementation. Nov 8 00:05:06.263566 kernel: rcu: Max phase no-delay instances is 400. Nov 8 00:05:06.263584 kernel: Platform MSI: ITS@0x10080000 domain created Nov 8 00:05:06.263602 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 8 00:05:06.263624 kernel: Remapping and enabling EFI services. Nov 8 00:05:06.263642 kernel: smp: Bringing up secondary CPUs ... Nov 8 00:05:06.263660 kernel: Detected PIPT I-cache on CPU1 Nov 8 00:05:06.263678 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 8 00:05:06.263697 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 8 00:05:06.263715 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 8 00:05:06.263732 kernel: smp: Brought up 1 node, 2 CPUs Nov 8 00:05:06.263751 kernel: SMP: Total of 2 processors activated. Nov 8 00:05:06.263768 kernel: CPU features: detected: 32-bit EL0 Support Nov 8 00:05:06.263791 kernel: CPU features: detected: 32-bit EL1 Support Nov 8 00:05:06.263809 kernel: CPU features: detected: CRC32 instructions Nov 8 00:05:06.263827 kernel: CPU: All CPU(s) started at EL1 Nov 8 00:05:06.263857 kernel: alternatives: applying system-wide alternatives Nov 8 00:05:06.263880 kernel: devtmpfs: initialized Nov 8 00:05:06.263899 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 8 00:05:06.263918 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 8 00:05:06.263949 kernel: pinctrl core: initialized pinctrl subsystem Nov 8 00:05:06.263971 kernel: SMBIOS 3.0.0 present. Nov 8 00:05:06.263996 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 8 00:05:06.264015 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 8 00:05:06.264034 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 8 00:05:06.264053 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 8 00:05:06.264093 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 8 00:05:06.264113 kernel: audit: initializing netlink subsys (disabled) Nov 8 00:05:06.264133 kernel: audit: type=2000 audit(0.298:1): state=initialized audit_enabled=0 res=1 Nov 8 00:05:06.264152 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 8 00:05:06.264180 kernel: cpuidle: using governor menu Nov 8 00:05:06.264200 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 8 00:05:06.264220 kernel: ASID allocator initialised with 65536 entries Nov 8 00:05:06.264239 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 8 00:05:06.264259 kernel: Serial: AMBA PL011 UART driver Nov 8 00:05:06.264278 kernel: Modules: 17488 pages in range for non-PLT usage Nov 8 00:05:06.264320 kernel: Modules: 509008 pages in range for PLT usage Nov 8 00:05:06.264341 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 8 00:05:06.264361 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 8 00:05:06.264387 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 8 00:05:06.264408 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 8 00:05:06.264432 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 8 00:05:06.264451 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 8 00:05:06.264471 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 8 00:05:06.264490 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 8 00:05:06.264509 kernel: ACPI: Added _OSI(Module Device) Nov 8 00:05:06.264529 kernel: ACPI: Added _OSI(Processor Device) Nov 8 00:05:06.264548 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 8 00:05:06.264571 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 8 00:05:06.264591 kernel: ACPI: Interpreter enabled Nov 8 00:05:06.264610 kernel: ACPI: Using GIC for interrupt routing Nov 8 00:05:06.264630 kernel: ACPI: MCFG table detected, 1 entries Nov 8 00:05:06.264649 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 8 00:05:06.265026 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 8 00:05:06.267445 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 8 00:05:06.267681 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 8 00:05:06.267889 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 8 00:05:06.268121 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 8 00:05:06.268149 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 8 00:05:06.268169 kernel: acpiphp: Slot [1] registered Nov 8 00:05:06.268189 kernel: acpiphp: Slot [2] registered Nov 8 00:05:06.268209 kernel: acpiphp: Slot [3] registered Nov 8 00:05:06.268228 kernel: acpiphp: Slot [4] registered Nov 8 00:05:06.268247 kernel: acpiphp: Slot [5] registered Nov 8 00:05:06.268273 kernel: acpiphp: Slot [6] registered Nov 8 00:05:06.268314 kernel: acpiphp: Slot [7] registered Nov 8 00:05:06.268335 kernel: acpiphp: Slot [8] registered Nov 8 00:05:06.268354 kernel: acpiphp: Slot [9] registered Nov 8 00:05:06.268373 kernel: acpiphp: Slot [10] registered Nov 8 00:05:06.268392 kernel: acpiphp: Slot [11] registered Nov 8 00:05:06.268411 kernel: acpiphp: Slot [12] registered Nov 8 00:05:06.268430 kernel: acpiphp: Slot [13] registered Nov 8 00:05:06.268449 kernel: acpiphp: Slot [14] registered Nov 8 00:05:06.268469 kernel: acpiphp: Slot [15] registered Nov 8 00:05:06.268494 kernel: acpiphp: Slot [16] registered Nov 8 00:05:06.268513 kernel: acpiphp: Slot [17] registered Nov 8 00:05:06.268532 kernel: acpiphp: Slot [18] registered Nov 8 00:05:06.268551 kernel: acpiphp: Slot [19] registered Nov 8 00:05:06.268570 kernel: acpiphp: Slot [20] registered Nov 8 00:05:06.268588 kernel: acpiphp: Slot [21] registered Nov 8 00:05:06.268607 kernel: acpiphp: Slot [22] registered Nov 8 00:05:06.268626 kernel: acpiphp: Slot [23] registered Nov 8 00:05:06.268644 kernel: acpiphp: Slot [24] registered Nov 8 00:05:06.268668 kernel: acpiphp: Slot [25] registered Nov 8 00:05:06.268713 kernel: acpiphp: Slot [26] registered Nov 8 00:05:06.268734 kernel: acpiphp: Slot [27] registered Nov 8 00:05:06.268754 kernel: acpiphp: Slot [28] registered Nov 8 00:05:06.268773 kernel: acpiphp: Slot [29] registered Nov 8 00:05:06.268794 kernel: acpiphp: Slot [30] registered Nov 8 00:05:06.268813 kernel: acpiphp: Slot [31] registered Nov 8 00:05:06.268832 kernel: PCI host bridge to bus 0000:00 Nov 8 00:05:06.278083 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 8 00:05:06.278349 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 8 00:05:06.278544 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 8 00:05:06.278735 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 8 00:05:06.278982 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 8 00:05:06.279252 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 8 00:05:06.279472 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 8 00:05:06.281158 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 8 00:05:06.281402 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 8 00:05:06.281612 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 8 00:05:06.281840 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 8 00:05:06.282049 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 8 00:05:06.282293 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 8 00:05:06.282532 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 8 00:05:06.282761 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 8 00:05:06.282974 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 8 00:05:06.283353 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 8 00:05:06.283595 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 8 00:05:06.283837 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 8 00:05:06.284228 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 8 00:05:06.284469 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 8 00:05:06.284669 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 8 00:05:06.284879 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 8 00:05:06.284908 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 8 00:05:06.284928 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 8 00:05:06.284962 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 8 00:05:06.284982 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 8 00:05:06.285002 kernel: iommu: Default domain type: Translated Nov 8 00:05:06.285021 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 8 00:05:06.285046 kernel: efivars: Registered efivars operations Nov 8 00:05:06.285110 kernel: vgaarb: loaded Nov 8 00:05:06.285134 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 8 00:05:06.285154 kernel: VFS: Disk quotas dquot_6.6.0 Nov 8 00:05:06.285173 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 8 00:05:06.285192 kernel: pnp: PnP ACPI init Nov 8 00:05:06.285413 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 8 00:05:06.285441 kernel: pnp: PnP ACPI: found 1 devices Nov 8 00:05:06.285460 kernel: NET: Registered PF_INET protocol family Nov 8 00:05:06.285486 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 8 00:05:06.285505 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 8 00:05:06.285524 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 8 00:05:06.285543 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 8 00:05:06.285562 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 8 00:05:06.285581 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 8 00:05:06.285600 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 00:05:06.285619 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 00:05:06.285638 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 8 00:05:06.285661 kernel: PCI: CLS 0 bytes, default 64 Nov 8 00:05:06.285680 kernel: kvm [1]: HYP mode not available Nov 8 00:05:06.285699 kernel: Initialise system trusted keyrings Nov 8 00:05:06.285718 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 8 00:05:06.285737 kernel: Key type asymmetric registered Nov 8 00:05:06.285756 kernel: Asymmetric key parser 'x509' registered Nov 8 00:05:06.285774 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 8 00:05:06.285793 kernel: io scheduler mq-deadline registered Nov 8 00:05:06.285812 kernel: io scheduler kyber registered Nov 8 00:05:06.285847 kernel: io scheduler bfq registered Nov 8 00:05:06.288175 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 8 00:05:06.288225 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 8 00:05:06.288246 kernel: ACPI: button: Power Button [PWRB] Nov 8 00:05:06.288265 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 8 00:05:06.288302 kernel: ACPI: button: Sleep Button [SLPB] Nov 8 00:05:06.288328 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 8 00:05:06.288348 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 8 00:05:06.288606 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 8 00:05:06.288633 kernel: printk: console [ttyS0] disabled Nov 8 00:05:06.288654 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 8 00:05:06.288673 kernel: printk: console [ttyS0] enabled Nov 8 00:05:06.288692 kernel: printk: bootconsole [uart0] disabled Nov 8 00:05:06.288711 kernel: thunder_xcv, ver 1.0 Nov 8 00:05:06.288729 kernel: thunder_bgx, ver 1.0 Nov 8 00:05:06.288748 kernel: nicpf, ver 1.0 Nov 8 00:05:06.288767 kernel: nicvf, ver 1.0 Nov 8 00:05:06.289029 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 8 00:05:06.292045 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-08T00:05:05 UTC (1762560305) Nov 8 00:05:06.292201 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 8 00:05:06.292222 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 8 00:05:06.292243 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 8 00:05:06.292263 kernel: watchdog: Hard watchdog permanently disabled Nov 8 00:05:06.292282 kernel: NET: Registered PF_INET6 protocol family Nov 8 00:05:06.292324 kernel: Segment Routing with IPv6 Nov 8 00:05:06.292355 kernel: In-situ OAM (IOAM) with IPv6 Nov 8 00:05:06.292375 kernel: NET: Registered PF_PACKET protocol family Nov 8 00:05:06.292395 kernel: Key type dns_resolver registered Nov 8 00:05:06.292415 kernel: registered taskstats version 1 Nov 8 00:05:06.292434 kernel: Loading compiled-in X.509 certificates Nov 8 00:05:06.292454 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: e35af6a719ba4c60f9d6788b11f5e5836ebf73b5' Nov 8 00:05:06.292472 kernel: Key type .fscrypt registered Nov 8 00:05:06.292491 kernel: Key type fscrypt-provisioning registered Nov 8 00:05:06.292509 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 8 00:05:06.292533 kernel: ima: Allocated hash algorithm: sha1 Nov 8 00:05:06.292552 kernel: ima: No architecture policies found Nov 8 00:05:06.292572 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 8 00:05:06.292590 kernel: clk: Disabling unused clocks Nov 8 00:05:06.292609 kernel: Freeing unused kernel memory: 39424K Nov 8 00:05:06.292627 kernel: Run /init as init process Nov 8 00:05:06.292646 kernel: with arguments: Nov 8 00:05:06.292665 kernel: /init Nov 8 00:05:06.292683 kernel: with environment: Nov 8 00:05:06.292701 kernel: HOME=/ Nov 8 00:05:06.292725 kernel: TERM=linux Nov 8 00:05:06.292748 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 8 00:05:06.292772 systemd[1]: Detected virtualization amazon. Nov 8 00:05:06.292793 systemd[1]: Detected architecture arm64. Nov 8 00:05:06.292813 systemd[1]: Running in initrd. Nov 8 00:05:06.292834 systemd[1]: No hostname configured, using default hostname. Nov 8 00:05:06.292853 systemd[1]: Hostname set to . Nov 8 00:05:06.292879 systemd[1]: Initializing machine ID from VM UUID. Nov 8 00:05:06.292900 systemd[1]: Queued start job for default target initrd.target. Nov 8 00:05:06.292920 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:05:06.292940 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:05:06.292962 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 8 00:05:06.292983 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 8 00:05:06.293004 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 8 00:05:06.293025 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 8 00:05:06.293053 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 8 00:05:06.293097 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 8 00:05:06.293119 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:05:06.293140 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:05:06.293161 systemd[1]: Reached target paths.target - Path Units. Nov 8 00:05:06.293181 systemd[1]: Reached target slices.target - Slice Units. Nov 8 00:05:06.293201 systemd[1]: Reached target swap.target - Swaps. Nov 8 00:05:06.293228 systemd[1]: Reached target timers.target - Timer Units. Nov 8 00:05:06.293249 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 8 00:05:06.293270 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 8 00:05:06.293291 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 8 00:05:06.293311 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 8 00:05:06.293333 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:05:06.293353 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 8 00:05:06.293375 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:05:06.293400 systemd[1]: Reached target sockets.target - Socket Units. Nov 8 00:05:06.293423 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 8 00:05:06.293446 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 8 00:05:06.293468 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 8 00:05:06.293490 systemd[1]: Starting systemd-fsck-usr.service... Nov 8 00:05:06.293511 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 8 00:05:06.293534 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 8 00:05:06.293557 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:05:06.293580 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 8 00:05:06.293607 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:05:06.293628 systemd[1]: Finished systemd-fsck-usr.service. Nov 8 00:05:06.293708 systemd-journald[250]: Collecting audit messages is disabled. Nov 8 00:05:06.293762 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 8 00:05:06.293785 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 8 00:05:06.293805 kernel: Bridge firewalling registered Nov 8 00:05:06.293830 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 8 00:05:06.293852 systemd-journald[250]: Journal started Nov 8 00:05:06.293895 systemd-journald[250]: Runtime Journal (/run/log/journal/ec2fb96cad6c6d11139a907a1e75675a) is 8.0M, max 75.3M, 67.3M free. Nov 8 00:05:06.245973 systemd-modules-load[251]: Inserted module 'overlay' Nov 8 00:05:06.287228 systemd-modules-load[251]: Inserted module 'br_netfilter' Nov 8 00:05:06.307920 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:05:06.315971 systemd[1]: Started systemd-journald.service - Journal Service. Nov 8 00:05:06.324490 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:05:06.333501 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 8 00:05:06.335344 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 8 00:05:06.335988 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 8 00:05:06.350344 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 8 00:05:06.390689 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:05:06.406119 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:05:06.414934 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:05:06.418824 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:05:06.434497 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 8 00:05:06.448371 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 8 00:05:06.475619 dracut-cmdline[287]: dracut-dracut-053 Nov 8 00:05:06.482100 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=653fdcb8a67e255793a721f32d76976d3ed6223b235b7c618cf75e5edffbdb68 Nov 8 00:05:06.544124 systemd-resolved[289]: Positive Trust Anchors: Nov 8 00:05:06.544154 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 00:05:06.544218 systemd-resolved[289]: 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 Nov 8 00:05:06.699727 kernel: SCSI subsystem initialized Nov 8 00:05:06.707182 kernel: Loading iSCSI transport class v2.0-870. Nov 8 00:05:06.720194 kernel: iscsi: registered transport (tcp) Nov 8 00:05:06.743183 kernel: iscsi: registered transport (qla4xxx) Nov 8 00:05:06.743255 kernel: QLogic iSCSI HBA Driver Nov 8 00:05:06.796127 kernel: random: crng init done Nov 8 00:05:06.796746 systemd-resolved[289]: Defaulting to hostname 'linux'. Nov 8 00:05:06.800980 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 8 00:05:06.808582 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:05:06.831929 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 8 00:05:06.847939 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 8 00:05:06.882110 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 8 00:05:06.882185 kernel: device-mapper: uevent: version 1.0.3 Nov 8 00:05:06.885034 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 8 00:05:06.956126 kernel: raid6: neonx8 gen() 6583 MB/s Nov 8 00:05:06.974132 kernel: raid6: neonx4 gen() 6348 MB/s Nov 8 00:05:06.991121 kernel: raid6: neonx2 gen() 5283 MB/s Nov 8 00:05:07.008129 kernel: raid6: neonx1 gen() 3865 MB/s Nov 8 00:05:07.026122 kernel: raid6: int64x8 gen() 3747 MB/s Nov 8 00:05:07.043119 kernel: raid6: int64x4 gen() 3647 MB/s Nov 8 00:05:07.060106 kernel: raid6: int64x2 gen() 3512 MB/s Nov 8 00:05:07.078182 kernel: raid6: int64x1 gen() 2745 MB/s Nov 8 00:05:07.078241 kernel: raid6: using algorithm neonx8 gen() 6583 MB/s Nov 8 00:05:07.097148 kernel: raid6: .... xor() 4899 MB/s, rmw enabled Nov 8 00:05:07.097207 kernel: raid6: using neon recovery algorithm Nov 8 00:05:07.105101 kernel: xor: measuring software checksum speed Nov 8 00:05:07.107552 kernel: 8regs : 10259 MB/sec Nov 8 00:05:07.107585 kernel: 32regs : 11909 MB/sec Nov 8 00:05:07.108870 kernel: arm64_neon : 9271 MB/sec Nov 8 00:05:07.108902 kernel: xor: using function: 32regs (11909 MB/sec) Nov 8 00:05:07.195109 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 8 00:05:07.214521 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 8 00:05:07.229346 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:05:07.265673 systemd-udevd[471]: Using default interface naming scheme 'v255'. Nov 8 00:05:07.273774 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:05:07.289411 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 8 00:05:07.322242 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Nov 8 00:05:07.381193 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 8 00:05:07.393386 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 8 00:05:07.516735 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:05:07.538381 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 8 00:05:07.577357 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 8 00:05:07.588030 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 8 00:05:07.598962 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:05:07.599126 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 8 00:05:07.615311 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 8 00:05:07.673429 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 8 00:05:07.731654 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 8 00:05:07.731718 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 8 00:05:07.748792 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 8 00:05:07.749183 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 8 00:05:07.760111 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:f6:3c:e9:b6:83 Nov 8 00:05:07.764383 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 00:05:07.764623 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:05:07.765004 (udev-worker)[516]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:05:07.780584 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:05:07.786825 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 00:05:07.793142 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:05:07.800440 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:05:07.810121 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 8 00:05:07.812642 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:05:07.819236 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 8 00:05:07.834176 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 8 00:05:07.849483 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 8 00:05:07.849983 kernel: GPT:9289727 != 33554431 Nov 8 00:05:07.850472 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 8 00:05:07.850935 kernel: GPT:9289727 != 33554431 Nov 8 00:05:07.851417 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 8 00:05:07.851550 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:05:07.843573 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:05:07.864391 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:05:07.905577 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:05:07.963473 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (540) Nov 8 00:05:07.983159 kernel: BTRFS: device fsid 55a292e1-3824-4229-a9ae-952140d2698c devid 1 transid 37 /dev/nvme0n1p3 scanned by (udev-worker) (521) Nov 8 00:05:08.072837 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 8 00:05:08.092468 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 8 00:05:08.117512 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 8 00:05:08.124393 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 8 00:05:08.150022 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 8 00:05:08.162329 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 8 00:05:08.177387 disk-uuid[660]: Primary Header is updated. Nov 8 00:05:08.177387 disk-uuid[660]: Secondary Entries is updated. Nov 8 00:05:08.177387 disk-uuid[660]: Secondary Header is updated. Nov 8 00:05:08.192104 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:05:08.203100 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:05:08.209090 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:05:09.210129 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:05:09.211241 disk-uuid[661]: The operation has completed successfully. Nov 8 00:05:09.405896 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 8 00:05:09.406538 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 8 00:05:09.478328 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 8 00:05:09.490796 sh[1006]: Success Nov 8 00:05:09.525124 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 8 00:05:09.638754 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 8 00:05:09.661284 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 8 00:05:09.667994 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 8 00:05:09.699789 kernel: BTRFS info (device dm-0): first mount of filesystem 55a292e1-3824-4229-a9ae-952140d2698c Nov 8 00:05:09.699851 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:05:09.701899 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 8 00:05:09.701942 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 8 00:05:09.703335 kernel: BTRFS info (device dm-0): using free space tree Nov 8 00:05:09.819120 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 8 00:05:09.845919 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 8 00:05:09.848982 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 8 00:05:09.867408 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 8 00:05:09.874328 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 8 00:05:09.912609 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:05:09.912679 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:05:09.914100 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:05:09.929357 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:05:09.948825 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 8 00:05:09.953138 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:05:09.962780 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 8 00:05:09.975400 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 8 00:05:10.062469 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 8 00:05:10.076429 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 8 00:05:10.127207 systemd-networkd[1210]: lo: Link UP Nov 8 00:05:10.127228 systemd-networkd[1210]: lo: Gained carrier Nov 8 00:05:10.129751 systemd-networkd[1210]: Enumeration completed Nov 8 00:05:10.131136 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 8 00:05:10.131429 systemd-networkd[1210]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:05:10.131435 systemd-networkd[1210]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 8 00:05:10.137353 systemd[1]: Reached target network.target - Network. Nov 8 00:05:10.137609 systemd-networkd[1210]: eth0: Link UP Nov 8 00:05:10.137617 systemd-networkd[1210]: eth0: Gained carrier Nov 8 00:05:10.137633 systemd-networkd[1210]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:05:10.177169 systemd-networkd[1210]: eth0: DHCPv4 address 172.31.20.66/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 8 00:05:10.416370 ignition[1147]: Ignition 2.19.0 Nov 8 00:05:10.416396 ignition[1147]: Stage: fetch-offline Nov 8 00:05:10.420945 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 8 00:05:10.417984 ignition[1147]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:10.436464 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 8 00:05:10.418008 ignition[1147]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:10.418921 ignition[1147]: Ignition finished successfully Nov 8 00:05:10.470374 ignition[1220]: Ignition 2.19.0 Nov 8 00:05:10.470396 ignition[1220]: Stage: fetch Nov 8 00:05:10.471042 ignition[1220]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:10.471107 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:10.471265 ignition[1220]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:10.487690 ignition[1220]: PUT result: OK Nov 8 00:05:10.491629 ignition[1220]: parsed url from cmdline: "" Nov 8 00:05:10.491671 ignition[1220]: no config URL provided Nov 8 00:05:10.491691 ignition[1220]: reading system config file "/usr/lib/ignition/user.ign" Nov 8 00:05:10.491719 ignition[1220]: no config at "/usr/lib/ignition/user.ign" Nov 8 00:05:10.491751 ignition[1220]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:10.494351 ignition[1220]: PUT result: OK Nov 8 00:05:10.499223 ignition[1220]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 8 00:05:10.506557 ignition[1220]: GET result: OK Nov 8 00:05:10.506678 ignition[1220]: parsing config with SHA512: dd01b5b3c64215678e45154da95af7cda0a4f2f5b226fb94b1d87b91611d2b489ec75340d8375781b80551a7879b1e61269cdc2143410a07bfb1247903f3fa37 Nov 8 00:05:10.514276 unknown[1220]: fetched base config from "system" Nov 8 00:05:10.514859 ignition[1220]: fetch: fetch complete Nov 8 00:05:10.514311 unknown[1220]: fetched base config from "system" Nov 8 00:05:10.514871 ignition[1220]: fetch: fetch passed Nov 8 00:05:10.514325 unknown[1220]: fetched user config from "aws" Nov 8 00:05:10.514943 ignition[1220]: Ignition finished successfully Nov 8 00:05:10.527313 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 8 00:05:10.540545 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 8 00:05:10.571706 ignition[1227]: Ignition 2.19.0 Nov 8 00:05:10.572350 ignition[1227]: Stage: kargs Nov 8 00:05:10.576238 ignition[1227]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:10.576299 ignition[1227]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:10.576562 ignition[1227]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:10.579698 ignition[1227]: PUT result: OK Nov 8 00:05:10.589649 ignition[1227]: kargs: kargs passed Nov 8 00:05:10.589755 ignition[1227]: Ignition finished successfully Nov 8 00:05:10.594926 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 8 00:05:10.609529 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 8 00:05:10.636913 ignition[1233]: Ignition 2.19.0 Nov 8 00:05:10.636936 ignition[1233]: Stage: disks Nov 8 00:05:10.638193 ignition[1233]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:10.638222 ignition[1233]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:10.638408 ignition[1233]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:10.649013 ignition[1233]: PUT result: OK Nov 8 00:05:10.654123 ignition[1233]: disks: disks passed Nov 8 00:05:10.654222 ignition[1233]: Ignition finished successfully Nov 8 00:05:10.656908 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 8 00:05:10.663667 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 8 00:05:10.666693 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 8 00:05:10.670159 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 8 00:05:10.681215 systemd[1]: Reached target sysinit.target - System Initialization. Nov 8 00:05:10.683884 systemd[1]: Reached target basic.target - Basic System. Nov 8 00:05:10.699987 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 8 00:05:10.757690 systemd-fsck[1241]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 8 00:05:10.766324 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 8 00:05:10.779321 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 8 00:05:10.858119 kernel: EXT4-fs (nvme0n1p9): mounted filesystem ba97f76e-2e9b-450a-8320-3c4b94a19632 r/w with ordered data mode. Quota mode: none. Nov 8 00:05:10.859573 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 8 00:05:10.869173 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 8 00:05:10.889279 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 8 00:05:10.899276 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 8 00:05:10.908756 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 8 00:05:10.908861 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 8 00:05:10.934577 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/nvme0n1p6 scanned by mount (1260) Nov 8 00:05:10.934625 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:05:10.934653 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:05:10.934680 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:05:10.908911 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 8 00:05:10.940037 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 8 00:05:10.952379 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 8 00:05:10.966130 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:05:10.968464 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 8 00:05:11.307263 initrd-setup-root[1284]: cut: /sysroot/etc/passwd: No such file or directory Nov 8 00:05:11.329497 initrd-setup-root[1291]: cut: /sysroot/etc/group: No such file or directory Nov 8 00:05:11.350941 initrd-setup-root[1298]: cut: /sysroot/etc/shadow: No such file or directory Nov 8 00:05:11.360525 initrd-setup-root[1305]: cut: /sysroot/etc/gshadow: No such file or directory Nov 8 00:05:11.647221 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 8 00:05:11.662403 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 8 00:05:11.666870 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 8 00:05:11.690565 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 8 00:05:11.694634 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:05:11.726970 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 8 00:05:11.740869 ignition[1373]: INFO : Ignition 2.19.0 Nov 8 00:05:11.740869 ignition[1373]: INFO : Stage: mount Nov 8 00:05:11.745669 ignition[1373]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:11.745669 ignition[1373]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:11.745669 ignition[1373]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:11.754557 ignition[1373]: INFO : PUT result: OK Nov 8 00:05:11.759418 ignition[1373]: INFO : mount: mount passed Nov 8 00:05:11.759418 ignition[1373]: INFO : Ignition finished successfully Nov 8 00:05:11.764773 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 8 00:05:11.776346 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 8 00:05:11.785873 systemd-networkd[1210]: eth0: Gained IPv6LL Nov 8 00:05:11.867506 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 8 00:05:11.899085 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1384) Nov 8 00:05:11.903097 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:05:11.903148 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:05:11.903175 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:05:11.910103 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:05:11.913592 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 8 00:05:11.952661 ignition[1401]: INFO : Ignition 2.19.0 Nov 8 00:05:11.952661 ignition[1401]: INFO : Stage: files Nov 8 00:05:11.957859 ignition[1401]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:11.957859 ignition[1401]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:11.957859 ignition[1401]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:11.957859 ignition[1401]: INFO : PUT result: OK Nov 8 00:05:11.970332 ignition[1401]: DEBUG : files: compiled without relabeling support, skipping Nov 8 00:05:11.980212 ignition[1401]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 8 00:05:11.980212 ignition[1401]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 8 00:05:12.026678 ignition[1401]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 8 00:05:12.030643 ignition[1401]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 8 00:05:12.036645 unknown[1401]: wrote ssh authorized keys file for user: core Nov 8 00:05:12.039578 ignition[1401]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 8 00:05:12.048558 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 8 00:05:12.054352 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Nov 8 00:05:12.394611 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Nov 8 00:05:12.783650 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 8 00:05:12.783650 ignition[1401]: INFO : files: op(8): [started] processing unit "containerd.service" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: op(8): [finished] processing unit "containerd.service" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 8 00:05:12.791938 ignition[1401]: INFO : files: files passed Nov 8 00:05:12.791938 ignition[1401]: INFO : Ignition finished successfully Nov 8 00:05:12.798110 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 8 00:05:12.813108 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 8 00:05:12.818555 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 8 00:05:12.844470 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 8 00:05:12.847210 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 8 00:05:12.871806 initrd-setup-root-after-ignition[1429]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:05:12.871806 initrd-setup-root-after-ignition[1429]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:05:12.880610 initrd-setup-root-after-ignition[1433]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:05:12.887488 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 8 00:05:12.891430 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 8 00:05:12.907444 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 8 00:05:12.972043 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 8 00:05:12.972372 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 8 00:05:12.981591 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 8 00:05:12.986941 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 8 00:05:12.989671 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 8 00:05:12.991866 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 8 00:05:13.032117 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 8 00:05:13.046897 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 8 00:05:13.073473 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:05:13.076864 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:05:13.085463 systemd[1]: Stopped target timers.target - Timer Units. Nov 8 00:05:13.087781 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 8 00:05:13.088016 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 8 00:05:13.093818 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 8 00:05:13.096656 systemd[1]: Stopped target basic.target - Basic System. Nov 8 00:05:13.099342 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 8 00:05:13.114765 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 8 00:05:13.118102 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 8 00:05:13.121802 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 8 00:05:13.129585 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 8 00:05:13.133143 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 8 00:05:13.144055 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 8 00:05:13.147144 systemd[1]: Stopped target swap.target - Swaps. Nov 8 00:05:13.153365 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 8 00:05:13.153607 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 8 00:05:13.156604 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:05:13.167851 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:05:13.170860 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 8 00:05:13.173881 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:05:13.177503 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 8 00:05:13.178192 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 8 00:05:13.191012 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 8 00:05:13.191506 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 8 00:05:13.200967 systemd[1]: ignition-files.service: Deactivated successfully. Nov 8 00:05:13.201419 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 8 00:05:13.215627 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 8 00:05:13.219075 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 8 00:05:13.221902 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:05:13.236852 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 8 00:05:13.245017 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 8 00:05:13.245344 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:05:13.262493 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 8 00:05:13.262728 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 8 00:05:13.278762 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 8 00:05:13.279721 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 8 00:05:13.302427 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 8 00:05:13.311672 ignition[1453]: INFO : Ignition 2.19.0 Nov 8 00:05:13.311672 ignition[1453]: INFO : Stage: umount Nov 8 00:05:13.316156 ignition[1453]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:05:13.318745 ignition[1453]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:05:13.324172 ignition[1453]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:05:13.329104 ignition[1453]: INFO : PUT result: OK Nov 8 00:05:13.334462 ignition[1453]: INFO : umount: umount passed Nov 8 00:05:13.334462 ignition[1453]: INFO : Ignition finished successfully Nov 8 00:05:13.336955 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 8 00:05:13.337195 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 8 00:05:13.351529 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 8 00:05:13.351704 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 8 00:05:13.354908 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 8 00:05:13.355009 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 8 00:05:13.367135 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 8 00:05:13.367252 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 8 00:05:13.370107 systemd[1]: Stopped target network.target - Network. Nov 8 00:05:13.372928 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 8 00:05:13.373034 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 8 00:05:13.388734 systemd[1]: Stopped target paths.target - Path Units. Nov 8 00:05:13.388952 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 8 00:05:13.395832 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:05:13.398778 systemd[1]: Stopped target slices.target - Slice Units. Nov 8 00:05:13.401158 systemd[1]: Stopped target sockets.target - Socket Units. Nov 8 00:05:13.403746 systemd[1]: iscsid.socket: Deactivated successfully. Nov 8 00:05:13.403845 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 8 00:05:13.406634 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 8 00:05:13.406725 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 8 00:05:13.409487 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 8 00:05:13.409584 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 8 00:05:13.415889 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 8 00:05:13.415988 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 8 00:05:13.419127 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 8 00:05:13.430955 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 8 00:05:13.435416 systemd-networkd[1210]: eth0: DHCPv6 lease lost Nov 8 00:05:13.443077 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 8 00:05:13.443421 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 8 00:05:13.455875 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 8 00:05:13.471328 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 8 00:05:13.476736 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 8 00:05:13.480291 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 8 00:05:13.490768 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 8 00:05:13.491426 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:05:13.496325 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 8 00:05:13.496441 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 8 00:05:13.509695 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 8 00:05:13.517134 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 8 00:05:13.517253 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 8 00:05:13.520573 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 8 00:05:13.520673 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:05:13.523513 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 8 00:05:13.523591 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 8 00:05:13.526516 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 8 00:05:13.526605 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:05:13.530103 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:05:13.570750 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 8 00:05:13.572264 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:05:13.578428 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 8 00:05:13.578518 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 8 00:05:13.582271 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 8 00:05:13.593630 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:05:13.596209 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 8 00:05:13.596322 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 8 00:05:13.599517 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 8 00:05:13.599608 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 8 00:05:13.607455 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 00:05:13.607567 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:05:13.632440 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 8 00:05:13.635016 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 8 00:05:13.635173 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:05:13.638772 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 00:05:13.638867 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:05:13.642961 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 8 00:05:13.643169 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 8 00:05:13.669615 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 8 00:05:13.669817 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 8 00:05:13.673271 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 8 00:05:13.690523 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 8 00:05:13.716124 systemd[1]: Switching root. Nov 8 00:05:13.760144 systemd-journald[250]: Journal stopped Nov 8 00:05:16.305400 systemd-journald[250]: Received SIGTERM from PID 1 (systemd). Nov 8 00:05:16.305530 kernel: SELinux: policy capability network_peer_controls=1 Nov 8 00:05:16.305574 kernel: SELinux: policy capability open_perms=1 Nov 8 00:05:16.305606 kernel: SELinux: policy capability extended_socket_class=1 Nov 8 00:05:16.305638 kernel: SELinux: policy capability always_check_network=0 Nov 8 00:05:16.305668 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 8 00:05:16.305700 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 8 00:05:16.305737 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 8 00:05:16.305769 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 8 00:05:16.305800 kernel: audit: type=1403 audit(1762560314.404:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 8 00:05:16.305843 systemd[1]: Successfully loaded SELinux policy in 71.434ms. Nov 8 00:05:16.305887 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.657ms. Nov 8 00:05:16.305923 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 8 00:05:16.305964 systemd[1]: Detected virtualization amazon. Nov 8 00:05:16.305997 systemd[1]: Detected architecture arm64. Nov 8 00:05:16.306026 systemd[1]: Detected first boot. Nov 8 00:05:16.312143 systemd[1]: Initializing machine ID from VM UUID. Nov 8 00:05:16.312239 zram_generator::config[1513]: No configuration found. Nov 8 00:05:16.312282 systemd[1]: Populated /etc with preset unit settings. Nov 8 00:05:16.312317 systemd[1]: Queued start job for default target multi-user.target. Nov 8 00:05:16.312349 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 8 00:05:16.312393 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 8 00:05:16.312428 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 8 00:05:16.312460 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 8 00:05:16.312493 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 8 00:05:16.312527 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 8 00:05:16.312557 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 8 00:05:16.312588 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 8 00:05:16.312619 systemd[1]: Created slice user.slice - User and Session Slice. Nov 8 00:05:16.312651 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:05:16.312686 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:05:16.312717 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 8 00:05:16.312749 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 8 00:05:16.312779 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 8 00:05:16.312811 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 8 00:05:16.312841 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 8 00:05:16.312873 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:05:16.312905 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 8 00:05:16.312939 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:05:16.312975 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 8 00:05:16.313006 systemd[1]: Reached target slices.target - Slice Units. Nov 8 00:05:16.313035 systemd[1]: Reached target swap.target - Swaps. Nov 8 00:05:16.315147 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 8 00:05:16.315209 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 8 00:05:16.315243 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 8 00:05:16.315274 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 8 00:05:16.315303 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:05:16.315341 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 8 00:05:16.315373 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:05:16.315403 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 8 00:05:16.315435 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 8 00:05:16.315467 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 8 00:05:16.315496 systemd[1]: Mounting media.mount - External Media Directory... Nov 8 00:05:16.315526 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 8 00:05:16.315558 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 8 00:05:16.315590 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 8 00:05:16.315634 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 8 00:05:16.315665 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:05:16.315697 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 8 00:05:16.315731 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 8 00:05:16.315763 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:05:16.315793 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 8 00:05:16.315824 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:05:16.315856 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 8 00:05:16.315890 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 8 00:05:16.315921 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 8 00:05:16.315951 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Nov 8 00:05:16.315984 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Nov 8 00:05:16.316014 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 8 00:05:16.316043 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 8 00:05:16.316104 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 8 00:05:16.316145 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 8 00:05:16.316176 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 8 00:05:16.316230 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 8 00:05:16.316265 kernel: loop: module loaded Nov 8 00:05:16.316294 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 8 00:05:16.316324 systemd[1]: Mounted media.mount - External Media Directory. Nov 8 00:05:16.316356 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 8 00:05:16.316388 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 8 00:05:16.316423 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 8 00:05:16.316460 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:05:16.316490 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 8 00:05:16.316526 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 8 00:05:16.316556 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:05:16.316586 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:05:16.316615 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:05:16.316645 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:05:16.316682 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 8 00:05:16.316717 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 8 00:05:16.316746 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 8 00:05:16.316778 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 8 00:05:16.316808 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 8 00:05:16.316836 kernel: fuse: init (API version 7.39) Nov 8 00:05:16.316865 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 8 00:05:16.316907 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 8 00:05:16.316941 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 8 00:05:16.317024 systemd-journald[1616]: Collecting audit messages is disabled. Nov 8 00:05:16.326449 kernel: ACPI: bus type drm_connector registered Nov 8 00:05:16.326507 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 8 00:05:16.326543 systemd-journald[1616]: Journal started Nov 8 00:05:16.326594 systemd-journald[1616]: Runtime Journal (/run/log/journal/ec2fb96cad6c6d11139a907a1e75675a) is 8.0M, max 75.3M, 67.3M free. Nov 8 00:05:16.338150 systemd[1]: Started systemd-journald.service - Journal Service. Nov 8 00:05:16.344321 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 8 00:05:16.348497 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 00:05:16.348877 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 8 00:05:16.352463 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 8 00:05:16.355408 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 8 00:05:16.359582 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 8 00:05:16.363856 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 8 00:05:16.413316 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 8 00:05:16.416599 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 8 00:05:16.429355 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 8 00:05:16.443929 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 8 00:05:16.449310 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 8 00:05:16.467498 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 8 00:05:16.479234 systemd-tmpfiles[1640]: ACLs are not supported, ignoring. Nov 8 00:05:16.479268 systemd-tmpfiles[1640]: ACLs are not supported, ignoring. Nov 8 00:05:16.486980 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:05:16.491767 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 8 00:05:16.518395 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 8 00:05:16.530556 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 8 00:05:16.538667 systemd-journald[1616]: Time spent on flushing to /var/log/journal/ec2fb96cad6c6d11139a907a1e75675a is 70.520ms for 881 entries. Nov 8 00:05:16.538667 systemd-journald[1616]: System Journal (/var/log/journal/ec2fb96cad6c6d11139a907a1e75675a) is 8.0M, max 195.6M, 187.6M free. Nov 8 00:05:16.629275 systemd-journald[1616]: Received client request to flush runtime journal. Nov 8 00:05:16.558789 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 8 00:05:16.565445 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 8 00:05:16.639031 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 8 00:05:16.662412 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 8 00:05:16.680440 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 8 00:05:16.685643 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:05:16.703425 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 8 00:05:16.742319 systemd-tmpfiles[1684]: ACLs are not supported, ignoring. Nov 8 00:05:16.742825 systemd-tmpfiles[1684]: ACLs are not supported, ignoring. Nov 8 00:05:16.754444 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:05:16.769025 udevadm[1687]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 8 00:05:17.437175 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 8 00:05:17.449335 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:05:17.512423 systemd-udevd[1693]: Using default interface naming scheme 'v255'. Nov 8 00:05:17.558002 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:05:17.582359 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 8 00:05:17.620428 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 8 00:05:17.735711 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Nov 8 00:05:17.746458 (udev-worker)[1694]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:05:17.788886 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 8 00:05:17.995396 systemd-networkd[1699]: lo: Link UP Nov 8 00:05:17.995418 systemd-networkd[1699]: lo: Gained carrier Nov 8 00:05:17.998754 systemd-networkd[1699]: Enumeration completed Nov 8 00:05:17.999030 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 8 00:05:18.000014 systemd-networkd[1699]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:05:18.000023 systemd-networkd[1699]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 8 00:05:18.003992 systemd-networkd[1699]: eth0: Link UP Nov 8 00:05:18.004452 systemd-networkd[1699]: eth0: Gained carrier Nov 8 00:05:18.004486 systemd-networkd[1699]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:05:18.017204 systemd-networkd[1699]: eth0: DHCPv4 address 172.31.20.66/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 8 00:05:18.023322 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 8 00:05:18.037585 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:05:18.102140 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (1700) Nov 8 00:05:18.295082 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:05:18.317645 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 8 00:05:18.361166 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 8 00:05:18.375491 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 8 00:05:18.410088 lvm[1822]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 00:05:18.449851 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 8 00:05:18.455818 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:05:18.464385 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 8 00:05:18.478229 lvm[1825]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 00:05:18.519579 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 8 00:05:18.525383 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 8 00:05:18.528685 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). Nov 8 00:05:18.528748 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 8 00:05:18.531462 systemd[1]: Reached target machines.target - Containers. Nov 8 00:05:18.536475 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 8 00:05:18.548409 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 8 00:05:18.557052 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 8 00:05:18.560167 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:05:18.564467 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 8 00:05:18.583391 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 8 00:05:18.597457 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 8 00:05:18.603895 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 8 00:05:18.653146 kernel: loop0: detected capacity change from 0 to 52536 Nov 8 00:05:18.655739 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 8 00:05:18.660486 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 8 00:05:18.665968 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 8 00:05:18.752177 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 8 00:05:18.794141 kernel: loop1: detected capacity change from 0 to 114432 Nov 8 00:05:18.908116 kernel: loop2: detected capacity change from 0 to 207008 Nov 8 00:05:18.957117 kernel: loop3: detected capacity change from 0 to 114328 Nov 8 00:05:19.090527 kernel: loop4: detected capacity change from 0 to 52536 Nov 8 00:05:19.117303 kernel: loop5: detected capacity change from 0 to 114432 Nov 8 00:05:19.133102 kernel: loop6: detected capacity change from 0 to 207008 Nov 8 00:05:19.162101 kernel: loop7: detected capacity change from 0 to 114328 Nov 8 00:05:19.172453 (sd-merge)[1846]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Nov 8 00:05:19.173512 (sd-merge)[1846]: Merged extensions into '/usr'. Nov 8 00:05:19.184243 systemd[1]: Reloading requested from client PID 1833 ('systemd-sysext') (unit systemd-sysext.service)... Nov 8 00:05:19.184277 systemd[1]: Reloading... Nov 8 00:05:19.315125 zram_generator::config[1874]: No configuration found. Nov 8 00:05:19.603253 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. Nov 8 00:05:19.654222 systemd-networkd[1699]: eth0: Gained IPv6LL Nov 8 00:05:19.765615 systemd[1]: Reloading finished in 580 ms. Nov 8 00:05:19.790399 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 8 00:05:19.794465 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 8 00:05:19.810467 systemd[1]: Starting ensure-sysext.service... Nov 8 00:05:19.816354 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 8 00:05:19.858517 systemd-tmpfiles[1934]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 8 00:05:19.858702 systemd[1]: Reloading requested from client PID 1933 ('systemctl') (unit ensure-sysext.service)... Nov 8 00:05:19.858721 systemd[1]: Reloading... Nov 8 00:05:19.862822 systemd-tmpfiles[1934]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 8 00:05:19.868827 systemd-tmpfiles[1934]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 8 00:05:19.869424 systemd-tmpfiles[1934]: ACLs are not supported, ignoring. Nov 8 00:05:19.869576 systemd-tmpfiles[1934]: ACLs are not supported, ignoring. Nov 8 00:05:19.886906 systemd-tmpfiles[1934]: Detected autofs mount point /boot during canonicalization of boot. Nov 8 00:05:19.886933 systemd-tmpfiles[1934]: Skipping /boot Nov 8 00:05:19.915123 ldconfig[1829]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 8 00:05:19.928628 systemd-tmpfiles[1934]: Detected autofs mount point /boot during canonicalization of boot. Nov 8 00:05:19.928658 systemd-tmpfiles[1934]: Skipping /boot Nov 8 00:05:20.037130 zram_generator::config[1968]: No configuration found. Nov 8 00:05:20.276968 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. Nov 8 00:05:20.432200 systemd[1]: Reloading finished in 572 ms. Nov 8 00:05:20.460667 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 8 00:05:20.471183 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:05:20.488392 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Nov 8 00:05:20.505450 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 8 00:05:20.514535 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 8 00:05:20.525401 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 8 00:05:20.533248 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 8 00:05:20.567763 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:05:20.581177 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:05:20.601026 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:05:20.626614 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 8 00:05:20.633319 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:05:20.637539 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:05:20.637949 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:05:20.652276 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:05:20.652665 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:05:20.662075 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 8 00:05:20.664529 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 8 00:05:20.683021 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 8 00:05:20.699575 augenrules[2054]: No rules Nov 8 00:05:20.707800 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Nov 8 00:05:20.725297 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:05:20.737603 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:05:20.745026 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:05:20.764512 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 8 00:05:20.768718 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:05:20.775400 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 8 00:05:20.787163 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 8 00:05:20.791852 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 8 00:05:20.798340 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:05:20.798974 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:05:20.805439 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:05:20.805820 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:05:20.825453 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:05:20.830515 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:05:20.849692 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 8 00:05:20.857169 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:05:20.860772 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:05:20.861363 systemd[1]: Reached target time-set.target - System Time Set. Nov 8 00:05:20.866267 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). Nov 8 00:05:20.872500 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 8 00:05:20.874561 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 8 00:05:20.889822 systemd[1]: Finished ensure-sysext.service. Nov 8 00:05:20.895926 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 00:05:20.896398 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 8 00:05:20.913387 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:05:20.913775 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:05:20.917966 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 8 00:05:20.922860 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 8 00:05:20.936024 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:05:20.938626 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:05:20.943583 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 8 00:05:20.997208 systemd-resolved[2029]: Positive Trust Anchors: Nov 8 00:05:20.997244 systemd-resolved[2029]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 00:05:20.997308 systemd-resolved[2029]: 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 Nov 8 00:05:21.011631 systemd-resolved[2029]: Defaulting to hostname 'linux'. Nov 8 00:05:21.015178 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 8 00:05:21.018189 systemd[1]: Reached target network.target - Network. Nov 8 00:05:21.020360 systemd[1]: Reached target network-online.target - Network is Online. Nov 8 00:05:21.022789 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:05:21.025832 systemd[1]: Reached target sysinit.target - System Initialization. Nov 8 00:05:21.028568 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 8 00:05:21.031478 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 8 00:05:21.035691 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 8 00:05:21.038801 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 8 00:05:21.042094 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 8 00:05:21.045379 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 8 00:05:21.045438 systemd[1]: Reached target paths.target - Path Units. Nov 8 00:05:21.047816 systemd[1]: Reached target timers.target - Timer Units. Nov 8 00:05:21.051649 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 8 00:05:21.057042 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 8 00:05:21.062536 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 8 00:05:21.072013 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 8 00:05:21.075032 systemd[1]: Reached target sockets.target - Socket Units. Nov 8 00:05:21.079209 systemd[1]: Reached target basic.target - Basic System. Nov 8 00:05:21.081883 systemd[1]: System is tainted: cgroupsv1 Nov 8 00:05:21.081968 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 8 00:05:21.082016 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 8 00:05:21.084686 systemd[1]: Starting containerd.service - containerd container runtime... Nov 8 00:05:21.092447 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 8 00:05:21.101765 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 8 00:05:21.118281 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 8 00:05:21.130829 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 8 00:05:21.137777 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). Nov 8 00:05:21.145318 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:05:21.154807 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 8 00:05:21.166883 jq[2098]: false Nov 8 00:05:21.171418 systemd[1]: Started ntpd.service - Network Time Service. Nov 8 00:05:21.203371 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 8 00:05:21.215787 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 8 00:05:21.228847 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 8 00:05:21.258586 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 8 00:05:21.271365 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 8 00:05:21.281460 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 8 00:05:21.291384 systemd[1]: Starting update-engine.service - Update Engine... Nov 8 00:05:21.314289 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 8 00:05:21.329345 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 8 00:05:21.329850 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 8 00:05:21.343147 dbus-daemon[2097]: [system] SELinux support is enabled Nov 8 00:05:21.345814 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 8 00:05:21.372154 jq[2122]: true Nov 8 00:05:21.372985 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 8 00:05:21.375191 dbus-daemon[2097]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1699 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 8 00:05:21.388539 ntpd[2103]: ntpd 4.2.8p17@1.4004-o Fri Nov 7 22:04:46 UTC 2025 (1): Starting Nov 8 00:05:21.398246 extend-filesystems[2100]: Found loop4 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found loop5 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found loop6 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found loop7 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p1 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p2 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p3 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found usr Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p4 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p6 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p7 Nov 8 00:05:21.398246 extend-filesystems[2100]: Found nvme0n1p9 Nov 8 00:05:21.398246 extend-filesystems[2100]: Checking size of /dev/nvme0n1p9 Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: ntpd 4.2.8p17@1.4004-o Fri Nov 7 22:04:46 UTC 2025 (1): Starting Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: ---------------------------------------------------- Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: ntp-4 is maintained by Network Time Foundation, Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: corporation. Support and training for ntp-4 are Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: available at https://www.nwtime.org/support Nov 8 00:05:21.440476 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: ---------------------------------------------------- Nov 8 00:05:21.396292 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 8 00:05:21.388604 ntpd[2103]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 8 00:05:21.467162 update_engine[2118]: I20251108 00:05:21.419081 2118 main.cc:92] Flatcar Update Engine starting Nov 8 00:05:21.467162 update_engine[2118]: I20251108 00:05:21.424713 2118 update_check_scheduler.cc:74] Next update check in 11m59s Nov 8 00:05:21.467659 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: proto: precision = 0.120 usec (-23) Nov 8 00:05:21.467659 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: basedate set to 2025-10-26 Nov 8 00:05:21.467659 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: gps base set to 2025-10-26 (week 2390) Nov 8 00:05:21.467659 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen and drop on 0 v6wildcard [::]:123 Nov 8 00:05:21.388626 ntpd[2103]: ---------------------------------------------------- Nov 8 00:05:21.463002 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). Nov 8 00:05:21.388645 ntpd[2103]: ntp-4 is maintained by Network Time Foundation, Nov 8 00:05:21.463133 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen normally on 2 lo 127.0.0.1:123 Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen normally on 3 eth0 172.31.20.66:123 Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen normally on 4 lo [::1]:123 Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listen normally on 5 eth0 [fe80::4f6:3cff:fee9:b683%2]:123 Nov 8 00:05:21.485544 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: Listening on routing socket on fd #22 for interface updates Nov 8 00:05:21.388665 ntpd[2103]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 8 00:05:21.469213 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). Nov 8 00:05:21.388684 ntpd[2103]: corporation. Support and training for ntp-4 are Nov 8 00:05:21.469254 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 8 00:05:21.388707 ntpd[2103]: available at https://www.nwtime.org/support Nov 8 00:05:21.388726 ntpd[2103]: ---------------------------------------------------- Nov 8 00:05:21.446426 ntpd[2103]: proto: precision = 0.120 usec (-23) Nov 8 00:05:21.451249 ntpd[2103]: basedate set to 2025-10-26 Nov 8 00:05:21.451284 ntpd[2103]: gps base set to 2025-10-26 (week 2390) Nov 8 00:05:21.461979 ntpd[2103]: Listen and drop on 0 v6wildcard [::]:123 Nov 8 00:05:21.475563 dbus-daemon[2097]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 8 00:05:21.479751 ntpd[2103]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 8 00:05:21.480025 ntpd[2103]: Listen normally on 2 lo 127.0.0.1:123 Nov 8 00:05:21.480120 ntpd[2103]: Listen normally on 3 eth0 172.31.20.66:123 Nov 8 00:05:21.480208 ntpd[2103]: Listen normally on 4 lo [::1]:123 Nov 8 00:05:21.480292 ntpd[2103]: Listen normally on 5 eth0 [fe80::4f6:3cff:fee9:b683%2]:123 Nov 8 00:05:21.480357 ntpd[2103]: Listening on routing socket on fd #22 for interface updates Nov 8 00:05:21.494220 ntpd[2103]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:05:21.494523 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:05:21.494523 ntpd[2103]: 8 Nov 00:05:21 ntpd[2103]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:05:21.494288 ntpd[2103]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:05:21.514197 systemd[1]: Started update-engine.service - Update Engine. Nov 8 00:05:21.540848 jq[2139]: true Nov 8 00:05:21.564833 (ntainerd)[2150]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 8 00:05:21.565947 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 8 00:05:21.577848 systemd[1]: motdgen.service: Deactivated successfully. Nov 8 00:05:21.578425 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 8 00:05:21.608927 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 8 00:05:21.613636 extend-filesystems[2100]: Resized partition /dev/nvme0n1p9 Nov 8 00:05:21.613659 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 8 00:05:21.622371 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 8 00:05:21.627099 extend-filesystems[2169]: resize2fs 1.47.1 (20-May-2024) Nov 8 00:05:21.630098 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 8 00:05:21.650386 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 8 00:05:21.663101 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 3587067 blocks Nov 8 00:05:21.826175 coreos-metadata[2096]: Nov 08 00:05:21.823 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 8 00:05:21.837347 coreos-metadata[2096]: Nov 08 00:05:21.833 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 8 00:05:21.842194 coreos-metadata[2096]: Nov 08 00:05:21.841 INFO Fetch successful Nov 8 00:05:21.842194 coreos-metadata[2096]: Nov 08 00:05:21.841 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 8 00:05:21.843352 coreos-metadata[2096]: Nov 08 00:05:21.843 INFO Fetch successful Nov 8 00:05:21.843352 coreos-metadata[2096]: Nov 08 00:05:21.843 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 8 00:05:21.846567 coreos-metadata[2096]: Nov 08 00:05:21.844 INFO Fetch successful Nov 8 00:05:21.846567 coreos-metadata[2096]: Nov 08 00:05:21.844 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 8 00:05:21.854866 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 3587067 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.851 INFO Fetch successful Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.851 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.855 INFO Fetch failed with 404: resource not found Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.855 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.857 INFO Fetch successful Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.857 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.860 INFO Fetch successful Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.860 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.866 INFO Fetch successful Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.866 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.868 INFO Fetch successful Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.868 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 8 00:05:21.882197 coreos-metadata[2096]: Nov 08 00:05:21.869 INFO Fetch successful Nov 8 00:05:21.899192 extend-filesystems[2169]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 8 00:05:21.899192 extend-filesystems[2169]: old_desc_blocks = 1, new_desc_blocks = 2 Nov 8 00:05:21.899192 extend-filesystems[2169]: The filesystem on /dev/nvme0n1p9 is now 3587067 (4k) blocks long. Nov 8 00:05:21.932344 extend-filesystems[2100]: Resized filesystem in /dev/nvme0n1p9 Nov 8 00:05:21.903773 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 8 00:05:21.904343 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 8 00:05:21.972101 bash[2195]: Updated "/home/core/.ssh/authorized_keys" Nov 8 00:05:21.979660 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 8 00:05:22.000498 systemd[1]: Starting sshkeys.service... Nov 8 00:05:22.048985 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 8 00:05:22.054624 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 8 00:05:22.080162 systemd-logind[2116]: Watching system buttons on /dev/input/event0 (Power Button) Nov 8 00:05:22.080270 systemd-logind[2116]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 8 00:05:22.080682 systemd-logind[2116]: New seat seat0. Nov 8 00:05:22.092425 systemd[1]: Started systemd-logind.service - User Login Management. Nov 8 00:05:22.123923 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 8 00:05:22.141181 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 8 00:05:22.170661 amazon-ssm-agent[2171]: Initializing new seelog logger Nov 8 00:05:22.205169 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (2223) Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: New Seelog Logger Creation Complete Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 processing appconfig overrides Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 processing appconfig overrides Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 processing appconfig overrides Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO Proxy environment variables: Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:05:22.205217 amazon-ssm-agent[2171]: 2025/11/08 00:05:22 processing appconfig overrides Nov 8 00:05:22.215269 dbus-daemon[2097]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 8 00:05:22.215523 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 8 00:05:22.225881 dbus-daemon[2097]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=2168 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 8 00:05:22.234286 systemd[1]: Starting polkit.service - Authorization Manager... Nov 8 00:05:22.275986 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO https_proxy: Nov 8 00:05:22.280147 polkitd[2235]: Started polkitd version 121 Nov 8 00:05:22.317712 polkitd[2235]: Loading rules from directory /etc/polkit-1/rules.d Nov 8 00:05:22.323774 polkitd[2235]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 8 00:05:22.332886 polkitd[2235]: Finished loading, compiling and executing 2 rules Nov 8 00:05:22.339376 dbus-daemon[2097]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 8 00:05:22.339909 systemd[1]: Started polkit.service - Authorization Manager. Nov 8 00:05:22.342840 polkitd[2235]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 8 00:05:22.380745 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO http_proxy: Nov 8 00:05:22.421036 systemd-resolved[2029]: System hostname changed to 'ip-172-31-20-66'. Nov 8 00:05:22.421044 systemd-hostnamed[2168]: Hostname set to (transient) Nov 8 00:05:22.486085 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO no_proxy: Nov 8 00:05:22.555153 coreos-metadata[2225]: Nov 08 00:05:22.555 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 8 00:05:22.560308 coreos-metadata[2225]: Nov 08 00:05:22.557 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 8 00:05:22.562192 coreos-metadata[2225]: Nov 08 00:05:22.562 INFO Fetch successful Nov 8 00:05:22.562192 coreos-metadata[2225]: Nov 08 00:05:22.562 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 8 00:05:22.562192 coreos-metadata[2225]: Nov 08 00:05:22.562 INFO Fetch successful Nov 8 00:05:22.572029 unknown[2225]: wrote ssh authorized keys file for user: core Nov 8 00:05:22.582211 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO Checking if agent identity type OnPrem can be assumed Nov 8 00:05:22.636082 containerd[2150]: time="2025-11-08T00:05:22.631945681Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Nov 8 00:05:22.659927 update-ssh-keys[2271]: Updated "/home/core/.ssh/authorized_keys" Nov 8 00:05:22.660285 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 8 00:05:22.674221 systemd[1]: Finished sshkeys.service. Nov 8 00:05:22.686702 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO Checking if agent identity type EC2 can be assumed Nov 8 00:05:22.786114 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO Agent will take identity from EC2 Nov 8 00:05:22.840907 containerd[2150]: time="2025-11-08T00:05:22.840790778Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.847712 locksmithd[2170]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.849742310Z" 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.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.849820118Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.849858002Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850183802Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850219082Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850335278Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850363646Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850725038Z" 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 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850758206Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850790870Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852082 containerd[2150]: time="2025-11-08T00:05:22.850822634Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.852620 containerd[2150]: time="2025-11-08T00:05:22.850979414Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.859322 containerd[2150]: time="2025-11-08T00:05:22.858499154Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:05:22.859322 containerd[2150]: time="2025-11-08T00:05:22.858881162Z" 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 Nov 8 00:05:22.859322 containerd[2150]: time="2025-11-08T00:05:22.858918722Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 8 00:05:22.859322 containerd[2150]: time="2025-11-08T00:05:22.859153250Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 8 00:05:22.859322 containerd[2150]: time="2025-11-08T00:05:22.859252814Z" level=info msg="metadata content store policy set" policy=shared Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.874742546Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.874858994Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.874988894Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.875027246Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.875083634Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.875367122Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877594826Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877862258Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877896182Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877926626Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877958750Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.877998542Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.878092 containerd[2150]: time="2025-11-08T00:05:22.878035262Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887184 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885159206Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885239270Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885271262Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885313070Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885346610Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885393470Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885426230Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885456722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885489494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885518894Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885550466Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885579398Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885610634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887305 containerd[2150]: time="2025-11-08T00:05:22.885641942Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885676058Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885707186Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885736154Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885769166Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885804050Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885885842Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885919634Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.885952922Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.886184090Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.886223642Z" 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 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.886254014Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.886283222Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 8 00:05:22.887941 containerd[2150]: time="2025-11-08T00:05:22.886309166Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.888540 containerd[2150]: time="2025-11-08T00:05:22.886355594Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 8 00:05:22.888540 containerd[2150]: time="2025-11-08T00:05:22.886381178Z" level=info msg="NRI interface is disabled by configuration." Nov 8 00:05:22.888540 containerd[2150]: time="2025-11-08T00:05:22.886406438Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 8 00:05:22.894555 containerd[2150]: time="2025-11-08T00:05:22.887044358Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 8 00:05:22.894555 containerd[2150]: time="2025-11-08T00:05:22.893545742Z" level=info msg="Connect containerd service" Nov 8 00:05:22.894555 containerd[2150]: time="2025-11-08T00:05:22.893623586Z" level=info msg="using legacy CRI server" Nov 8 00:05:22.894555 containerd[2150]: time="2025-11-08T00:05:22.893643854Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 8 00:05:22.894555 containerd[2150]: time="2025-11-08T00:05:22.893800094Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.896048678Z" 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" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898328210Z" level=info msg="Start subscribing containerd event" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898428194Z" level=info msg="Start recovering state" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898609874Z" level=info msg="Start event monitor" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898637078Z" level=info msg="Start snapshots syncer" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898680446Z" level=info msg="Start cni network conf syncer for default" Nov 8 00:05:22.899213 containerd[2150]: time="2025-11-08T00:05:22.898700942Z" level=info msg="Start streaming server" Nov 8 00:05:22.906615 containerd[2150]: time="2025-11-08T00:05:22.906283874Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 8 00:05:22.906615 containerd[2150]: time="2025-11-08T00:05:22.906504410Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 8 00:05:22.924083 containerd[2150]: time="2025-11-08T00:05:22.916893266Z" level=info msg="containerd successfully booted in 0.288665s" Nov 8 00:05:22.917046 systemd[1]: Started containerd.service - containerd container runtime. Nov 8 00:05:22.981757 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:05:23.082160 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:05:23.181223 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 8 00:05:23.282129 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 8 00:05:23.382551 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] Starting Core Agent Nov 8 00:05:23.484464 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 8 00:05:23.583599 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [Registrar] Starting registrar module Nov 8 00:05:23.684113 amazon-ssm-agent[2171]: 2025-11-08 00:05:22 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 8 00:05:23.784740 amazon-ssm-agent[2171]: 2025-11-08 00:05:23 INFO [EC2Identity] EC2 registration was successful. Nov 8 00:05:23.835560 amazon-ssm-agent[2171]: 2025-11-08 00:05:23 INFO [CredentialRefresher] credentialRefresher has started Nov 8 00:05:23.835560 amazon-ssm-agent[2171]: 2025-11-08 00:05:23 INFO [CredentialRefresher] Starting credentials refresher loop Nov 8 00:05:23.835560 amazon-ssm-agent[2171]: 2025-11-08 00:05:23 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 8 00:05:23.885459 amazon-ssm-agent[2171]: 2025-11-08 00:05:23 INFO [CredentialRefresher] Next credential rotation will be in 29.9999923418 minutes Nov 8 00:05:23.984550 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:05:23.992015 (kubelet)[2363]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 8 00:05:24.833103 kubelet[2363]: E1108 00:05:24.832415 2363 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" Nov 8 00:05:24.838551 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 8 00:05:24.839014 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 8 00:05:24.871297 amazon-ssm-agent[2171]: 2025-11-08 00:05:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 8 00:05:24.972237 amazon-ssm-agent[2171]: 2025-11-08 00:05:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2372) started Nov 8 00:05:25.072607 amazon-ssm-agent[2171]: 2025-11-08 00:05:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 8 00:05:25.548256 sshd_keygen[2140]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 8 00:05:25.588935 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 8 00:05:25.602528 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 8 00:05:25.625917 systemd[1]: issuegen.service: Deactivated successfully. Nov 8 00:05:25.626728 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 8 00:05:25.642054 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 8 00:05:25.664822 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 8 00:05:25.680621 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 8 00:05:25.693633 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 8 00:05:25.698782 systemd[1]: Reached target getty.target - Login Prompts. Nov 8 00:05:25.702119 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 8 00:05:25.706565 systemd[1]: Startup finished in 9.813s (kernel) + 11.373s (userspace) = 21.187s. Nov 8 00:05:28.036135 systemd-resolved[2029]: Clock change detected. Flushing caches. Nov 8 00:05:28.619692 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 8 00:05:28.630698 systemd[1]: Started sshd@0-172.31.20.66:22-139.178.89.65:33120.service - OpenSSH per-connection server daemon (139.178.89.65:33120). Nov 8 00:05:28.813628 sshd[2405]: Accepted publickey for core from 139.178.89.65 port 33120 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:05:28.817718 sshd[2405]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:05:28.840201 systemd-logind[2116]: New session 1 of user core. Nov 8 00:05:28.840813 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 8 00:05:28.847750 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 8 00:05:28.874721 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 8 00:05:28.891787 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 8 00:05:28.899756 (systemd)[2411]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 8 00:05:29.126818 systemd[2411]: Queued start job for default target default.target. Nov 8 00:05:29.128463 systemd[2411]: Created slice app.slice - User Application Slice. Nov 8 00:05:29.128862 systemd[2411]: Reached target paths.target - Paths. Nov 8 00:05:29.128894 systemd[2411]: Reached target timers.target - Timers. Nov 8 00:05:29.140820 systemd[2411]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 8 00:05:29.154117 systemd[2411]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 8 00:05:29.154460 systemd[2411]: Reached target sockets.target - Sockets. Nov 8 00:05:29.154613 systemd[2411]: Reached target basic.target - Basic System. Nov 8 00:05:29.154804 systemd[2411]: Reached target default.target - Main User Target. Nov 8 00:05:29.154886 systemd[2411]: Startup finished in 243ms. Nov 8 00:05:29.155385 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 8 00:05:29.169929 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 8 00:05:29.331945 systemd[1]: Started sshd@1-172.31.20.66:22-139.178.89.65:33132.service - OpenSSH per-connection server daemon (139.178.89.65:33132). Nov 8 00:05:29.511028 sshd[2423]: Accepted publickey for core from 139.178.89.65 port 33132 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:05:29.513720 sshd[2423]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:05:29.524282 systemd-logind[2116]: New session 2 of user core. Nov 8 00:05:29.530887 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 8 00:05:29.664095 sshd[2423]: pam_unix(sshd:session): session closed for user core Nov 8 00:05:29.672366 systemd-logind[2116]: Session 2 logged out. Waiting for processes to exit. Nov 8 00:05:29.672502 systemd[1]: sshd@1-172.31.20.66:22-139.178.89.65:33132.service: Deactivated successfully. Nov 8 00:05:29.679243 systemd[1]: session-2.scope: Deactivated successfully. Nov 8 00:05:29.681655 systemd-logind[2116]: Removed session 2. Nov 8 00:05:29.694794 systemd[1]: Started sshd@2-172.31.20.66:22-139.178.89.65:33134.service - OpenSSH per-connection server daemon (139.178.89.65:33134). Nov 8 00:05:29.880093 sshd[2431]: Accepted publickey for core from 139.178.89.65 port 33134 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:05:29.883115 sshd[2431]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:05:29.891855 systemd-logind[2116]: New session 3 of user core. Nov 8 00:05:29.899791 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 8 00:05:30.023832 sshd[2431]: pam_unix(sshd:session): session closed for user core Nov 8 00:05:30.030579 systemd-logind[2116]: Session 3 logged out. Waiting for processes to exit. Nov 8 00:05:30.031675 systemd[1]: sshd@2-172.31.20.66:22-139.178.89.65:33134.service: Deactivated successfully. Nov 8 00:05:30.037474 systemd[1]: session-3.scope: Deactivated successfully. Nov 8 00:05:30.039612 systemd-logind[2116]: Removed session 3. Nov 8 00:05:30.059739 systemd[1]: Started sshd@3-172.31.20.66:22-139.178.89.65:33136.service - OpenSSH per-connection server daemon (139.178.89.65:33136). Nov 8 00:05:30.228585 sshd[2439]: Accepted publickey for core from 139.178.89.65 port 33136 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:05:30.230499 sshd[2439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:05:30.238969 systemd-logind[2116]: New session 4 of user core. Nov 8 00:05:30.246924 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 8 00:05:30.376242 sshd[2439]: pam_unix(sshd:session): session closed for user core Nov 8 00:05:30.381603 systemd[1]: sshd@3-172.31.20.66:22-139.178.89.65:33136.service: Deactivated successfully. Nov 8 00:05:30.386392 systemd-logind[2116]: Session 4 logged out. Waiting for processes to exit. Nov 8 00:05:30.390142 systemd[1]: session-4.scope: Deactivated successfully. Nov 8 00:05:30.392329 systemd-logind[2116]: Removed session 4. Nov 8 00:05:30.405756 systemd[1]: Started sshd@4-172.31.20.66:22-139.178.89.65:33152.service - OpenSSH per-connection server daemon (139.178.89.65:33152). Nov 8 00:05:30.591226 sshd[2447]: Accepted publickey for core from 139.178.89.65 port 33152 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:05:30.593774 sshd[2447]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:05:30.601308 systemd-logind[2116]: New session 5 of user core. Nov 8 00:05:30.609744 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 8 00:05:30.735990 sudo[2451]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 8 00:05:30.736666 sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 8 00:05:31.663586 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:05:31.672771 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:05:31.736534 systemd[1]: Reloading requested from client PID 2487 ('systemctl') (unit session-5.scope)... Nov 8 00:05:31.736573 systemd[1]: Reloading... Nov 8 00:05:31.983337 zram_generator::config[2530]: No configuration found. Nov 8 00:05:32.240803 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. Nov 8 00:05:32.413345 systemd[1]: Reloading finished in 675 ms. Nov 8 00:05:32.506158 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 8 00:05:32.506430 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 8 00:05:32.507029 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:05:32.517036 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:05:32.845601 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:05:32.859935 (kubelet)[2602]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 8 00:05:32.939309 kubelet[2602]: 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. Nov 8 00:05:32.939309 kubelet[2602]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 8 00:05:32.939309 kubelet[2602]: 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. Nov 8 00:05:32.939309 kubelet[2602]: I1108 00:05:32.938415 2602 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 8 00:05:34.679078 kubelet[2602]: I1108 00:05:34.679023 2602 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Nov 8 00:05:34.679755 kubelet[2602]: I1108 00:05:34.679732 2602 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 8 00:05:34.680379 kubelet[2602]: I1108 00:05:34.680354 2602 server.go:954] "Client rotation is on, will bootstrap in background" Nov 8 00:05:34.726753 kubelet[2602]: I1108 00:05:34.726703 2602 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 8 00:05:34.746209 kubelet[2602]: E1108 00:05:34.746077 2602 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 8 00:05:34.746209 kubelet[2602]: I1108 00:05:34.746152 2602 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Nov 8 00:05:34.752800 kubelet[2602]: I1108 00:05:34.752386 2602 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 8 00:05:34.754451 kubelet[2602]: I1108 00:05:34.754323 2602 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 8 00:05:34.754763 kubelet[2602]: I1108 00:05:34.754424 2602 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.20.66","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} Nov 8 00:05:34.754951 kubelet[2602]: I1108 00:05:34.754910 2602 topology_manager.go:138] "Creating topology manager with none policy" Nov 8 00:05:34.755031 kubelet[2602]: I1108 00:05:34.755016 2602 container_manager_linux.go:304] "Creating device plugin manager" Nov 8 00:05:34.755789 kubelet[2602]: I1108 00:05:34.755511 2602 state_mem.go:36] "Initialized new in-memory state store" Nov 8 00:05:34.759575 kubelet[2602]: I1108 00:05:34.759501 2602 kubelet.go:446] "Attempting to sync node with API server" Nov 8 00:05:34.759575 kubelet[2602]: I1108 00:05:34.759555 2602 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 8 00:05:34.760400 kubelet[2602]: I1108 00:05:34.759592 2602 kubelet.go:352] "Adding apiserver pod source" Nov 8 00:05:34.760400 kubelet[2602]: I1108 00:05:34.759616 2602 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 8 00:05:34.760615 kubelet[2602]: E1108 00:05:34.760422 2602 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:34.762192 kubelet[2602]: E1108 00:05:34.762089 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:34.766241 kubelet[2602]: I1108 00:05:34.766170 2602 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Nov 8 00:05:34.767340 kubelet[2602]: I1108 00:05:34.767298 2602 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 8 00:05:34.767579 kubelet[2602]: W1108 00:05:34.767539 2602 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 8 00:05:34.770308 kubelet[2602]: I1108 00:05:34.769578 2602 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 8 00:05:34.770308 kubelet[2602]: I1108 00:05:34.769645 2602 server.go:1287] "Started kubelet" Nov 8 00:05:34.770308 kubelet[2602]: I1108 00:05:34.769976 2602 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Nov 8 00:05:34.771913 kubelet[2602]: I1108 00:05:34.771832 2602 server.go:479] "Adding debug handlers to kubelet server" Nov 8 00:05:34.784293 kubelet[2602]: I1108 00:05:34.781851 2602 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 8 00:05:34.784293 kubelet[2602]: I1108 00:05:34.782353 2602 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 8 00:05:34.784293 kubelet[2602]: I1108 00:05:34.783786 2602 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 8 00:05:34.786529 kubelet[2602]: I1108 00:05:34.786472 2602 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 8 00:05:34.791106 kubelet[2602]: E1108 00:05:34.790641 2602 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.20.66.1875df493922df89 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.20.66,UID:172.31.20.66,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.20.66,},FirstTimestamp:2025-11-08 00:05:34.769610633 +0000 UTC m=+1.903227119,LastTimestamp:2025-11-08 00:05:34.769610633 +0000 UTC m=+1.903227119,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.20.66,}" Nov 8 00:05:34.792985 kubelet[2602]: I1108 00:05:34.792927 2602 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 8 00:05:34.793819 kubelet[2602]: E1108 00:05:34.793503 2602 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.20.66\" not found" Nov 8 00:05:34.794849 kubelet[2602]: I1108 00:05:34.794773 2602 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 8 00:05:34.794969 kubelet[2602]: I1108 00:05:34.794899 2602 reconciler.go:26] "Reconciler: start to sync state" Nov 8 00:05:34.804565 kubelet[2602]: I1108 00:05:34.804513 2602 factory.go:221] Registration of the systemd container factory successfully Nov 8 00:05:34.804929 kubelet[2602]: I1108 00:05:34.804712 2602 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 Nov 8 00:05:34.810698 kubelet[2602]: E1108 00:05:34.810409 2602 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 8 00:05:34.810976 kubelet[2602]: I1108 00:05:34.810912 2602 factory.go:221] Registration of the containerd container factory successfully Nov 8 00:05:34.848309 kubelet[2602]: E1108 00:05:34.847843 2602 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.20.66\" not found" node="172.31.20.66" Nov 8 00:05:34.885976 kubelet[2602]: I1108 00:05:34.885943 2602 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 8 00:05:34.886146 kubelet[2602]: I1108 00:05:34.886125 2602 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 8 00:05:34.886483 kubelet[2602]: I1108 00:05:34.886463 2602 state_mem.go:36] "Initialized new in-memory state store" Nov 8 00:05:34.895328 kubelet[2602]: I1108 00:05:34.894674 2602 policy_none.go:49] "None policy: Start" Nov 8 00:05:34.895328 kubelet[2602]: I1108 00:05:34.894719 2602 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 8 00:05:34.895328 kubelet[2602]: I1108 00:05:34.894743 2602 state_mem.go:35] "Initializing new in-memory state store" Nov 8 00:05:34.895328 kubelet[2602]: E1108 00:05:34.894869 2602 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.20.66\" not found" Nov 8 00:05:34.909223 kubelet[2602]: I1108 00:05:34.909170 2602 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 8 00:05:34.910739 kubelet[2602]: I1108 00:05:34.909520 2602 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 8 00:05:34.910739 kubelet[2602]: I1108 00:05:34.909541 2602 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 8 00:05:34.913117 kubelet[2602]: I1108 00:05:34.912591 2602 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 8 00:05:34.918489 kubelet[2602]: E1108 00:05:34.918403 2602 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 8 00:05:34.918489 kubelet[2602]: E1108 00:05:34.918478 2602 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.20.66\" not found" Nov 8 00:05:34.936310 kubelet[2602]: I1108 00:05:34.935796 2602 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 8 00:05:34.941837 kubelet[2602]: I1108 00:05:34.941794 2602 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 8 00:05:34.942059 kubelet[2602]: I1108 00:05:34.942027 2602 status_manager.go:227] "Starting to sync pod status with apiserver" Nov 8 00:05:34.942137 kubelet[2602]: I1108 00:05:34.942092 2602 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 8 00:05:34.942137 kubelet[2602]: I1108 00:05:34.942108 2602 kubelet.go:2382] "Starting kubelet main sync loop" Nov 8 00:05:34.942230 kubelet[2602]: E1108 00:05:34.942195 2602 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 8 00:05:35.018303 kubelet[2602]: I1108 00:05:35.018271 2602 kubelet_node_status.go:75] "Attempting to register node" node="172.31.20.66" Nov 8 00:05:35.026344 kubelet[2602]: I1108 00:05:35.026009 2602 kubelet_node_status.go:78] "Successfully registered node" node="172.31.20.66" Nov 8 00:05:35.026344 kubelet[2602]: E1108 00:05:35.026069 2602 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"172.31.20.66\": node \"172.31.20.66\" not found" Nov 8 00:05:35.149597 kubelet[2602]: I1108 00:05:35.149539 2602 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.2.0/24" Nov 8 00:05:35.150182 containerd[2150]: time="2025-11-08T00:05:35.150037479Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 8 00:05:35.150941 kubelet[2602]: I1108 00:05:35.150475 2602 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.2.0/24" Nov 8 00:05:35.396910 sudo[2451]: pam_unix(sudo:session): session closed for user root Nov 8 00:05:35.422586 sshd[2447]: pam_unix(sshd:session): session closed for user core Nov 8 00:05:35.427933 systemd-logind[2116]: Session 5 logged out. Waiting for processes to exit. Nov 8 00:05:35.429340 systemd[1]: sshd@4-172.31.20.66:22-139.178.89.65:33152.service: Deactivated successfully. Nov 8 00:05:35.437469 systemd[1]: session-5.scope: Deactivated successfully. Nov 8 00:05:35.442198 systemd-logind[2116]: Removed session 5. Nov 8 00:05:35.683860 kubelet[2602]: I1108 00:05:35.683706 2602 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 8 00:05:35.684623 kubelet[2602]: W1108 00:05:35.684562 2602 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 8 00:05:35.684838 kubelet[2602]: W1108 00:05:35.684648 2602 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 8 00:05:35.684838 kubelet[2602]: W1108 00:05:35.684699 2602 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 8 00:05:35.762839 kubelet[2602]: I1108 00:05:35.762780 2602 apiserver.go:52] "Watching apiserver" Nov 8 00:05:35.763053 kubelet[2602]: E1108 00:05:35.762873 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:35.795488 kubelet[2602]: I1108 00:05:35.795373 2602 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 8 00:05:35.800064 kubelet[2602]: I1108 00:05:35.800008 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-run\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.800705 kubelet[2602]: I1108 00:05:35.800417 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-cni-plugin\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.800705 kubelet[2602]: I1108 00:05:35.800501 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-xtables-lock\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.800705 kubelet[2602]: I1108 00:05:35.800756 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wrzzj\" (UniqueName: \"kubernetes.io/projected/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-kube-api-access-wrzzj\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.801461 kubelet[2602]: I1108 00:05:35.801166 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ptsfm\" (UniqueName: \"kubernetes.io/projected/1c216eac-d606-4aa5-990f-4dc33f9230a9-kube-api-access-ptsfm\") pod \"kube-proxy-swjjs\" (UID: \"1c216eac-d606-4aa5-990f-4dc33f9230a9\") " pod="kube-system/kube-proxy-swjjs" Nov 8 00:05:35.801461 kubelet[2602]: I1108 00:05:35.801309 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-cni\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.801461 kubelet[2602]: I1108 00:05:35.801384 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b781501f-dc5a-4a2c-bbbb-b4809677c6bb-flannel-cfg\") pod \"kube-flannel-ds-mcf2f\" (UID: \"b781501f-dc5a-4a2c-bbbb-b4809677c6bb\") " pod="kube-flannel/kube-flannel-ds-mcf2f" Nov 8 00:05:35.801927 kubelet[2602]: I1108 00:05:35.801438 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/1c216eac-d606-4aa5-990f-4dc33f9230a9-kube-proxy\") pod \"kube-proxy-swjjs\" (UID: \"1c216eac-d606-4aa5-990f-4dc33f9230a9\") " pod="kube-system/kube-proxy-swjjs" Nov 8 00:05:35.801927 kubelet[2602]: I1108 00:05:35.801793 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1c216eac-d606-4aa5-990f-4dc33f9230a9-xtables-lock\") pod \"kube-proxy-swjjs\" (UID: \"1c216eac-d606-4aa5-990f-4dc33f9230a9\") " pod="kube-system/kube-proxy-swjjs" Nov 8 00:05:35.802345 kubelet[2602]: I1108 00:05:35.801835 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/1c216eac-d606-4aa5-990f-4dc33f9230a9-lib-modules\") pod \"kube-proxy-swjjs\" (UID: \"1c216eac-d606-4aa5-990f-4dc33f9230a9\") " pod="kube-system/kube-proxy-swjjs" Nov 8 00:05:36.078603 containerd[2150]: time="2025-11-08T00:05:36.078515031Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-swjjs,Uid:1c216eac-d606-4aa5-990f-4dc33f9230a9,Namespace:kube-system,Attempt:0,}" Nov 8 00:05:36.081007 containerd[2150]: time="2025-11-08T00:05:36.080655591Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mcf2f,Uid:b781501f-dc5a-4a2c-bbbb-b4809677c6bb,Namespace:kube-flannel,Attempt:0,}" Nov 8 00:05:36.607431 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4133527940.mount: Deactivated successfully. Nov 8 00:05:36.616314 containerd[2150]: time="2025-11-08T00:05:36.615789954Z" 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\"}" Nov 8 00:05:36.617743 containerd[2150]: time="2025-11-08T00:05:36.617669586Z" 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\"}" Nov 8 00:05:36.619539 containerd[2150]: time="2025-11-08T00:05:36.619491438Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 8 00:05:36.619720 containerd[2150]: time="2025-11-08T00:05:36.619558194Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 8 00:05:36.622291 containerd[2150]: time="2025-11-08T00:05:36.620436210Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 8 00:05:36.626409 containerd[2150]: time="2025-11-08T00:05:36.626321094Z" 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\"}" Nov 8 00:05:36.628519 containerd[2150]: time="2025-11-08T00:05:36.628129302Z" 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 547.372467ms" Nov 8 00:05:36.632479 containerd[2150]: time="2025-11-08T00:05:36.632324550Z" 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 553.690263ms" Nov 8 00:05:36.763594 kubelet[2602]: E1108 00:05:36.763464 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:36.807216 containerd[2150]: time="2025-11-08T00:05:36.806425819Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:05:36.807216 containerd[2150]: time="2025-11-08T00:05:36.806628511Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:05:36.807216 containerd[2150]: time="2025-11-08T00:05:36.806745859Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:36.808560 containerd[2150]: time="2025-11-08T00:05:36.808462723Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:36.813870 containerd[2150]: time="2025-11-08T00:05:36.813689143Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:05:36.813870 containerd[2150]: time="2025-11-08T00:05:36.813780439Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:05:36.813870 containerd[2150]: time="2025-11-08T00:05:36.813823807Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:36.814206 containerd[2150]: time="2025-11-08T00:05:36.813972883Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:37.013018 containerd[2150]: time="2025-11-08T00:05:37.012960352Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mcf2f,Uid:b781501f-dc5a-4a2c-bbbb-b4809677c6bb,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\"" Nov 8 00:05:37.018994 containerd[2150]: time="2025-11-08T00:05:37.018914464Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 8 00:05:37.022635 containerd[2150]: time="2025-11-08T00:05:37.022489864Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-swjjs,Uid:1c216eac-d606-4aa5-990f-4dc33f9230a9,Namespace:kube-system,Attempt:0,} returns sandbox id \"c19657bd944568d8fc52b6af299846c6733eab7ebb03d6c9fca1527f020d5471\"" Nov 8 00:05:37.763960 kubelet[2602]: E1108 00:05:37.763880 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:38.766461 kubelet[2602]: E1108 00:05:38.766381 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:38.785017 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4215340893.mount: Deactivated successfully. Nov 8 00:05:38.854680 containerd[2150]: time="2025-11-08T00:05:38.854613717Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:38.856638 containerd[2150]: time="2025-11-08T00:05:38.856568961Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 8 00:05:38.860282 containerd[2150]: time="2025-11-08T00:05:38.860180349Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:38.865303 containerd[2150]: time="2025-11-08T00:05:38.864772041Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:38.866721 containerd[2150]: time="2025-11-08T00:05:38.866657385Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.847341857s" Nov 8 00:05:38.866812 containerd[2150]: time="2025-11-08T00:05:38.866719161Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 8 00:05:38.870576 containerd[2150]: time="2025-11-08T00:05:38.870275949Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Nov 8 00:05:38.873171 containerd[2150]: time="2025-11-08T00:05:38.873118833Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 8 00:05:38.905231 containerd[2150]: time="2025-11-08T00:05:38.905064213Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952\"" Nov 8 00:05:38.907877 containerd[2150]: time="2025-11-08T00:05:38.906363009Z" level=info msg="StartContainer for \"6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952\"" Nov 8 00:05:39.004263 containerd[2150]: time="2025-11-08T00:05:39.004106874Z" level=info msg="StartContainer for \"6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952\" returns successfully" Nov 8 00:05:39.072661 containerd[2150]: time="2025-11-08T00:05:39.072507570Z" level=info msg="shim disconnected" id=6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952 namespace=k8s.io Nov 8 00:05:39.073703 containerd[2150]: time="2025-11-08T00:05:39.073655310Z" level=warning msg="cleaning up after shim disconnected" id=6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952 namespace=k8s.io Nov 8 00:05:39.073987 containerd[2150]: time="2025-11-08T00:05:39.073957746Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 8 00:05:39.644912 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6f902dc065eba1415919383d0e6b2460efde42bee06d2770355bf8e18c073952-rootfs.mount: Deactivated successfully. Nov 8 00:05:39.767496 kubelet[2602]: E1108 00:05:39.767428 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:40.166544 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1989736114.mount: Deactivated successfully. Nov 8 00:05:40.755566 containerd[2150]: time="2025-11-08T00:05:40.755485823Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:40.757488 containerd[2150]: time="2025-11-08T00:05:40.757416491Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=27417817" Nov 8 00:05:40.759964 containerd[2150]: time="2025-11-08T00:05:40.759892055Z" level=info msg="ImageCreate event name:\"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:40.764676 containerd[2150]: time="2025-11-08T00:05:40.764590427Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:40.767038 containerd[2150]: time="2025-11-08T00:05:40.765918803Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.9\" with image id \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\", repo tag \"registry.k8s.io/kube-proxy:v1.32.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\", size \"27416836\" in 1.89557587s" Nov 8 00:05:40.767038 containerd[2150]: time="2025-11-08T00:05:40.765982511Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\"" Nov 8 00:05:40.768229 kubelet[2602]: E1108 00:05:40.768173 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:40.768908 containerd[2150]: time="2025-11-08T00:05:40.768803171Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 8 00:05:40.770371 containerd[2150]: time="2025-11-08T00:05:40.770298743Z" level=info msg="CreateContainer within sandbox \"c19657bd944568d8fc52b6af299846c6733eab7ebb03d6c9fca1527f020d5471\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 8 00:05:40.802345 containerd[2150]: time="2025-11-08T00:05:40.802221395Z" level=info msg="CreateContainer within sandbox \"c19657bd944568d8fc52b6af299846c6733eab7ebb03d6c9fca1527f020d5471\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"46f9f5f929abed0f10b2ee78ce1a92be97d5adbac77fd732ef4255dc9eaa2655\"" Nov 8 00:05:40.804322 containerd[2150]: time="2025-11-08T00:05:40.803180471Z" level=info msg="StartContainer for \"46f9f5f929abed0f10b2ee78ce1a92be97d5adbac77fd732ef4255dc9eaa2655\"" Nov 8 00:05:40.859410 systemd[1]: run-containerd-runc-k8s.io-46f9f5f929abed0f10b2ee78ce1a92be97d5adbac77fd732ef4255dc9eaa2655-runc.AfX328.mount: Deactivated successfully. Nov 8 00:05:40.916779 containerd[2150]: time="2025-11-08T00:05:40.916712387Z" level=info msg="StartContainer for \"46f9f5f929abed0f10b2ee78ce1a92be97d5adbac77fd732ef4255dc9eaa2655\" returns successfully" Nov 8 00:05:41.002356 kubelet[2602]: I1108 00:05:41.001038 2602 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-swjjs" podStartSLOduration=2.260216033 podStartE2EDuration="6.001013048s" podCreationTimestamp="2025-11-08 00:05:35 +0000 UTC" firstStartedPulling="2025-11-08 00:05:37.026585728 +0000 UTC m=+4.160202202" lastFinishedPulling="2025-11-08 00:05:40.767382755 +0000 UTC m=+7.900999217" observedRunningTime="2025-11-08 00:05:40.99932358 +0000 UTC m=+8.132940090" watchObservedRunningTime="2025-11-08 00:05:41.001013048 +0000 UTC m=+8.134629534" Nov 8 00:05:41.768398 kubelet[2602]: E1108 00:05:41.768341 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:42.708220 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4218499856.mount: Deactivated successfully. Nov 8 00:05:42.769220 kubelet[2602]: E1108 00:05:42.769163 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:43.770385 kubelet[2602]: E1108 00:05:43.770337 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:43.845766 containerd[2150]: time="2025-11-08T00:05:43.845686046Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:43.848107 containerd[2150]: time="2025-11-08T00:05:43.848028854Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 8 00:05:43.850753 containerd[2150]: time="2025-11-08T00:05:43.850361246Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:43.858792 containerd[2150]: time="2025-11-08T00:05:43.858731750Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:43.863712 containerd[2150]: time="2025-11-08T00:05:43.863519594Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 3.094654335s" Nov 8 00:05:43.863712 containerd[2150]: time="2025-11-08T00:05:43.863579318Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 8 00:05:43.867485 containerd[2150]: time="2025-11-08T00:05:43.867293918Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 8 00:05:43.893320 containerd[2150]: time="2025-11-08T00:05:43.893123234Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c\"" Nov 8 00:05:43.894529 containerd[2150]: time="2025-11-08T00:05:43.893969666Z" level=info msg="StartContainer for \"0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c\"" Nov 8 00:05:43.991107 containerd[2150]: time="2025-11-08T00:05:43.990655563Z" level=info msg="StartContainer for \"0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c\" returns successfully" Nov 8 00:05:44.028081 kubelet[2602]: I1108 00:05:44.027774 2602 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 8 00:05:44.032167 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c-rootfs.mount: Deactivated successfully. Nov 8 00:05:44.475625 containerd[2150]: time="2025-11-08T00:05:44.475349833Z" level=info msg="shim disconnected" id=0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c namespace=k8s.io Nov 8 00:05:44.475625 containerd[2150]: time="2025-11-08T00:05:44.475448881Z" level=warning msg="cleaning up after shim disconnected" id=0fa1fd02bca674150f8a83ae47b6b4ec02e2d3ab5e904aa7bd8303d57712783c namespace=k8s.io Nov 8 00:05:44.475625 containerd[2150]: time="2025-11-08T00:05:44.475469221Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 8 00:05:44.771481 kubelet[2602]: E1108 00:05:44.771419 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:45.013009 containerd[2150]: time="2025-11-08T00:05:45.012696576Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 8 00:05:45.044063 containerd[2150]: time="2025-11-08T00:05:45.043797816Z" level=info msg="CreateContainer within sandbox \"1e32134c5b5f2bd77c7ec38856c03349ac70e97513fb747814bab999b6a77c7c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"d3078d429ae881d94b0b45f9ad4eb1ee3bf57a024b694c86d10112991edd0935\"" Nov 8 00:05:45.045192 containerd[2150]: time="2025-11-08T00:05:45.045131748Z" level=info msg="StartContainer for \"d3078d429ae881d94b0b45f9ad4eb1ee3bf57a024b694c86d10112991edd0935\"" Nov 8 00:05:45.138928 containerd[2150]: time="2025-11-08T00:05:45.138685680Z" level=info msg="StartContainer for \"d3078d429ae881d94b0b45f9ad4eb1ee3bf57a024b694c86d10112991edd0935\" returns successfully" Nov 8 00:05:45.771836 kubelet[2602]: E1108 00:05:45.771722 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:46.032605 kubelet[2602]: I1108 00:05:46.032387 2602 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-mcf2f" podStartSLOduration=4.184215139 podStartE2EDuration="11.032361193s" podCreationTimestamp="2025-11-08 00:05:35 +0000 UTC" firstStartedPulling="2025-11-08 00:05:37.016590076 +0000 UTC m=+4.150206550" lastFinishedPulling="2025-11-08 00:05:43.864736142 +0000 UTC m=+10.998352604" observedRunningTime="2025-11-08 00:05:46.031482145 +0000 UTC m=+13.165098631" watchObservedRunningTime="2025-11-08 00:05:46.032361193 +0000 UTC m=+13.165977667" Nov 8 00:05:46.225540 (udev-worker)[3101]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:05:46.242035 systemd-networkd[1699]: flannel.1: Link UP Nov 8 00:05:46.242052 systemd-networkd[1699]: flannel.1: Gained carrier Nov 8 00:05:46.772760 kubelet[2602]: E1108 00:05:46.772697 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:47.321619 systemd-networkd[1699]: flannel.1: Gained IPv6LL Nov 8 00:05:47.584159 kubelet[2602]: I1108 00:05:47.583990 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7lr7w\" (UniqueName: \"kubernetes.io/projected/92d75a51-8ef9-4364-b694-662c26a46432-kube-api-access-7lr7w\") pod \"nginx-deployment-7fcdb87857-hf225\" (UID: \"92d75a51-8ef9-4364-b694-662c26a46432\") " pod="default/nginx-deployment-7fcdb87857-hf225" Nov 8 00:05:47.773091 kubelet[2602]: E1108 00:05:47.773017 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:47.797607 containerd[2150]: time="2025-11-08T00:05:47.797529210Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hf225,Uid:92d75a51-8ef9-4364-b694-662c26a46432,Namespace:default,Attempt:0,}" Nov 8 00:05:47.851394 systemd-networkd[1699]: cni0: Link UP Nov 8 00:05:47.851411 systemd-networkd[1699]: cni0: Gained carrier Nov 8 00:05:47.852721 systemd-networkd[1699]: cni0: Lost carrier Nov 8 00:05:47.862535 systemd-networkd[1699]: veth5a0aca5f: Link UP Nov 8 00:05:47.863881 (udev-worker)[3102]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:05:47.874573 kernel: cni0: port 1(veth5a0aca5f) entered blocking state Nov 8 00:05:47.874717 kernel: cni0: port 1(veth5a0aca5f) entered disabled state Nov 8 00:05:47.874778 kernel: veth5a0aca5f: entered allmulticast mode Nov 8 00:05:47.877198 kernel: veth5a0aca5f: entered promiscuous mode Nov 8 00:05:47.880945 kernel: cni0: port 1(veth5a0aca5f) entered blocking state Nov 8 00:05:47.881067 kernel: cni0: port 1(veth5a0aca5f) entered forwarding state Nov 8 00:05:47.883287 kernel: cni0: port 1(veth5a0aca5f) entered disabled state Nov 8 00:05:47.903939 kernel: cni0: port 1(veth5a0aca5f) entered blocking state Nov 8 00:05:47.905482 kernel: cni0: port 1(veth5a0aca5f) entered forwarding state Nov 8 00:05:47.904505 systemd-networkd[1699]: veth5a0aca5f: Gained carrier Nov 8 00:05:47.905061 systemd-networkd[1699]: cni0: Gained carrier Nov 8 00:05:47.912510 containerd[2150]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.2.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Nov 8 00:05:47.912510 containerd[2150]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:05:47.956116 containerd[2150]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.2.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:05:47.955704306Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:05:47.956116 containerd[2150]: time="2025-11-08T00:05:47.955802478Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:05:47.956116 containerd[2150]: time="2025-11-08T00:05:47.955830954Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:47.956431 containerd[2150]: time="2025-11-08T00:05:47.956034570Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:48.054141 containerd[2150]: time="2025-11-08T00:05:48.053986911Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hf225,Uid:92d75a51-8ef9-4364-b694-662c26a46432,Namespace:default,Attempt:0,} returns sandbox id \"6abf3ec765e9b4726f9e85364f628cf9495c66b7bd15dfd47c452a89e9bdc5a4\"" Nov 8 00:05:48.056226 containerd[2150]: time="2025-11-08T00:05:48.056140803Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 8 00:05:48.773639 kubelet[2602]: E1108 00:05:48.773568 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:48.921537 systemd-networkd[1699]: cni0: Gained IPv6LL Nov 8 00:05:49.689504 systemd-networkd[1699]: veth5a0aca5f: Gained IPv6LL Nov 8 00:05:49.774372 kubelet[2602]: E1108 00:05:49.774312 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:50.775131 kubelet[2602]: E1108 00:05:50.775084 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:51.287222 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount65884495.mount: Deactivated successfully. Nov 8 00:05:51.776220 kubelet[2602]: E1108 00:05:51.776177 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:52.036033 ntpd[2103]: Listen normally on 6 flannel.1 192.168.2.0:123 Nov 8 00:05:52.038114 ntpd[2103]: 8 Nov 00:05:52 ntpd[2103]: Listen normally on 6 flannel.1 192.168.2.0:123 Nov 8 00:05:52.038114 ntpd[2103]: 8 Nov 00:05:52 ntpd[2103]: Listen normally on 7 cni0 192.168.2.1:123 Nov 8 00:05:52.038114 ntpd[2103]: 8 Nov 00:05:52 ntpd[2103]: Listen normally on 8 flannel.1 [fe80::b4de:55ff:fe6f:c16c%3]:123 Nov 8 00:05:52.038114 ntpd[2103]: 8 Nov 00:05:52 ntpd[2103]: Listen normally on 9 cni0 [fe80::2c3c:73ff:fe69:13b2%4]:123 Nov 8 00:05:52.038114 ntpd[2103]: 8 Nov 00:05:52 ntpd[2103]: Listen normally on 10 veth5a0aca5f [fe80::7c75:6dff:fed1:f4e0%5]:123 Nov 8 00:05:52.036160 ntpd[2103]: Listen normally on 7 cni0 192.168.2.1:123 Nov 8 00:05:52.036321 ntpd[2103]: Listen normally on 8 flannel.1 [fe80::b4de:55ff:fe6f:c16c%3]:123 Nov 8 00:05:52.036414 ntpd[2103]: Listen normally on 9 cni0 [fe80::2c3c:73ff:fe69:13b2%4]:123 Nov 8 00:05:52.036492 ntpd[2103]: Listen normally on 10 veth5a0aca5f [fe80::7c75:6dff:fed1:f4e0%5]:123 Nov 8 00:05:52.092448 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 8 00:05:52.777588 kubelet[2602]: E1108 00:05:52.777542 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:52.801102 containerd[2150]: time="2025-11-08T00:05:52.801010019Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:52.803296 containerd[2150]: time="2025-11-08T00:05:52.803112239Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 8 00:05:52.807277 containerd[2150]: time="2025-11-08T00:05:52.806352179Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:52.812500 containerd[2150]: time="2025-11-08T00:05:52.812413559Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:05:52.813914 containerd[2150]: time="2025-11-08T00:05:52.813865595Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 4.757647884s" Nov 8 00:05:52.814082 containerd[2150]: time="2025-11-08T00:05:52.814053011Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 8 00:05:52.818144 containerd[2150]: time="2025-11-08T00:05:52.818091395Z" level=info msg="CreateContainer within sandbox \"6abf3ec765e9b4726f9e85364f628cf9495c66b7bd15dfd47c452a89e9bdc5a4\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 8 00:05:52.841708 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3117231245.mount: Deactivated successfully. Nov 8 00:05:52.859882 containerd[2150]: time="2025-11-08T00:05:52.859810043Z" level=info msg="CreateContainer within sandbox \"6abf3ec765e9b4726f9e85364f628cf9495c66b7bd15dfd47c452a89e9bdc5a4\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b53f41d7907d92205aecc6dcfd746219007d3e2e1c255beecec70bff79464e88\"" Nov 8 00:05:52.860606 containerd[2150]: time="2025-11-08T00:05:52.860541023Z" level=info msg="StartContainer for \"b53f41d7907d92205aecc6dcfd746219007d3e2e1c255beecec70bff79464e88\"" Nov 8 00:05:52.968457 containerd[2150]: time="2025-11-08T00:05:52.968398571Z" level=info msg="StartContainer for \"b53f41d7907d92205aecc6dcfd746219007d3e2e1c255beecec70bff79464e88\" returns successfully" Nov 8 00:05:53.070779 kubelet[2602]: I1108 00:05:53.070552 2602 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-hf225" podStartSLOduration=1.31015276 podStartE2EDuration="6.070529324s" podCreationTimestamp="2025-11-08 00:05:47 +0000 UTC" firstStartedPulling="2025-11-08 00:05:48.055572147 +0000 UTC m=+15.189188633" lastFinishedPulling="2025-11-08 00:05:52.815948735 +0000 UTC m=+19.949565197" observedRunningTime="2025-11-08 00:05:53.066645788 +0000 UTC m=+20.200262286" watchObservedRunningTime="2025-11-08 00:05:53.070529324 +0000 UTC m=+20.204145798" Nov 8 00:05:53.779353 kubelet[2602]: E1108 00:05:53.779282 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:54.760098 kubelet[2602]: E1108 00:05:54.760014 2602 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:54.780306 kubelet[2602]: E1108 00:05:54.780212 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:55.442199 kubelet[2602]: I1108 00:05:55.442037 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1-data\") pod \"nfs-server-provisioner-0\" (UID: \"d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1\") " pod="default/nfs-server-provisioner-0" Nov 8 00:05:55.442199 kubelet[2602]: I1108 00:05:55.442122 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jskz9\" (UniqueName: \"kubernetes.io/projected/d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1-kube-api-access-jskz9\") pod \"nfs-server-provisioner-0\" (UID: \"d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1\") " pod="default/nfs-server-provisioner-0" Nov 8 00:05:55.694733 containerd[2150]: time="2025-11-08T00:05:55.694568425Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1,Namespace:default,Attempt:0,}" Nov 8 00:05:55.738609 (udev-worker)[3370]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:05:55.739461 systemd-networkd[1699]: veth8ce6b625: Link UP Nov 8 00:05:55.745147 kernel: cni0: port 2(veth8ce6b625) entered blocking state Nov 8 00:05:55.745268 kernel: cni0: port 2(veth8ce6b625) entered disabled state Nov 8 00:05:55.746557 kernel: veth8ce6b625: entered allmulticast mode Nov 8 00:05:55.747863 kernel: veth8ce6b625: entered promiscuous mode Nov 8 00:05:55.765691 kernel: cni0: port 2(veth8ce6b625) entered blocking state Nov 8 00:05:55.765868 kernel: cni0: port 2(veth8ce6b625) entered forwarding state Nov 8 00:05:55.766218 systemd-networkd[1699]: veth8ce6b625: Gained carrier Nov 8 00:05:55.771721 containerd[2150]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.2.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001e938), "name":"cbr0", "type":"bridge"} Nov 8 00:05:55.771721 containerd[2150]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:05:55.781402 kubelet[2602]: E1108 00:05:55.780847 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:55.812546 containerd[2150]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.2.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:05:55.812404441Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:05:55.812780 containerd[2150]: time="2025-11-08T00:05:55.812499301Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:05:55.812780 containerd[2150]: time="2025-11-08T00:05:55.812557501Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:55.813021 containerd[2150]: time="2025-11-08T00:05:55.812825497Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:05:55.908338 containerd[2150]: time="2025-11-08T00:05:55.908206214Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d2f0d361-7e9f-4c6b-8a05-be91ea6e9fc1,Namespace:default,Attempt:0,} returns sandbox id \"13502effbdb8597b55caf67c1e1a0cd7203f930eb2830ea0d1f5a75d927e2a1a\"" Nov 8 00:05:55.911544 containerd[2150]: time="2025-11-08T00:05:55.911179154Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 8 00:05:56.781737 kubelet[2602]: E1108 00:05:56.781690 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:56.921959 systemd-networkd[1699]: veth8ce6b625: Gained IPv6LL Nov 8 00:05:57.782783 kubelet[2602]: E1108 00:05:57.782730 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:58.562405 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2313207442.mount: Deactivated successfully. Nov 8 00:05:58.783792 kubelet[2602]: E1108 00:05:58.783632 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:05:59.036089 ntpd[2103]: Listen normally on 11 veth8ce6b625 [fe80::78e6:45ff:fe8c:50cf%6]:123 Nov 8 00:05:59.037551 ntpd[2103]: 8 Nov 00:05:59 ntpd[2103]: Listen normally on 11 veth8ce6b625 [fe80::78e6:45ff:fe8c:50cf%6]:123 Nov 8 00:05:59.784841 kubelet[2602]: E1108 00:05:59.784793 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:00.785011 kubelet[2602]: E1108 00:06:00.784933 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:01.785213 kubelet[2602]: E1108 00:06:01.785143 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:01.860115 containerd[2150]: time="2025-11-08T00:06:01.859967168Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:01.862882 containerd[2150]: time="2025-11-08T00:06:01.862771172Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 8 00:06:01.865841 containerd[2150]: time="2025-11-08T00:06:01.864749408Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:01.873384 containerd[2150]: time="2025-11-08T00:06:01.873317060Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:01.876280 containerd[2150]: time="2025-11-08T00:06:01.876158372Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 5.964904914s" Nov 8 00:06:01.876430 containerd[2150]: time="2025-11-08T00:06:01.876287336Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 8 00:06:01.882351 containerd[2150]: time="2025-11-08T00:06:01.882225260Z" level=info msg="CreateContainer within sandbox \"13502effbdb8597b55caf67c1e1a0cd7203f930eb2830ea0d1f5a75d927e2a1a\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 8 00:06:01.916195 containerd[2150]: time="2025-11-08T00:06:01.916117064Z" level=info msg="CreateContainer within sandbox \"13502effbdb8597b55caf67c1e1a0cd7203f930eb2830ea0d1f5a75d927e2a1a\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"761c10ca555f2be93451934a883f2f438b30002389c308db5411c3143ad2c07c\"" Nov 8 00:06:01.918333 containerd[2150]: time="2025-11-08T00:06:01.917348708Z" level=info msg="StartContainer for \"761c10ca555f2be93451934a883f2f438b30002389c308db5411c3143ad2c07c\"" Nov 8 00:06:01.987306 systemd[1]: run-containerd-runc-k8s.io-761c10ca555f2be93451934a883f2f438b30002389c308db5411c3143ad2c07c-runc.Jj61nV.mount: Deactivated successfully. Nov 8 00:06:02.047106 containerd[2150]: time="2025-11-08T00:06:02.044458408Z" level=info msg="StartContainer for \"761c10ca555f2be93451934a883f2f438b30002389c308db5411c3143ad2c07c\" returns successfully" Nov 8 00:06:02.785378 kubelet[2602]: E1108 00:06:02.785309 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:03.785663 kubelet[2602]: E1108 00:06:03.785597 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:04.786081 kubelet[2602]: E1108 00:06:04.786010 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:05.787144 kubelet[2602]: E1108 00:06:05.787070 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:06.169394 update_engine[2118]: I20251108 00:06:06.168743 2118 update_attempter.cc:509] Updating boot flags... Nov 8 00:06:06.257503 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (3598) Nov 8 00:06:06.579368 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (3589) Nov 8 00:06:06.787401 kubelet[2602]: E1108 00:06:06.787299 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:06.867459 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (3589) Nov 8 00:06:07.788439 kubelet[2602]: E1108 00:06:07.788364 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:08.789195 kubelet[2602]: E1108 00:06:08.789114 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:09.789577 kubelet[2602]: E1108 00:06:09.789511 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:10.790671 kubelet[2602]: E1108 00:06:10.790604 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:11.615171 kubelet[2602]: I1108 00:06:11.615064 2602 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.645852886 podStartE2EDuration="16.615040108s" podCreationTimestamp="2025-11-08 00:05:55 +0000 UTC" firstStartedPulling="2025-11-08 00:05:55.910121198 +0000 UTC m=+23.043737672" lastFinishedPulling="2025-11-08 00:06:01.87930842 +0000 UTC m=+29.012924894" observedRunningTime="2025-11-08 00:06:02.099649925 +0000 UTC m=+29.233266423" watchObservedRunningTime="2025-11-08 00:06:11.615040108 +0000 UTC m=+38.748656606" Nov 8 00:06:11.650136 kubelet[2602]: I1108 00:06:11.650026 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xwq5h\" (UniqueName: \"kubernetes.io/projected/f7f2d3e8-39cf-45ff-9d19-f8480dd88bce-kube-api-access-xwq5h\") pod \"test-pod-1\" (UID: \"f7f2d3e8-39cf-45ff-9d19-f8480dd88bce\") " pod="default/test-pod-1" Nov 8 00:06:11.650136 kubelet[2602]: I1108 00:06:11.650104 2602 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9803c019-ae2c-4ca3-a0e1-24d73d14cd72\" (UniqueName: \"kubernetes.io/nfs/f7f2d3e8-39cf-45ff-9d19-f8480dd88bce-pvc-9803c019-ae2c-4ca3-a0e1-24d73d14cd72\") pod \"test-pod-1\" (UID: \"f7f2d3e8-39cf-45ff-9d19-f8480dd88bce\") " pod="default/test-pod-1" Nov 8 00:06:11.785403 kernel: FS-Cache: Loaded Nov 8 00:06:11.791735 kubelet[2602]: E1108 00:06:11.791623 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:11.829779 kernel: RPC: Registered named UNIX socket transport module. Nov 8 00:06:11.829922 kernel: RPC: Registered udp transport module. Nov 8 00:06:11.829984 kernel: RPC: Registered tcp transport module. Nov 8 00:06:11.832021 kernel: RPC: Registered tcp-with-tls transport module. Nov 8 00:06:11.832100 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 8 00:06:12.159330 kernel: NFS: Registering the id_resolver key type Nov 8 00:06:12.159482 kernel: Key type id_resolver registered Nov 8 00:06:12.159539 kernel: Key type id_legacy registered Nov 8 00:06:12.198916 nfsidmap[3910]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 8 00:06:12.205098 nfsidmap[3911]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 8 00:06:12.229068 containerd[2150]: time="2025-11-08T00:06:12.225096435Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f7f2d3e8-39cf-45ff-9d19-f8480dd88bce,Namespace:default,Attempt:0,}" Nov 8 00:06:12.281929 (udev-worker)[3899]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:12.283802 systemd-networkd[1699]: veth83b05fdd: Link UP Nov 8 00:06:12.286227 kernel: cni0: port 3(veth83b05fdd) entered blocking state Nov 8 00:06:12.286383 kernel: cni0: port 3(veth83b05fdd) entered disabled state Nov 8 00:06:12.289795 kernel: veth83b05fdd: entered allmulticast mode Nov 8 00:06:12.289914 kernel: veth83b05fdd: entered promiscuous mode Nov 8 00:06:12.300591 kernel: cni0: port 3(veth83b05fdd) entered blocking state Nov 8 00:06:12.300726 kernel: cni0: port 3(veth83b05fdd) entered forwarding state Nov 8 00:06:12.301033 systemd-networkd[1699]: veth83b05fdd: Gained carrier Nov 8 00:06:12.311031 containerd[2150]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.2.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a08e8), "name":"cbr0", "type":"bridge"} Nov 8 00:06:12.311031 containerd[2150]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:06:12.347374 containerd[2150]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.2.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:06:12.346863964Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:12.347374 containerd[2150]: time="2025-11-08T00:06:12.346963480Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:12.347374 containerd[2150]: time="2025-11-08T00:06:12.347021644Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:12.347374 containerd[2150]: time="2025-11-08T00:06:12.347202340Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:12.430693 containerd[2150]: time="2025-11-08T00:06:12.430461784Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:f7f2d3e8-39cf-45ff-9d19-f8480dd88bce,Namespace:default,Attempt:0,} returns sandbox id \"0b389054513086dc8355c8da84686a2ff32e19b3082ddf0406287acde07e0f31\"" Nov 8 00:06:12.433838 containerd[2150]: time="2025-11-08T00:06:12.433379956Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 8 00:06:12.769585 containerd[2150]: time="2025-11-08T00:06:12.769509498Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:12.771814 containerd[2150]: time="2025-11-08T00:06:12.771741918Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 8 00:06:12.781616 containerd[2150]: time="2025-11-08T00:06:12.781414398Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 347.97413ms" Nov 8 00:06:12.781616 containerd[2150]: time="2025-11-08T00:06:12.781478226Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 8 00:06:12.785021 containerd[2150]: time="2025-11-08T00:06:12.784951734Z" level=info msg="CreateContainer within sandbox \"0b389054513086dc8355c8da84686a2ff32e19b3082ddf0406287acde07e0f31\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 8 00:06:12.793407 kubelet[2602]: E1108 00:06:12.793342 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:12.812713 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount233085673.mount: Deactivated successfully. Nov 8 00:06:12.816794 containerd[2150]: time="2025-11-08T00:06:12.816598470Z" level=info msg="CreateContainer within sandbox \"0b389054513086dc8355c8da84686a2ff32e19b3082ddf0406287acde07e0f31\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"898f4ee61585f5c40d48cbbb7d104a9990db0e8ac1307c5996210021344bcce6\"" Nov 8 00:06:12.819306 containerd[2150]: time="2025-11-08T00:06:12.818336538Z" level=info msg="StartContainer for \"898f4ee61585f5c40d48cbbb7d104a9990db0e8ac1307c5996210021344bcce6\"" Nov 8 00:06:12.927796 containerd[2150]: time="2025-11-08T00:06:12.927719382Z" level=info msg="StartContainer for \"898f4ee61585f5c40d48cbbb7d104a9990db0e8ac1307c5996210021344bcce6\" returns successfully" Nov 8 00:06:13.122201 kubelet[2602]: I1108 00:06:13.121997 2602 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=16.772339745 podStartE2EDuration="17.121971171s" podCreationTimestamp="2025-11-08 00:05:56 +0000 UTC" firstStartedPulling="2025-11-08 00:06:12.432806752 +0000 UTC m=+39.566423226" lastFinishedPulling="2025-11-08 00:06:12.782438178 +0000 UTC m=+39.916054652" observedRunningTime="2025-11-08 00:06:13.121187223 +0000 UTC m=+40.254803721" watchObservedRunningTime="2025-11-08 00:06:13.121971171 +0000 UTC m=+40.255587633" Nov 8 00:06:13.753498 systemd-networkd[1699]: veth83b05fdd: Gained IPv6LL Nov 8 00:06:13.793689 kubelet[2602]: E1108 00:06:13.793627 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:14.763792 kubelet[2602]: E1108 00:06:14.759810 2602 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:14.793849 kubelet[2602]: E1108 00:06:14.793788 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:15.794419 kubelet[2602]: E1108 00:06:15.794349 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:16.036125 ntpd[2103]: Listen normally on 12 veth83b05fdd [fe80::20c3:eeff:feda:5a99%7]:123 Nov 8 00:06:16.036702 ntpd[2103]: 8 Nov 00:06:16 ntpd[2103]: Listen normally on 12 veth83b05fdd [fe80::20c3:eeff:feda:5a99%7]:123 Nov 8 00:06:16.794749 kubelet[2602]: E1108 00:06:16.794680 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:17.795278 kubelet[2602]: E1108 00:06:17.795206 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:18.795879 kubelet[2602]: E1108 00:06:18.795814 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:19.796371 kubelet[2602]: E1108 00:06:19.796307 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:20.796689 kubelet[2602]: E1108 00:06:20.796618 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:21.797350 kubelet[2602]: E1108 00:06:21.797273 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:22.797653 kubelet[2602]: E1108 00:06:22.797587 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:23.798220 kubelet[2602]: E1108 00:06:23.798155 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:24.798718 kubelet[2602]: E1108 00:06:24.798644 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:25.799509 kubelet[2602]: E1108 00:06:25.799441 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:26.800543 kubelet[2602]: E1108 00:06:26.800468 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:27.801181 kubelet[2602]: E1108 00:06:27.801116 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:28.802053 kubelet[2602]: E1108 00:06:28.801980 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:29.802497 kubelet[2602]: E1108 00:06:29.802432 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:30.803225 kubelet[2602]: E1108 00:06:30.803158 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:31.804393 kubelet[2602]: E1108 00:06:31.804314 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:32.804684 kubelet[2602]: E1108 00:06:32.804611 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:33.805266 kubelet[2602]: E1108 00:06:33.805190 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:34.760031 kubelet[2602]: E1108 00:06:34.759955 2602 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:34.805719 kubelet[2602]: E1108 00:06:34.805437 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:35.806726 kubelet[2602]: E1108 00:06:35.806575 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:35.806726 kubelet[2602]: E1108 00:06:35.806604 2602 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 8 00:06:36.807896 kubelet[2602]: E1108 00:06:36.807704 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:37.808502 kubelet[2602]: E1108 00:06:37.808441 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:38.809525 kubelet[2602]: E1108 00:06:38.809459 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:39.810125 kubelet[2602]: E1108 00:06:39.810048 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:40.810403 kubelet[2602]: E1108 00:06:40.810338 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:41.810588 kubelet[2602]: E1108 00:06:41.810519 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:42.811518 kubelet[2602]: E1108 00:06:42.811443 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:43.812205 kubelet[2602]: E1108 00:06:43.812128 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:44.813188 kubelet[2602]: E1108 00:06:44.813121 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:45.807026 kubelet[2602]: E1108 00:06:45.806862 2602 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 8 00:06:45.814201 kubelet[2602]: E1108 00:06:45.814132 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:46.814386 kubelet[2602]: E1108 00:06:46.814324 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:47.815284 kubelet[2602]: E1108 00:06:47.815200 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:48.815923 kubelet[2602]: E1108 00:06:48.815855 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:49.816378 kubelet[2602]: E1108 00:06:49.816301 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:50.816931 kubelet[2602]: E1108 00:06:50.816865 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:51.817896 kubelet[2602]: E1108 00:06:51.817819 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:52.818203 kubelet[2602]: E1108 00:06:52.818139 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:53.818891 kubelet[2602]: E1108 00:06:53.818810 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:54.760558 kubelet[2602]: E1108 00:06:54.760491 2602 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:54.819587 kubelet[2602]: E1108 00:06:54.819536 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:55.807911 kubelet[2602]: E1108 00:06:55.807662 2602 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 8 00:06:55.820446 kubelet[2602]: E1108 00:06:55.820365 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:56.821536 kubelet[2602]: E1108 00:06:56.821470 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:57.822669 kubelet[2602]: E1108 00:06:57.822587 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:58.823723 kubelet[2602]: E1108 00:06:58.823647 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:59.824414 kubelet[2602]: E1108 00:06:59.824349 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:00.825131 kubelet[2602]: E1108 00:07:00.825063 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:01.825699 kubelet[2602]: E1108 00:07:01.825628 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:02.826953 kubelet[2602]: E1108 00:07:02.826860 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:02.993310 kubelet[2602]: E1108 00:07:02.992066 2602 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": unexpected EOF" Nov 8 00:07:03.002155 kubelet[2602]: E1108 00:07:03.001633 2602 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": read tcp 172.31.20.66:49866->172.31.29.196:6443: read: connection reset by peer" Nov 8 00:07:03.002155 kubelet[2602]: I1108 00:07:03.001701 2602 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Nov 8 00:07:03.002599 kubelet[2602]: E1108 00:07:03.002414 2602 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": dial tcp 172.31.29.196:6443: connect: connection refused" interval="200ms" Nov 8 00:07:03.203499 kubelet[2602]: E1108 00:07:03.203337 2602 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": dial tcp 172.31.29.196:6443: connect: connection refused" interval="400ms" Nov 8 00:07:03.605105 kubelet[2602]: E1108 00:07:03.605005 2602 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": dial tcp 172.31.29.196:6443: connect: connection refused" interval="800ms" Nov 8 00:07:03.827593 kubelet[2602]: E1108 00:07:03.827539 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:04.406835 kubelet[2602]: E1108 00:07:04.406762 2602 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": dial tcp 172.31.29.196:6443: connect: connection refused" interval="1.6s" Nov 8 00:07:04.828018 kubelet[2602]: E1108 00:07:04.827961 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:05.828876 kubelet[2602]: E1108 00:07:05.828807 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:06.008428 kubelet[2602]: E1108 00:07:06.008352 2602 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.196:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.20.66?timeout=10s\": dial tcp 172.31.29.196:6443: connect: connection refused" interval="3.2s" Nov 8 00:07:06.830000 kubelet[2602]: E1108 00:07:06.829918 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:07.831022 kubelet[2602]: E1108 00:07:07.830956 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:08.832032 kubelet[2602]: E1108 00:07:08.831968 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:09.832614 kubelet[2602]: E1108 00:07:09.832550 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:10.833234 kubelet[2602]: E1108 00:07:10.833178 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:11.834120 kubelet[2602]: E1108 00:07:11.834045 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:12.834930 kubelet[2602]: E1108 00:07:12.834870 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:13.835213 kubelet[2602]: E1108 00:07:13.835158 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:14.759895 kubelet[2602]: E1108 00:07:14.759833 2602 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:14.835884 kubelet[2602]: E1108 00:07:14.835844 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:15.837006 kubelet[2602]: E1108 00:07:15.836943 2602 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"