Nov 8 00:06:00.251259 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 8 00:06:00.251317 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:06:00.251345 kernel: KASLR disabled due to lack of seed Nov 8 00:06:00.251362 kernel: efi: EFI v2.7 by EDK II Nov 8 00:06:00.251379 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7affea98 MEMRESERVE=0x7852ee18 Nov 8 00:06:00.251396 kernel: ACPI: Early table checksum verification disabled Nov 8 00:06:00.251417 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 8 00:06:00.251433 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 8 00:06:00.251449 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 8 00:06:00.251465 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 8 00:06:00.251487 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 8 00:06:00.251503 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 8 00:06:00.251519 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 8 00:06:00.251535 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 8 00:06:00.251555 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 8 00:06:00.251576 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 8 00:06:00.251594 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 8 00:06:00.251611 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 8 00:06:00.251628 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 8 00:06:00.251644 kernel: printk: bootconsole [uart0] enabled Nov 8 00:06:00.251661 kernel: NUMA: Failed to initialise from firmware Nov 8 00:06:00.251678 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 8 00:06:00.251696 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 8 00:06:00.251713 kernel: Zone ranges: Nov 8 00:06:00.251730 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 8 00:06:00.251747 kernel: DMA32 empty Nov 8 00:06:00.251768 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 8 00:06:00.251785 kernel: Movable zone start for each node Nov 8 00:06:00.251802 kernel: Early memory node ranges Nov 8 00:06:00.251818 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 8 00:06:00.251835 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 8 00:06:00.251852 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 8 00:06:00.251869 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 8 00:06:00.251886 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 8 00:06:00.251903 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 8 00:06:00.251919 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 8 00:06:00.251936 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 8 00:06:00.251953 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 8 00:06:00.251976 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 8 00:06:00.251994 kernel: psci: probing for conduit method from ACPI. Nov 8 00:06:00.252018 kernel: psci: PSCIv1.0 detected in firmware. Nov 8 00:06:00.252036 kernel: psci: Using standard PSCI v0.2 function IDs Nov 8 00:06:00.252054 kernel: psci: Trusted OS migration not required Nov 8 00:06:00.252076 kernel: psci: SMC Calling Convention v1.1 Nov 8 00:06:00.252095 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Nov 8 00:06:00.252112 kernel: percpu: Embedded 31 pages/cpu s86120 r8192 d32664 u126976 Nov 8 00:06:00.252130 kernel: pcpu-alloc: s86120 r8192 d32664 u126976 alloc=31*4096 Nov 8 00:06:00.252148 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 8 00:06:00.252208 kernel: Detected PIPT I-cache on CPU0 Nov 8 00:06:00.252228 kernel: CPU features: detected: GIC system register CPU interface Nov 8 00:06:00.252246 kernel: CPU features: detected: Spectre-v2 Nov 8 00:06:00.252264 kernel: CPU features: detected: Spectre-v3a Nov 8 00:06:00.252282 kernel: CPU features: detected: Spectre-BHB Nov 8 00:06:00.252300 kernel: CPU features: detected: ARM erratum 1742098 Nov 8 00:06:00.252327 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 8 00:06:00.252345 kernel: alternatives: applying boot alternatives Nov 8 00:06:00.252365 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:06:00.252384 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 8 00:06:00.252402 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 8 00:06:00.252420 kernel: Fallback order for Node 0: 0 Nov 8 00:06:00.252438 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 8 00:06:00.252456 kernel: Policy zone: Normal Nov 8 00:06:00.252473 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 8 00:06:00.252491 kernel: software IO TLB: area num 2. Nov 8 00:06:00.252508 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 8 00:06:00.252532 kernel: Memory: 3820088K/4030464K available (10304K kernel code, 2180K rwdata, 8112K rodata, 39424K init, 897K bss, 210376K reserved, 0K cma-reserved) Nov 8 00:06:00.252550 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 8 00:06:00.252568 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 8 00:06:00.252587 kernel: rcu: RCU event tracing is enabled. Nov 8 00:06:00.252605 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 8 00:06:00.252623 kernel: Trampoline variant of Tasks RCU enabled. Nov 8 00:06:00.252641 kernel: Tracing variant of Tasks RCU enabled. Nov 8 00:06:00.252659 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 8 00:06:00.252677 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 8 00:06:00.252694 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 8 00:06:00.252712 kernel: GICv3: 96 SPIs implemented Nov 8 00:06:00.252734 kernel: GICv3: 0 Extended SPIs implemented Nov 8 00:06:00.252752 kernel: Root IRQ handler: gic_handle_irq Nov 8 00:06:00.252769 kernel: GICv3: GICv3 features: 16 PPIs Nov 8 00:06:00.252787 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 8 00:06:00.252804 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 8 00:06:00.252822 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 8 00:06:00.252840 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 8 00:06:00.252858 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 8 00:06:00.252876 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 8 00:06:00.252894 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 8 00:06:00.252911 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 8 00:06:00.252929 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 8 00:06:00.252951 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 8 00:06:00.252969 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 8 00:06:00.252987 kernel: Console: colour dummy device 80x25 Nov 8 00:06:00.253005 kernel: printk: console [tty1] enabled Nov 8 00:06:00.253023 kernel: ACPI: Core revision 20230628 Nov 8 00:06:00.253042 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 8 00:06:00.253060 kernel: pid_max: default: 32768 minimum: 301 Nov 8 00:06:00.253078 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 8 00:06:00.253096 kernel: landlock: Up and running. Nov 8 00:06:00.253119 kernel: SELinux: Initializing. Nov 8 00:06:00.253137 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 00:06:00.260201 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 8 00:06:00.260248 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 8 00:06:00.260268 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:06:00.260287 kernel: rcu: Hierarchical SRCU implementation. Nov 8 00:06:00.260307 kernel: rcu: Max phase no-delay instances is 400. Nov 8 00:06:00.260325 kernel: Platform MSI: ITS@0x10080000 domain created Nov 8 00:06:00.260344 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 8 00:06:00.260372 kernel: Remapping and enabling EFI services. Nov 8 00:06:00.260390 kernel: smp: Bringing up secondary CPUs ... Nov 8 00:06:00.260409 kernel: Detected PIPT I-cache on CPU1 Nov 8 00:06:00.260427 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 8 00:06:00.260445 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 8 00:06:00.260463 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 8 00:06:00.260482 kernel: smp: Brought up 1 node, 2 CPUs Nov 8 00:06:00.260500 kernel: SMP: Total of 2 processors activated. Nov 8 00:06:00.260518 kernel: CPU features: detected: 32-bit EL0 Support Nov 8 00:06:00.260541 kernel: CPU features: detected: 32-bit EL1 Support Nov 8 00:06:00.260559 kernel: CPU features: detected: CRC32 instructions Nov 8 00:06:00.260578 kernel: CPU: All CPU(s) started at EL1 Nov 8 00:06:00.260607 kernel: alternatives: applying system-wide alternatives Nov 8 00:06:00.260631 kernel: devtmpfs: initialized Nov 8 00:06:00.260650 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 8 00:06:00.260669 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 8 00:06:00.260688 kernel: pinctrl core: initialized pinctrl subsystem Nov 8 00:06:00.260706 kernel: SMBIOS 3.0.0 present. Nov 8 00:06:00.260730 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 8 00:06:00.260749 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 8 00:06:00.260768 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 8 00:06:00.260787 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 8 00:06:00.260807 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 8 00:06:00.260825 kernel: audit: initializing netlink subsys (disabled) Nov 8 00:06:00.260845 kernel: audit: type=2000 audit(0.287:1): state=initialized audit_enabled=0 res=1 Nov 8 00:06:00.260864 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 8 00:06:00.260888 kernel: cpuidle: using governor menu Nov 8 00:06:00.260906 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 8 00:06:00.260925 kernel: ASID allocator initialised with 65536 entries Nov 8 00:06:00.260945 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 8 00:06:00.260963 kernel: Serial: AMBA PL011 UART driver Nov 8 00:06:00.260982 kernel: Modules: 17488 pages in range for non-PLT usage Nov 8 00:06:00.261001 kernel: Modules: 509008 pages in range for PLT usage Nov 8 00:06:00.261020 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 8 00:06:00.261039 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 8 00:06:00.261062 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 8 00:06:00.261082 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 8 00:06:00.261101 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 8 00:06:00.261120 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 8 00:06:00.261139 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 8 00:06:00.261180 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 8 00:06:00.261204 kernel: ACPI: Added _OSI(Module Device) Nov 8 00:06:00.261223 kernel: ACPI: Added _OSI(Processor Device) Nov 8 00:06:00.261243 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 8 00:06:00.261268 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 8 00:06:00.261288 kernel: ACPI: Interpreter enabled Nov 8 00:06:00.261307 kernel: ACPI: Using GIC for interrupt routing Nov 8 00:06:00.261325 kernel: ACPI: MCFG table detected, 1 entries Nov 8 00:06:00.261344 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 8 00:06:00.261661 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 8 00:06:00.261881 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 8 00:06:00.262119 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 8 00:06:00.262467 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 8 00:06:00.262705 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 8 00:06:00.262734 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 8 00:06:00.262754 kernel: acpiphp: Slot [1] registered Nov 8 00:06:00.262775 kernel: acpiphp: Slot [2] registered Nov 8 00:06:00.262794 kernel: acpiphp: Slot [3] registered Nov 8 00:06:00.262815 kernel: acpiphp: Slot [4] registered Nov 8 00:06:00.262835 kernel: acpiphp: Slot [5] registered Nov 8 00:06:00.262865 kernel: acpiphp: Slot [6] registered Nov 8 00:06:00.262892 kernel: acpiphp: Slot [7] registered Nov 8 00:06:00.262911 kernel: acpiphp: Slot [8] registered Nov 8 00:06:00.262931 kernel: acpiphp: Slot [9] registered Nov 8 00:06:00.262950 kernel: acpiphp: Slot [10] registered Nov 8 00:06:00.262970 kernel: acpiphp: Slot [11] registered Nov 8 00:06:00.262989 kernel: acpiphp: Slot [12] registered Nov 8 00:06:00.263008 kernel: acpiphp: Slot [13] registered Nov 8 00:06:00.263027 kernel: acpiphp: Slot [14] registered Nov 8 00:06:00.263046 kernel: acpiphp: Slot [15] registered Nov 8 00:06:00.263071 kernel: acpiphp: Slot [16] registered Nov 8 00:06:00.263090 kernel: acpiphp: Slot [17] registered Nov 8 00:06:00.263109 kernel: acpiphp: Slot [18] registered Nov 8 00:06:00.263128 kernel: acpiphp: Slot [19] registered Nov 8 00:06:00.263148 kernel: acpiphp: Slot [20] registered Nov 8 00:06:00.263207 kernel: acpiphp: Slot [21] registered Nov 8 00:06:00.263228 kernel: acpiphp: Slot [22] registered Nov 8 00:06:00.263247 kernel: acpiphp: Slot [23] registered Nov 8 00:06:00.263266 kernel: acpiphp: Slot [24] registered Nov 8 00:06:00.263292 kernel: acpiphp: Slot [25] registered Nov 8 00:06:00.263312 kernel: acpiphp: Slot [26] registered Nov 8 00:06:00.263332 kernel: acpiphp: Slot [27] registered Nov 8 00:06:00.263352 kernel: acpiphp: Slot [28] registered Nov 8 00:06:00.263372 kernel: acpiphp: Slot [29] registered Nov 8 00:06:00.263392 kernel: acpiphp: Slot [30] registered Nov 8 00:06:00.263411 kernel: acpiphp: Slot [31] registered Nov 8 00:06:00.263430 kernel: PCI host bridge to bus 0000:00 Nov 8 00:06:00.263704 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 8 00:06:00.263912 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 8 00:06:00.264106 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 8 00:06:00.267972 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 8 00:06:00.268277 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 8 00:06:00.268509 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 8 00:06:00.268723 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 8 00:06:00.268960 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 8 00:06:00.269205 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 8 00:06:00.269424 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 8 00:06:00.269651 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 8 00:06:00.269870 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 8 00:06:00.270122 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 8 00:06:00.271473 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 8 00:06:00.271716 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 8 00:06:00.271930 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 8 00:06:00.272141 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 8 00:06:00.274274 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 8 00:06:00.274507 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 8 00:06:00.274722 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 8 00:06:00.274922 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 8 00:06:00.275122 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 8 00:06:00.279012 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 8 00:06:00.279059 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 8 00:06:00.279079 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 8 00:06:00.279100 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 8 00:06:00.279119 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 8 00:06:00.279138 kernel: iommu: Default domain type: Translated Nov 8 00:06:00.279238 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 8 00:06:00.279263 kernel: efivars: Registered efivars operations Nov 8 00:06:00.279293 kernel: vgaarb: loaded Nov 8 00:06:00.279313 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 8 00:06:00.279332 kernel: VFS: Disk quotas dquot_6.6.0 Nov 8 00:06:00.279351 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 8 00:06:00.279370 kernel: pnp: PnP ACPI init Nov 8 00:06:00.279600 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 8 00:06:00.279628 kernel: pnp: PnP ACPI: found 1 devices Nov 8 00:06:00.279649 kernel: NET: Registered PF_INET protocol family Nov 8 00:06:00.279674 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 8 00:06:00.279694 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 8 00:06:00.279713 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 8 00:06:00.279732 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 8 00:06:00.279751 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 8 00:06:00.279771 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 8 00:06:00.279790 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 00:06:00.279808 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 8 00:06:00.279827 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 8 00:06:00.279851 kernel: PCI: CLS 0 bytes, default 64 Nov 8 00:06:00.279870 kernel: kvm [1]: HYP mode not available Nov 8 00:06:00.279889 kernel: Initialise system trusted keyrings Nov 8 00:06:00.279907 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 8 00:06:00.279926 kernel: Key type asymmetric registered Nov 8 00:06:00.279945 kernel: Asymmetric key parser 'x509' registered Nov 8 00:06:00.279964 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 8 00:06:00.279983 kernel: io scheduler mq-deadline registered Nov 8 00:06:00.280001 kernel: io scheduler kyber registered Nov 8 00:06:00.280025 kernel: io scheduler bfq registered Nov 8 00:06:00.280275 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 8 00:06:00.280304 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 8 00:06:00.280324 kernel: ACPI: button: Power Button [PWRB] Nov 8 00:06:00.280343 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 8 00:06:00.280362 kernel: ACPI: button: Sleep Button [SLPB] Nov 8 00:06:00.280381 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 8 00:06:00.280401 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 8 00:06:00.280621 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 8 00:06:00.280649 kernel: printk: console [ttyS0] disabled Nov 8 00:06:00.280668 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 8 00:06:00.280687 kernel: printk: console [ttyS0] enabled Nov 8 00:06:00.280706 kernel: printk: bootconsole [uart0] disabled Nov 8 00:06:00.280725 kernel: thunder_xcv, ver 1.0 Nov 8 00:06:00.280744 kernel: thunder_bgx, ver 1.0 Nov 8 00:06:00.280763 kernel: nicpf, ver 1.0 Nov 8 00:06:00.280782 kernel: nicvf, ver 1.0 Nov 8 00:06:00.281005 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 8 00:06:00.281225 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-08T00:05:59 UTC (1762560359) Nov 8 00:06:00.281253 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 8 00:06:00.281272 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 8 00:06:00.281292 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 8 00:06:00.281311 kernel: watchdog: Hard watchdog permanently disabled Nov 8 00:06:00.281329 kernel: NET: Registered PF_INET6 protocol family Nov 8 00:06:00.281348 kernel: Segment Routing with IPv6 Nov 8 00:06:00.281373 kernel: In-situ OAM (IOAM) with IPv6 Nov 8 00:06:00.281393 kernel: NET: Registered PF_PACKET protocol family Nov 8 00:06:00.281412 kernel: Key type dns_resolver registered Nov 8 00:06:00.281432 kernel: registered taskstats version 1 Nov 8 00:06:00.281450 kernel: Loading compiled-in X.509 certificates Nov 8 00:06:00.281469 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: e35af6a719ba4c60f9d6788b11f5e5836ebf73b5' Nov 8 00:06:00.281488 kernel: Key type .fscrypt registered Nov 8 00:06:00.281507 kernel: Key type fscrypt-provisioning registered Nov 8 00:06:00.281525 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 8 00:06:00.281549 kernel: ima: Allocated hash algorithm: sha1 Nov 8 00:06:00.281568 kernel: ima: No architecture policies found Nov 8 00:06:00.281586 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 8 00:06:00.281605 kernel: clk: Disabling unused clocks Nov 8 00:06:00.281624 kernel: Freeing unused kernel memory: 39424K Nov 8 00:06:00.281642 kernel: Run /init as init process Nov 8 00:06:00.281661 kernel: with arguments: Nov 8 00:06:00.281680 kernel: /init Nov 8 00:06:00.281698 kernel: with environment: Nov 8 00:06:00.281716 kernel: HOME=/ Nov 8 00:06:00.281739 kernel: TERM=linux Nov 8 00:06:00.281762 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:06:00.281786 systemd[1]: Detected virtualization amazon. Nov 8 00:06:00.281807 systemd[1]: Detected architecture arm64. Nov 8 00:06:00.281827 systemd[1]: Running in initrd. Nov 8 00:06:00.281846 systemd[1]: No hostname configured, using default hostname. Nov 8 00:06:00.281866 systemd[1]: Hostname set to . Nov 8 00:06:00.281892 systemd[1]: Initializing machine ID from VM UUID. Nov 8 00:06:00.281912 systemd[1]: Queued start job for default target initrd.target. Nov 8 00:06:00.281955 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:06:00.281977 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:06:00.281999 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 8 00:06:00.282021 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 8 00:06:00.282041 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 8 00:06:00.282062 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 8 00:06:00.282092 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:06:00.282114 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 8 00:06:00.282135 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:06:00.282183 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:06:00.282234 systemd[1]: Reached target paths.target - Path Units. Nov 8 00:06:00.282259 systemd[1]: Reached target slices.target - Slice Units. Nov 8 00:06:00.282280 systemd[1]: Reached target swap.target - Swaps. Nov 8 00:06:00.282316 systemd[1]: Reached target timers.target - Timer Units. Nov 8 00:06:00.282339 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 8 00:06:00.282361 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 8 00:06:00.282383 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 8 00:06:00.282404 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 8 00:06:00.282425 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:06:00.282446 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 8 00:06:00.282466 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:06:00.282492 systemd[1]: Reached target sockets.target - Socket Units. Nov 8 00:06:00.282514 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 8 00:06:00.282535 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 8 00:06:00.282556 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 8 00:06:00.282577 systemd[1]: Starting systemd-fsck-usr.service... Nov 8 00:06:00.282598 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 8 00:06:00.282619 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 8 00:06:00.282640 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:06:00.282662 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 8 00:06:00.282689 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:06:00.282711 systemd[1]: Finished systemd-fsck-usr.service. Nov 8 00:06:00.282786 systemd-journald[252]: Collecting audit messages is disabled. Nov 8 00:06:00.282839 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 8 00:06:00.282861 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:06:00.282882 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:06:00.282904 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:06:00.282924 kernel: Bridge firewalling registered Nov 8 00:06:00.282949 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 8 00:06:00.282970 systemd-journald[252]: Journal started Nov 8 00:06:00.283008 systemd-journald[252]: Runtime Journal (/run/log/journal/ec21b1a28caf8c4356a1c5da9e077ee7) is 8.0M, max 75.3M, 67.3M free. Nov 8 00:06:00.220586 systemd-modules-load[253]: Inserted module 'overlay' Nov 8 00:06:00.290064 systemd[1]: Started systemd-journald.service - Journal Service. Nov 8 00:06:00.275258 systemd-modules-load[253]: Inserted module 'br_netfilter' Nov 8 00:06:00.297817 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 8 00:06:00.320479 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 8 00:06:00.333814 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 8 00:06:00.341362 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 8 00:06:00.366372 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:06:00.379091 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:06:00.382680 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:06:00.396568 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 8 00:06:00.411220 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:06:00.421319 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 8 00:06:00.451331 dracut-cmdline[287]: dracut-dracut-053 Nov 8 00:06:00.458208 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:06:00.513971 systemd-resolved[289]: Positive Trust Anchors: Nov 8 00:06:00.515893 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 00:06:00.516184 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:06:00.630196 kernel: SCSI subsystem initialized Nov 8 00:06:00.638255 kernel: Loading iSCSI transport class v2.0-870. Nov 8 00:06:00.650270 kernel: iscsi: registered transport (tcp) Nov 8 00:06:00.673273 kernel: iscsi: registered transport (qla4xxx) Nov 8 00:06:00.673347 kernel: QLogic iSCSI HBA Driver Nov 8 00:06:00.747191 kernel: random: crng init done Nov 8 00:06:00.745526 systemd-resolved[289]: Defaulting to hostname 'linux'. Nov 8 00:06:00.749970 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 8 00:06:00.755124 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:06:00.780907 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 8 00:06:00.791432 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 8 00:06:00.822055 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 8 00:06:00.822131 kernel: device-mapper: uevent: version 1.0.3 Nov 8 00:06:00.822181 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 8 00:06:00.890219 kernel: raid6: neonx8 gen() 6706 MB/s Nov 8 00:06:00.908192 kernel: raid6: neonx4 gen() 6550 MB/s Nov 8 00:06:00.925196 kernel: raid6: neonx2 gen() 5443 MB/s Nov 8 00:06:00.942193 kernel: raid6: neonx1 gen() 3950 MB/s Nov 8 00:06:00.959194 kernel: raid6: int64x8 gen() 3808 MB/s Nov 8 00:06:00.976194 kernel: raid6: int64x4 gen() 3703 MB/s Nov 8 00:06:00.993196 kernel: raid6: int64x2 gen() 3596 MB/s Nov 8 00:06:01.011266 kernel: raid6: int64x1 gen() 2756 MB/s Nov 8 00:06:01.011301 kernel: raid6: using algorithm neonx8 gen() 6706 MB/s Nov 8 00:06:01.030244 kernel: raid6: .... xor() 4824 MB/s, rmw enabled Nov 8 00:06:01.030284 kernel: raid6: using neon recovery algorithm Nov 8 00:06:01.038198 kernel: xor: measuring software checksum speed Nov 8 00:06:01.040571 kernel: 8regs : 9800 MB/sec Nov 8 00:06:01.040620 kernel: 32regs : 11454 MB/sec Nov 8 00:06:01.041868 kernel: arm64_neon : 9575 MB/sec Nov 8 00:06:01.041901 kernel: xor: using function: 32regs (11454 MB/sec) Nov 8 00:06:01.127204 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 8 00:06:01.146937 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 8 00:06:01.158544 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:06:01.204324 systemd-udevd[471]: Using default interface naming scheme 'v255'. Nov 8 00:06:01.213719 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:06:01.227456 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 8 00:06:01.264432 dracut-pre-trigger[480]: rd.md=0: removing MD RAID activation Nov 8 00:06:01.323231 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 8 00:06:01.334490 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 8 00:06:01.462419 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:06:01.478440 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 8 00:06:01.525976 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 8 00:06:01.535576 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 8 00:06:01.540300 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:06:01.552957 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 8 00:06:01.567543 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 8 00:06:01.607567 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 8 00:06:01.669419 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 8 00:06:01.669481 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 8 00:06:01.683408 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 8 00:06:01.683769 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 8 00:06:01.687824 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 00:06:01.690418 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:06:01.697813 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:06:01.700481 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 00:06:01.720763 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 8 00:06:01.720801 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 8 00:06:01.721091 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:d0:08:9a:2f:69 Nov 8 00:06:01.701102 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:06:01.711621 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:06:01.732201 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 8 00:06:01.737664 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:06:01.750388 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 8 00:06:01.750470 kernel: GPT:9289727 != 33554431 Nov 8 00:06:01.752907 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 8 00:06:01.752977 kernel: GPT:9289727 != 33554431 Nov 8 00:06:01.754203 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 8 00:06:01.755264 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:06:01.758963 (udev-worker)[539]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:01.771249 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:06:01.784624 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 8 00:06:01.845249 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:06:01.862307 kernel: BTRFS: device fsid 55a292e1-3824-4229-a9ae-952140d2698c devid 1 transid 37 /dev/nvme0n1p3 scanned by (udev-worker) (516) Nov 8 00:06:01.889263 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (535) Nov 8 00:06:01.988847 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 8 00:06:02.008335 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 8 00:06:02.022640 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 8 00:06:02.025415 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 8 00:06:02.042643 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 8 00:06:02.054448 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 8 00:06:02.070117 disk-uuid[662]: Primary Header is updated. Nov 8 00:06:02.070117 disk-uuid[662]: Secondary Entries is updated. Nov 8 00:06:02.070117 disk-uuid[662]: Secondary Header is updated. Nov 8 00:06:02.082265 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:06:02.091229 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:06:02.098205 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:06:03.102202 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 8 00:06:03.103386 disk-uuid[663]: The operation has completed successfully. Nov 8 00:06:03.287046 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 8 00:06:03.287266 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 8 00:06:03.353415 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 8 00:06:03.364295 sh[1006]: Success Nov 8 00:06:03.394197 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 8 00:06:03.519549 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 8 00:06:03.528211 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 8 00:06:03.540377 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 8 00:06:03.587201 kernel: BTRFS info (device dm-0): first mount of filesystem 55a292e1-3824-4229-a9ae-952140d2698c Nov 8 00:06:03.587266 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:06:03.587294 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 8 00:06:03.588118 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 8 00:06:03.589522 kernel: BTRFS info (device dm-0): using free space tree Nov 8 00:06:03.618197 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 8 00:06:03.632318 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 8 00:06:03.636777 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 8 00:06:03.651415 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 8 00:06:03.663409 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 8 00:06:03.683354 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:06:03.683416 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:06:03.683444 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:06:03.703230 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:06:03.721036 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 8 00:06:03.726182 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:06:03.737105 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 8 00:06:03.748480 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 8 00:06:03.868557 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 8 00:06:03.885463 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 8 00:06:03.952492 systemd-networkd[1201]: lo: Link UP Nov 8 00:06:03.953439 systemd-networkd[1201]: lo: Gained carrier Nov 8 00:06:03.956723 systemd-networkd[1201]: Enumeration completed Nov 8 00:06:03.957694 systemd-networkd[1201]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:06:03.957701 systemd-networkd[1201]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 8 00:06:03.958314 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 8 00:06:03.960924 systemd[1]: Reached target network.target - Network. Nov 8 00:06:03.972526 systemd-networkd[1201]: eth0: Link UP Nov 8 00:06:03.972534 systemd-networkd[1201]: eth0: Gained carrier Nov 8 00:06:03.972552 systemd-networkd[1201]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:06:03.983333 ignition[1113]: Ignition 2.19.0 Nov 8 00:06:03.983348 ignition[1113]: Stage: fetch-offline Nov 8 00:06:03.990187 ignition[1113]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:03.990214 ignition[1113]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:03.999431 ignition[1113]: Ignition finished successfully Nov 8 00:06:04.006075 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 8 00:06:04.006356 systemd-networkd[1201]: eth0: DHCPv4 address 172.31.24.87/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 8 00:06:04.021444 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 8 00:06:04.057547 ignition[1210]: Ignition 2.19.0 Nov 8 00:06:04.058086 ignition[1210]: Stage: fetch Nov 8 00:06:04.058759 ignition[1210]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:04.058785 ignition[1210]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:04.058932 ignition[1210]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:04.080798 ignition[1210]: PUT result: OK Nov 8 00:06:04.083909 ignition[1210]: parsed url from cmdline: "" Nov 8 00:06:04.083925 ignition[1210]: no config URL provided Nov 8 00:06:04.083942 ignition[1210]: reading system config file "/usr/lib/ignition/user.ign" Nov 8 00:06:04.083967 ignition[1210]: no config at "/usr/lib/ignition/user.ign" Nov 8 00:06:04.084003 ignition[1210]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:04.085964 ignition[1210]: PUT result: OK Nov 8 00:06:04.088295 ignition[1210]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 8 00:06:04.092816 ignition[1210]: GET result: OK Nov 8 00:06:04.093368 ignition[1210]: parsing config with SHA512: d83771faee2518188aa4e610c9117357a92de5f79f59f5bdb3a4ea2c0292b4fc57a85c02ccc9cbbe8a9b0208b9e7fee870d250d47e6f5a2f9e694909a815e07f Nov 8 00:06:04.100118 unknown[1210]: fetched base config from "system" Nov 8 00:06:04.100566 ignition[1210]: fetch: fetch complete Nov 8 00:06:04.100139 unknown[1210]: fetched base config from "system" Nov 8 00:06:04.100578 ignition[1210]: fetch: fetch passed Nov 8 00:06:04.100153 unknown[1210]: fetched user config from "aws" Nov 8 00:06:04.100649 ignition[1210]: Ignition finished successfully Nov 8 00:06:04.113627 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 8 00:06:04.123466 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 8 00:06:04.152076 ignition[1218]: Ignition 2.19.0 Nov 8 00:06:04.152637 ignition[1218]: Stage: kargs Nov 8 00:06:04.153327 ignition[1218]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:04.153359 ignition[1218]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:04.153515 ignition[1218]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:04.162444 ignition[1218]: PUT result: OK Nov 8 00:06:04.167142 ignition[1218]: kargs: kargs passed Nov 8 00:06:04.167264 ignition[1218]: Ignition finished successfully Nov 8 00:06:04.174536 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 8 00:06:04.184616 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 8 00:06:04.213981 ignition[1224]: Ignition 2.19.0 Nov 8 00:06:04.214010 ignition[1224]: Stage: disks Nov 8 00:06:04.215891 ignition[1224]: no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:04.215944 ignition[1224]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:04.217202 ignition[1224]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:04.221886 ignition[1224]: PUT result: OK Nov 8 00:06:04.232346 ignition[1224]: disks: disks passed Nov 8 00:06:04.232664 ignition[1224]: Ignition finished successfully Nov 8 00:06:04.237927 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 8 00:06:04.241102 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 8 00:06:04.243840 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 8 00:06:04.248617 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 8 00:06:04.250907 systemd[1]: Reached target sysinit.target - System Initialization. Nov 8 00:06:04.253220 systemd[1]: Reached target basic.target - Basic System. Nov 8 00:06:04.269589 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 8 00:06:04.319896 systemd-fsck[1232]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 8 00:06:04.324606 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 8 00:06:04.338849 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 8 00:06:04.420223 kernel: EXT4-fs (nvme0n1p9): mounted filesystem ba97f76e-2e9b-450a-8320-3c4b94a19632 r/w with ordered data mode. Quota mode: none. Nov 8 00:06:04.421689 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 8 00:06:04.425936 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 8 00:06:04.449359 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 8 00:06:04.459808 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 8 00:06:04.471622 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 8 00:06:04.471704 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:06:04.471757 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 8 00:06:04.490904 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 8 00:06:04.495697 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/nvme0n1p6 scanned by mount (1251) Nov 8 00:06:04.500357 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:06:04.500427 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:06:04.501784 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:06:04.503640 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 8 00:06:04.525510 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:06:04.528756 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 8 00:06:04.633587 initrd-setup-root[1275]: cut: /sysroot/etc/passwd: No such file or directory Nov 8 00:06:04.644031 initrd-setup-root[1282]: cut: /sysroot/etc/group: No such file or directory Nov 8 00:06:04.653338 initrd-setup-root[1289]: cut: /sysroot/etc/shadow: No such file or directory Nov 8 00:06:04.662411 initrd-setup-root[1296]: cut: /sysroot/etc/gshadow: No such file or directory Nov 8 00:06:04.806274 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 8 00:06:04.815429 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 8 00:06:04.827474 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 8 00:06:04.847804 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 8 00:06:04.850710 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:06:04.887957 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 8 00:06:04.897810 ignition[1364]: INFO : Ignition 2.19.0 Nov 8 00:06:04.899910 ignition[1364]: INFO : Stage: mount Nov 8 00:06:04.899910 ignition[1364]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:04.899910 ignition[1364]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:04.899910 ignition[1364]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:04.910075 ignition[1364]: INFO : PUT result: OK Nov 8 00:06:04.915016 ignition[1364]: INFO : mount: mount passed Nov 8 00:06:04.916767 ignition[1364]: INFO : Ignition finished successfully Nov 8 00:06:04.924458 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 8 00:06:04.938101 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 8 00:06:04.955528 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 8 00:06:04.992125 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1375) Nov 8 00:06:04.992201 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7afafbf9-edbd-49b5-ac90-6fc331f667e9 Nov 8 00:06:04.992231 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 8 00:06:04.995186 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 8 00:06:05.001199 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 8 00:06:05.003475 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 8 00:06:05.039279 ignition[1392]: INFO : Ignition 2.19.0 Nov 8 00:06:05.041303 ignition[1392]: INFO : Stage: files Nov 8 00:06:05.041303 ignition[1392]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:05.041303 ignition[1392]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:05.041303 ignition[1392]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:05.051537 ignition[1392]: INFO : PUT result: OK Nov 8 00:06:05.060795 ignition[1392]: DEBUG : files: compiled without relabeling support, skipping Nov 8 00:06:05.064200 ignition[1392]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 8 00:06:05.064200 ignition[1392]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 8 00:06:05.071002 ignition[1392]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 8 00:06:05.074143 ignition[1392]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 8 00:06:05.074143 ignition[1392]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 8 00:06:05.072846 unknown[1392]: wrote ssh authorized keys file for user: core Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 8 00:06:05.083051 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Nov 8 00:06:05.394353 systemd-networkd[1201]: eth0: Gained IPv6LL Nov 8 00:06:05.565826 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 8 00:06:05.996905 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 8 00:06:06.002087 ignition[1392]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 8 00:06:06.002087 ignition[1392]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 8 00:06:06.002087 ignition[1392]: INFO : files: files passed Nov 8 00:06:06.002087 ignition[1392]: INFO : Ignition finished successfully Nov 8 00:06:06.014800 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 8 00:06:06.028602 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 8 00:06:06.036786 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 8 00:06:06.040628 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 8 00:06:06.041548 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 8 00:06:06.075200 initrd-setup-root-after-ignition[1421]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:06:06.075200 initrd-setup-root-after-ignition[1421]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:06:06.082975 initrd-setup-root-after-ignition[1425]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 8 00:06:06.089484 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 8 00:06:06.097482 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 8 00:06:06.109525 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 8 00:06:06.173078 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 8 00:06:06.173546 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 8 00:06:06.184185 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 8 00:06:06.187239 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 8 00:06:06.192564 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 8 00:06:06.204476 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 8 00:06:06.233985 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 8 00:06:06.247492 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 8 00:06:06.276427 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:06:06.279660 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:06:06.287214 systemd[1]: Stopped target timers.target - Timer Units. Nov 8 00:06:06.289504 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 8 00:06:06.289836 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 8 00:06:06.299471 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 8 00:06:06.301855 systemd[1]: Stopped target basic.target - Basic System. Nov 8 00:06:06.304052 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 8 00:06:06.306987 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 8 00:06:06.318501 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 8 00:06:06.321369 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 8 00:06:06.323812 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 8 00:06:06.329094 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 8 00:06:06.331599 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 8 00:06:06.334507 systemd[1]: Stopped target swap.target - Swaps. Nov 8 00:06:06.338231 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 8 00:06:06.338645 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 8 00:06:06.345976 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:06:06.350729 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:06:06.353567 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 8 00:06:06.355672 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:06:06.372662 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 8 00:06:06.372893 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 8 00:06:06.375668 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 8 00:06:06.375893 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 8 00:06:06.378929 systemd[1]: ignition-files.service: Deactivated successfully. Nov 8 00:06:06.379185 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 8 00:06:06.399453 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 8 00:06:06.404505 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 8 00:06:06.406509 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 8 00:06:06.412837 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:06:06.424615 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 8 00:06:06.424877 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 8 00:06:06.438508 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 8 00:06:06.438740 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 8 00:06:06.467804 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 8 00:06:06.472455 ignition[1445]: INFO : Ignition 2.19.0 Nov 8 00:06:06.475379 ignition[1445]: INFO : Stage: umount Nov 8 00:06:06.482393 ignition[1445]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 8 00:06:06.482393 ignition[1445]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 8 00:06:06.482393 ignition[1445]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 8 00:06:06.481256 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 8 00:06:06.497252 ignition[1445]: INFO : PUT result: OK Nov 8 00:06:06.481502 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 8 00:06:06.502829 ignition[1445]: INFO : umount: umount passed Nov 8 00:06:06.504843 ignition[1445]: INFO : Ignition finished successfully Nov 8 00:06:06.507390 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 8 00:06:06.509623 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 8 00:06:06.514823 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 8 00:06:06.515009 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 8 00:06:06.519402 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 8 00:06:06.519492 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 8 00:06:06.522091 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 8 00:06:06.522201 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 8 00:06:06.528714 systemd[1]: Stopped target network.target - Network. Nov 8 00:06:06.528777 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 8 00:06:06.528868 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 8 00:06:06.528975 systemd[1]: Stopped target paths.target - Path Units. Nov 8 00:06:06.529028 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 8 00:06:06.537362 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:06:06.540223 systemd[1]: Stopped target slices.target - Slice Units. Nov 8 00:06:06.542654 systemd[1]: Stopped target sockets.target - Socket Units. Nov 8 00:06:06.542822 systemd[1]: iscsid.socket: Deactivated successfully. Nov 8 00:06:06.542899 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 8 00:06:06.544536 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 8 00:06:06.544609 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 8 00:06:06.545343 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 8 00:06:06.545427 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 8 00:06:06.546196 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 8 00:06:06.546273 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 8 00:06:06.547002 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 8 00:06:06.547079 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 8 00:06:06.548093 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 8 00:06:06.548796 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 8 00:06:06.578367 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 8 00:06:06.582397 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 8 00:06:06.583357 systemd-networkd[1201]: eth0: DHCPv6 lease lost Nov 8 00:06:06.602091 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 8 00:06:06.602886 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 8 00:06:06.608213 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 8 00:06:06.608321 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:06:06.622984 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 8 00:06:06.631582 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 8 00:06:06.631700 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 8 00:06:06.634805 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 8 00:06:06.634891 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:06:06.637596 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 8 00:06:06.637675 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 8 00:06:06.640343 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 8 00:06:06.640423 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:06:06.643766 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:06:06.705106 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 8 00:06:06.707325 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:06:06.713947 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 8 00:06:06.714088 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 8 00:06:06.717094 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 8 00:06:06.719306 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:06:06.723092 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 8 00:06:06.723344 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 8 00:06:06.734367 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 8 00:06:06.734480 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 8 00:06:06.744389 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 8 00:06:06.744509 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 8 00:06:06.764460 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 8 00:06:06.767148 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 8 00:06:06.767281 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:06:06.770030 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 8 00:06:06.770122 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 8 00:06:06.773320 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 8 00:06:06.773413 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:06:06.776222 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 8 00:06:06.776323 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:06:06.782098 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 8 00:06:06.782320 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 8 00:06:06.791653 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 8 00:06:06.791847 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 8 00:06:06.795402 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 8 00:06:06.804298 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 8 00:06:06.860688 systemd[1]: Switching root. Nov 8 00:06:06.902244 systemd-journald[252]: Journal stopped Nov 8 00:06:08.892887 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Nov 8 00:06:08.893006 kernel: SELinux: policy capability network_peer_controls=1 Nov 8 00:06:08.893050 kernel: SELinux: policy capability open_perms=1 Nov 8 00:06:08.893082 kernel: SELinux: policy capability extended_socket_class=1 Nov 8 00:06:08.893123 kernel: SELinux: policy capability always_check_network=0 Nov 8 00:06:08.905307 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 8 00:06:08.905393 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 8 00:06:08.905428 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 8 00:06:08.905460 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 8 00:06:08.905501 kernel: audit: type=1403 audit(1762560367.152:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 8 00:06:08.905553 systemd[1]: Successfully loaded SELinux policy in 50.687ms. Nov 8 00:06:08.905599 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.138ms. Nov 8 00:06:08.905635 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:06:08.905667 systemd[1]: Detected virtualization amazon. Nov 8 00:06:08.905703 systemd[1]: Detected architecture arm64. Nov 8 00:06:08.905739 systemd[1]: Detected first boot. Nov 8 00:06:08.905772 systemd[1]: Initializing machine ID from VM UUID. Nov 8 00:06:08.905809 zram_generator::config[1487]: No configuration found. Nov 8 00:06:08.905844 systemd[1]: Populated /etc with preset unit settings. Nov 8 00:06:08.905902 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 8 00:06:08.905938 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 8 00:06:08.905969 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 8 00:06:08.906001 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 8 00:06:08.906043 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 8 00:06:08.906073 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 8 00:06:08.906105 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 8 00:06:08.906138 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 8 00:06:08.911329 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 8 00:06:08.911396 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 8 00:06:08.916203 systemd[1]: Created slice user.slice - User and Session Slice. Nov 8 00:06:08.916248 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 8 00:06:08.916283 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 8 00:06:08.916314 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 8 00:06:08.916350 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 8 00:06:08.916381 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 8 00:06:08.916414 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 8 00:06:08.916447 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 8 00:06:08.916488 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 8 00:06:08.916520 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 8 00:06:08.916551 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 8 00:06:08.916581 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 8 00:06:08.916611 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 8 00:06:08.916640 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 8 00:06:08.916672 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 8 00:06:08.916707 systemd[1]: Reached target slices.target - Slice Units. Nov 8 00:06:08.916744 systemd[1]: Reached target swap.target - Swaps. Nov 8 00:06:08.916776 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 8 00:06:08.916808 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 8 00:06:08.916837 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 8 00:06:08.916867 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 8 00:06:08.916897 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 8 00:06:08.916926 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 8 00:06:08.916958 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 8 00:06:08.916991 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 8 00:06:08.917025 systemd[1]: Mounting media.mount - External Media Directory... Nov 8 00:06:08.917056 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 8 00:06:08.917089 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 8 00:06:08.917119 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 8 00:06:08.917149 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:06:08.926284 systemd[1]: Reached target machines.target - Containers. Nov 8 00:06:08.926330 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 8 00:06:08.926366 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:06:08.926407 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 8 00:06:08.926440 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 8 00:06:08.926470 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:06:08.926503 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 8 00:06:08.926536 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:06:08.926566 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 8 00:06:08.926601 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 8 00:06:08.926633 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 8 00:06:08.926670 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 8 00:06:08.926700 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 8 00:06:08.926730 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 8 00:06:08.926760 systemd[1]: Stopped systemd-fsck-usr.service. Nov 8 00:06:08.926790 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 8 00:06:08.926820 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 8 00:06:08.926855 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 8 00:06:08.926890 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 8 00:06:08.926920 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 8 00:06:08.926955 systemd[1]: verity-setup.service: Deactivated successfully. Nov 8 00:06:08.926986 systemd[1]: Stopped verity-setup.service. Nov 8 00:06:08.927015 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 8 00:06:08.927048 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 8 00:06:08.927077 systemd[1]: Mounted media.mount - External Media Directory. Nov 8 00:06:08.927107 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 8 00:06:08.927142 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 8 00:06:08.927204 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 8 00:06:08.927237 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 8 00:06:08.927268 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 8 00:06:08.927297 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 8 00:06:08.927330 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:06:08.927361 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:06:08.927391 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:06:08.927427 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:06:08.927457 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 8 00:06:08.927487 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 8 00:06:08.927519 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 8 00:06:08.927559 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 8 00:06:08.927595 kernel: loop: module loaded Nov 8 00:06:08.927626 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 8 00:06:08.927658 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 8 00:06:08.927688 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 8 00:06:08.927718 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 8 00:06:08.927748 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 8 00:06:08.927777 kernel: fuse: init (API version 7.39) Nov 8 00:06:08.927806 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 8 00:06:08.927898 systemd-journald[1576]: Collecting audit messages is disabled. Nov 8 00:06:08.927959 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 8 00:06:08.927992 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 8 00:06:08.928023 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 8 00:06:08.928055 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 8 00:06:08.928086 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:06:08.928117 systemd-journald[1576]: Journal started Nov 8 00:06:08.935807 systemd-journald[1576]: Runtime Journal (/run/log/journal/ec21b1a28caf8c4356a1c5da9e077ee7) is 8.0M, max 75.3M, 67.3M free. Nov 8 00:06:08.935914 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 8 00:06:08.198059 systemd[1]: Queued start job for default target multi-user.target. Nov 8 00:06:08.943360 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 8 00:06:08.224472 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 8 00:06:08.225395 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 8 00:06:08.972345 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 8 00:06:08.981238 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 8 00:06:08.981312 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 8 00:06:09.009282 kernel: ACPI: bus type drm_connector registered Nov 8 00:06:09.009378 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 8 00:06:09.009422 systemd[1]: Started systemd-journald.service - Journal Service. Nov 8 00:06:09.011917 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 8 00:06:09.015307 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 8 00:06:09.015905 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 8 00:06:09.019462 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 00:06:09.019767 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 8 00:06:09.023387 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 8 00:06:09.072334 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 8 00:06:09.089453 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 8 00:06:09.097266 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 8 00:06:09.100226 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 8 00:06:09.109476 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 8 00:06:09.113634 systemd-tmpfiles[1589]: ACLs are not supported, ignoring. Nov 8 00:06:09.116061 systemd-tmpfiles[1589]: ACLs are not supported, ignoring. Nov 8 00:06:09.118862 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 8 00:06:09.129505 kernel: loop0: detected capacity change from 0 to 114432 Nov 8 00:06:09.156906 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 8 00:06:09.176934 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 8 00:06:09.178152 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 8 00:06:09.197292 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 8 00:06:09.214085 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 8 00:06:09.215235 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 8 00:06:09.224403 systemd-journald[1576]: Time spent on flushing to /var/log/journal/ec21b1a28caf8c4356a1c5da9e077ee7 is 68.205ms for 902 entries. Nov 8 00:06:09.224403 systemd-journald[1576]: System Journal (/var/log/journal/ec21b1a28caf8c4356a1c5da9e077ee7) is 8.0M, max 195.6M, 187.6M free. Nov 8 00:06:09.304735 systemd-journald[1576]: Received client request to flush runtime journal. Nov 8 00:06:09.304804 kernel: loop1: detected capacity change from 0 to 114328 Nov 8 00:06:09.290242 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 8 00:06:09.307432 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 8 00:06:09.315288 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 8 00:06:09.322007 kernel: loop2: detected capacity change from 0 to 52536 Nov 8 00:06:09.324148 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 8 00:06:09.343398 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 8 00:06:09.386571 udevadm[1636]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 8 00:06:09.433329 systemd-tmpfiles[1641]: ACLs are not supported, ignoring. Nov 8 00:06:09.433370 systemd-tmpfiles[1641]: ACLs are not supported, ignoring. Nov 8 00:06:09.441199 kernel: loop3: detected capacity change from 0 to 211168 Nov 8 00:06:09.449057 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 8 00:06:09.553203 kernel: loop4: detected capacity change from 0 to 114432 Nov 8 00:06:09.583199 kernel: loop5: detected capacity change from 0 to 114328 Nov 8 00:06:09.613201 kernel: loop6: detected capacity change from 0 to 52536 Nov 8 00:06:09.633785 kernel: loop7: detected capacity change from 0 to 211168 Nov 8 00:06:09.671558 (sd-merge)[1646]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Nov 8 00:06:09.672581 (sd-merge)[1646]: Merged extensions into '/usr'. Nov 8 00:06:09.690409 systemd[1]: Reloading requested from client PID 1599 ('systemd-sysext') (unit systemd-sysext.service)... Nov 8 00:06:09.690445 systemd[1]: Reloading... Nov 8 00:06:09.907281 zram_generator::config[1675]: No configuration found. Nov 8 00:06:09.935921 ldconfig[1594]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 8 00:06:10.184096 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:06:10.304286 systemd[1]: Reloading finished in 613 ms. Nov 8 00:06:10.345213 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 8 00:06:10.349219 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 8 00:06:10.353287 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 8 00:06:10.371496 systemd[1]: Starting ensure-sysext.service... Nov 8 00:06:10.377490 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 8 00:06:10.385559 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 8 00:06:10.407072 systemd[1]: Reloading requested from client PID 1725 ('systemctl') (unit ensure-sysext.service)... Nov 8 00:06:10.407107 systemd[1]: Reloading... Nov 8 00:06:10.438644 systemd-tmpfiles[1726]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 8 00:06:10.439385 systemd-tmpfiles[1726]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 8 00:06:10.442045 systemd-tmpfiles[1726]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 8 00:06:10.442767 systemd-tmpfiles[1726]: ACLs are not supported, ignoring. Nov 8 00:06:10.442905 systemd-tmpfiles[1726]: ACLs are not supported, ignoring. Nov 8 00:06:10.452096 systemd-tmpfiles[1726]: Detected autofs mount point /boot during canonicalization of boot. Nov 8 00:06:10.452127 systemd-tmpfiles[1726]: Skipping /boot Nov 8 00:06:10.481681 systemd-tmpfiles[1726]: Detected autofs mount point /boot during canonicalization of boot. Nov 8 00:06:10.481715 systemd-tmpfiles[1726]: Skipping /boot Nov 8 00:06:10.521695 systemd-udevd[1727]: Using default interface naming scheme 'v255'. Nov 8 00:06:10.609679 zram_generator::config[1757]: No configuration found. Nov 8 00:06:10.756480 (udev-worker)[1763]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:10.995993 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:06:11.008205 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (1769) Nov 8 00:06:11.192854 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 8 00:06:11.193332 systemd[1]: Reloading finished in 785 ms. Nov 8 00:06:11.222483 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 8 00:06:11.237300 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 8 00:06:11.297305 systemd[1]: Finished ensure-sysext.service. Nov 8 00:06:11.310264 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 8 00:06:11.347024 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 8 00:06:11.356490 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Nov 8 00:06:11.374040 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 8 00:06:11.380110 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 8 00:06:11.384297 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 8 00:06:11.397467 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 8 00:06:11.401926 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 8 00:06:11.407632 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 8 00:06:11.415875 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 8 00:06:11.419879 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 8 00:06:11.424664 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 8 00:06:11.438778 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 8 00:06:11.471373 lvm[1925]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 00:06:11.468792 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 8 00:06:11.480729 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 8 00:06:11.486124 systemd[1]: Reached target time-set.target - System Time Set. Nov 8 00:06:11.493521 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 8 00:06:11.503541 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 8 00:06:11.519512 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 8 00:06:11.526800 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 8 00:06:11.528277 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 8 00:06:11.538006 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 8 00:06:11.539239 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 8 00:06:11.542385 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 8 00:06:11.542695 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 8 00:06:11.548550 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 8 00:06:11.551879 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 8 00:06:11.554113 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 8 00:06:11.559601 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 8 00:06:11.571493 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 8 00:06:11.594584 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 8 00:06:11.663386 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 8 00:06:11.670785 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 8 00:06:11.685221 augenrules[1960]: No rules Nov 8 00:06:11.687072 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Nov 8 00:06:11.700588 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 8 00:06:11.703983 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 8 00:06:11.717742 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 8 00:06:11.730249 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 8 00:06:11.732960 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 8 00:06:11.753361 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 8 00:06:11.757008 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:06:11.761535 lvm[1967]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 8 00:06:11.822061 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 8 00:06:11.893333 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 8 00:06:11.901117 systemd-networkd[1937]: lo: Link UP Nov 8 00:06:11.901133 systemd-networkd[1937]: lo: Gained carrier Nov 8 00:06:11.904578 systemd-networkd[1937]: Enumeration completed Nov 8 00:06:11.904807 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 8 00:06:11.905767 systemd-networkd[1937]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:06:11.905776 systemd-networkd[1937]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 8 00:06:11.909378 systemd-resolved[1938]: Positive Trust Anchors: Nov 8 00:06:11.909414 systemd-resolved[1938]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 8 00:06:11.909477 systemd-resolved[1938]: 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:06:11.914509 systemd-networkd[1937]: eth0: Link UP Nov 8 00:06:11.914949 systemd-networkd[1937]: eth0: Gained carrier Nov 8 00:06:11.914991 systemd-networkd[1937]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 8 00:06:11.917762 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 8 00:06:11.928260 systemd-networkd[1937]: eth0: DHCPv4 address 172.31.24.87/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 8 00:06:11.938662 systemd-resolved[1938]: Defaulting to hostname 'linux'. Nov 8 00:06:11.942343 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 8 00:06:11.945399 systemd[1]: Reached target network.target - Network. Nov 8 00:06:11.947562 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 8 00:06:11.950439 systemd[1]: Reached target sysinit.target - System Initialization. Nov 8 00:06:11.953090 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 8 00:06:11.955970 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:06:11.959182 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 8 00:06:11.961900 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 8 00:06:11.964835 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 8 00:06:11.967777 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 8 00:06:11.967926 systemd[1]: Reached target paths.target - Path Units. Nov 8 00:06:11.970012 systemd[1]: Reached target timers.target - Timer Units. Nov 8 00:06:11.973625 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 8 00:06:11.978587 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 8 00:06:11.988546 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 8 00:06:11.991985 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 8 00:06:11.994647 systemd[1]: Reached target sockets.target - Socket Units. Nov 8 00:06:11.996863 systemd[1]: Reached target basic.target - Basic System. Nov 8 00:06:11.999189 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 8 00:06:11.999247 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 8 00:06:12.011266 systemd[1]: Starting containerd.service - containerd container runtime... Nov 8 00:06:12.016344 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 8 00:06:12.021486 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 8 00:06:12.037105 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 8 00:06:12.044474 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 8 00:06:12.046929 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:06:12.050530 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 8 00:06:12.059375 systemd[1]: Started ntpd.service - Network Time Service. Nov 8 00:06:12.075723 jq[1988]: false Nov 8 00:06:12.067474 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 8 00:06:12.076592 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 8 00:06:12.089525 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 8 00:06:12.101588 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 8 00:06:12.113913 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 8 00:06:12.114805 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 8 00:06:12.121566 systemd[1]: Starting update-engine.service - Update Engine... Nov 8 00:06:12.127969 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 8 00:06:12.143278 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 8 00:06:12.144714 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 8 00:06:12.242616 ntpd[1991]: ntpd 4.2.8p17@1.4004-o Fri Nov 7 22:04:46 UTC 2025 (1): Starting Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: ntpd 4.2.8p17@1.4004-o Fri Nov 7 22:04:46 UTC 2025 (1): Starting Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: ---------------------------------------------------- Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: ntp-4 is maintained by Network Time Foundation, Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: corporation. Support and training for ntp-4 are Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: available at https://www.nwtime.org/support Nov 8 00:06:12.244651 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: ---------------------------------------------------- Nov 8 00:06:12.242675 ntpd[1991]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 8 00:06:12.242696 ntpd[1991]: ---------------------------------------------------- Nov 8 00:06:12.242715 ntpd[1991]: ntp-4 is maintained by Network Time Foundation, Nov 8 00:06:12.242739 ntpd[1991]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 8 00:06:12.242758 ntpd[1991]: corporation. Support and training for ntp-4 are Nov 8 00:06:12.242776 ntpd[1991]: available at https://www.nwtime.org/support Nov 8 00:06:12.242794 ntpd[1991]: ---------------------------------------------------- Nov 8 00:06:12.252938 ntpd[1991]: proto: precision = 0.096 usec (-23) Nov 8 00:06:12.259020 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: proto: precision = 0.096 usec (-23) Nov 8 00:06:12.259020 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: basedate set to 2025-10-26 Nov 8 00:06:12.259020 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: gps base set to 2025-10-26 (week 2390) Nov 8 00:06:12.254604 ntpd[1991]: basedate set to 2025-10-26 Nov 8 00:06:12.254642 ntpd[1991]: gps base set to 2025-10-26 (week 2390) Nov 8 00:06:12.260878 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 8 00:06:12.263255 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 8 00:06:12.273646 dbus-daemon[1987]: [system] SELinux support is enabled Nov 8 00:06:12.273992 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 8 00:06:12.278577 ntpd[1991]: Listen and drop on 0 v6wildcard [::]:123 Nov 8 00:06:12.280314 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listen and drop on 0 v6wildcard [::]:123 Nov 8 00:06:12.280314 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 8 00:06:12.278673 ntpd[1991]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 8 00:06:12.286206 jq[1999]: true Nov 8 00:06:12.287218 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:06:12.287328 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 8 00:06:12.291248 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:06:12.291293 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 8 00:06:12.296578 ntpd[1991]: Listen normally on 2 lo 127.0.0.1:123 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listen normally on 2 lo 127.0.0.1:123 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listen normally on 3 eth0 172.31.24.87:123 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listen normally on 4 lo [::1]:123 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: bind(21) AF_INET6 fe80::4d0:8ff:fe9a:2f69%2#123 flags 0x11 failed: Cannot assign requested address Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: unable to create socket on eth0 (5) for fe80::4d0:8ff:fe9a:2f69%2#123 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: failed to init interface for address fe80::4d0:8ff:fe9a:2f69%2 Nov 8 00:06:12.297984 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: Listening on routing socket on fd #21 for interface updates Nov 8 00:06:12.296674 ntpd[1991]: Listen normally on 3 eth0 172.31.24.87:123 Nov 8 00:06:12.296744 ntpd[1991]: Listen normally on 4 lo [::1]:123 Nov 8 00:06:12.296832 ntpd[1991]: bind(21) AF_INET6 fe80::4d0:8ff:fe9a:2f69%2#123 flags 0x11 failed: Cannot assign requested address Nov 8 00:06:12.296873 ntpd[1991]: unable to create socket on eth0 (5) for fe80::4d0:8ff:fe9a:2f69%2#123 Nov 8 00:06:12.296901 ntpd[1991]: failed to init interface for address fe80::4d0:8ff:fe9a:2f69%2 Nov 8 00:06:12.296965 ntpd[1991]: Listening on routing socket on fd #21 for interface updates Nov 8 00:06:12.312185 extend-filesystems[1989]: Found loop4 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found loop5 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found loop6 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found loop7 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p1 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p2 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p3 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found usr Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p4 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p6 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p7 Nov 8 00:06:12.312185 extend-filesystems[1989]: Found nvme0n1p9 Nov 8 00:06:12.312185 extend-filesystems[1989]: Checking size of /dev/nvme0n1p9 Nov 8 00:06:12.311257 dbus-daemon[1987]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1937 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 8 00:06:12.410584 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:06:12.410584 ntpd[1991]: 8 Nov 00:06:12 ntpd[1991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:06:12.321959 systemd[1]: motdgen.service: Deactivated successfully. Nov 8 00:06:12.345194 dbus-daemon[1987]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 8 00:06:12.324280 (ntainerd)[2009]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 8 00:06:12.359329 ntpd[1991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:06:12.324412 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 8 00:06:12.359379 ntpd[1991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 8 00:06:12.417557 jq[2025]: true Nov 8 00:06:12.375530 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 8 00:06:12.458234 extend-filesystems[1989]: Resized partition /dev/nvme0n1p9 Nov 8 00:06:12.470564 systemd-logind[1997]: Watching system buttons on /dev/input/event0 (Power Button) Nov 8 00:06:12.470621 systemd-logind[1997]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 8 00:06:12.473141 systemd-logind[1997]: New seat seat0. Nov 8 00:06:12.474656 systemd[1]: Started systemd-logind.service - User Login Management. Nov 8 00:06:12.488784 extend-filesystems[2035]: resize2fs 1.47.1 (20-May-2024) Nov 8 00:06:12.511596 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 3587067 blocks Nov 8 00:06:12.518257 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 8 00:06:12.551736 update_engine[1998]: I20251108 00:06:12.551328 1998 main.cc:92] Flatcar Update Engine starting Nov 8 00:06:12.598826 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (1766) Nov 8 00:06:12.598926 update_engine[1998]: I20251108 00:06:12.595693 1998 update_check_scheduler.cc:74] Next update check in 2m14s Nov 8 00:06:12.599206 systemd[1]: Started update-engine.service - Update Engine. Nov 8 00:06:12.619307 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 8 00:06:12.637626 coreos-metadata[1986]: Nov 08 00:06:12.637 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 8 00:06:12.645183 coreos-metadata[1986]: Nov 08 00:06:12.643 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 8 00:06:12.647208 coreos-metadata[1986]: Nov 08 00:06:12.645 INFO Fetch successful Nov 8 00:06:12.647208 coreos-metadata[1986]: Nov 08 00:06:12.645 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 8 00:06:12.650935 coreos-metadata[1986]: Nov 08 00:06:12.648 INFO Fetch successful Nov 8 00:06:12.650935 coreos-metadata[1986]: Nov 08 00:06:12.648 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 8 00:06:12.650935 coreos-metadata[1986]: Nov 08 00:06:12.649 INFO Fetch successful Nov 8 00:06:12.650935 coreos-metadata[1986]: Nov 08 00:06:12.650 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 8 00:06:12.652735 coreos-metadata[1986]: Nov 08 00:06:12.652 INFO Fetch successful Nov 8 00:06:12.652855 coreos-metadata[1986]: Nov 08 00:06:12.652 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 8 00:06:12.654299 coreos-metadata[1986]: Nov 08 00:06:12.654 INFO Fetch failed with 404: resource not found Nov 8 00:06:12.654420 coreos-metadata[1986]: Nov 08 00:06:12.654 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 8 00:06:12.659193 coreos-metadata[1986]: Nov 08 00:06:12.655 INFO Fetch successful Nov 8 00:06:12.659193 coreos-metadata[1986]: Nov 08 00:06:12.657 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 8 00:06:12.659193 coreos-metadata[1986]: Nov 08 00:06:12.658 INFO Fetch successful Nov 8 00:06:12.659193 coreos-metadata[1986]: Nov 08 00:06:12.658 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 8 00:06:12.663780 coreos-metadata[1986]: Nov 08 00:06:12.661 INFO Fetch successful Nov 8 00:06:12.663780 coreos-metadata[1986]: Nov 08 00:06:12.661 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 8 00:06:12.667739 coreos-metadata[1986]: Nov 08 00:06:12.664 INFO Fetch successful Nov 8 00:06:12.667739 coreos-metadata[1986]: Nov 08 00:06:12.664 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 8 00:06:12.667739 coreos-metadata[1986]: Nov 08 00:06:12.665 INFO Fetch successful Nov 8 00:06:12.785547 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 8 00:06:12.794836 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 3587067 Nov 8 00:06:12.792667 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 8 00:06:12.818102 bash[2081]: Updated "/home/core/.ssh/authorized_keys" Nov 8 00:06:12.828672 extend-filesystems[2035]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 8 00:06:12.828672 extend-filesystems[2035]: old_desc_blocks = 1, new_desc_blocks = 2 Nov 8 00:06:12.828672 extend-filesystems[2035]: The filesystem on /dev/nvme0n1p9 is now 3587067 (4k) blocks long. Nov 8 00:06:12.819726 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 8 00:06:12.842481 extend-filesystems[1989]: Resized filesystem in /dev/nvme0n1p9 Nov 8 00:06:12.850984 systemd[1]: Starting sshkeys.service... Nov 8 00:06:12.855024 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 8 00:06:12.855778 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 8 00:06:12.922415 dbus-daemon[1987]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 8 00:06:12.923182 dbus-daemon[1987]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=2028 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 8 00:06:12.925394 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 8 00:06:12.948551 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 8 00:06:12.960630 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 8 00:06:12.970806 systemd[1]: Starting polkit.service - Authorization Manager... Nov 8 00:06:12.996543 locksmithd[2052]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 8 00:06:13.010584 systemd-networkd[1937]: eth0: Gained IPv6LL Nov 8 00:06:13.045451 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 8 00:06:13.075153 systemd[1]: Reached target network-online.target - Network is Online. Nov 8 00:06:13.085038 polkitd[2144]: Started polkitd version 121 Nov 8 00:06:13.097821 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 8 00:06:13.107778 polkitd[2144]: Loading rules from directory /etc/polkit-1/rules.d Nov 8 00:06:13.107902 polkitd[2144]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 8 00:06:13.116700 polkitd[2144]: Finished loading, compiling and executing 2 rules Nov 8 00:06:13.117750 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:06:13.128016 dbus-daemon[1987]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 8 00:06:13.129342 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 8 00:06:13.137713 polkitd[2144]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 8 00:06:13.138994 systemd[1]: Started polkit.service - Authorization Manager. Nov 8 00:06:13.241428 systemd-hostnamed[2028]: Hostname set to (transient) Nov 8 00:06:13.241579 systemd-resolved[1938]: System hostname changed to 'ip-172-31-24-87'. Nov 8 00:06:13.316433 coreos-metadata[2136]: Nov 08 00:06:13.313 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 8 00:06:13.322573 coreos-metadata[2136]: Nov 08 00:06:13.319 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 8 00:06:13.322573 coreos-metadata[2136]: Nov 08 00:06:13.320 INFO Fetch successful Nov 8 00:06:13.322573 coreos-metadata[2136]: Nov 08 00:06:13.320 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 8 00:06:13.322573 coreos-metadata[2136]: Nov 08 00:06:13.321 INFO Fetch successful Nov 8 00:06:13.317306 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 8 00:06:13.334053 unknown[2136]: wrote ssh authorized keys file for user: core Nov 8 00:06:13.371003 containerd[2009]: time="2025-11-08T00:06:13.370656608Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Nov 8 00:06:13.393202 amazon-ssm-agent[2160]: Initializing new seelog logger Nov 8 00:06:13.398422 amazon-ssm-agent[2160]: New Seelog Logger Creation Complete Nov 8 00:06:13.398422 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.398422 amazon-ssm-agent[2160]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.398422 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 processing appconfig overrides Nov 8 00:06:13.399788 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.399788 amazon-ssm-agent[2160]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.399788 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 processing appconfig overrides Nov 8 00:06:13.400017 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.400017 amazon-ssm-agent[2160]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.400139 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 processing appconfig overrides Nov 8 00:06:13.410225 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO Proxy environment variables: Nov 8 00:06:13.412319 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.412319 amazon-ssm-agent[2160]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 8 00:06:13.412553 amazon-ssm-agent[2160]: 2025/11/08 00:06:13 processing appconfig overrides Nov 8 00:06:13.425958 update-ssh-keys[2190]: Updated "/home/core/.ssh/authorized_keys" Nov 8 00:06:13.432480 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 8 00:06:13.452280 systemd[1]: Finished sshkeys.service. Nov 8 00:06:13.494543 containerd[2009]: time="2025-11-08T00:06:13.494408985Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.507501 containerd[2009]: time="2025-11-08T00:06:13.507416565Z" 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:06:13.507501 containerd[2009]: time="2025-11-08T00:06:13.507487173Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 8 00:06:13.507662 containerd[2009]: time="2025-11-08T00:06:13.507529245Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 8 00:06:13.507913 containerd[2009]: time="2025-11-08T00:06:13.507864969Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 8 00:06:13.507973 containerd[2009]: time="2025-11-08T00:06:13.507913773Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.508081 containerd[2009]: time="2025-11-08T00:06:13.508036617Z" 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:06:13.508152 containerd[2009]: time="2025-11-08T00:06:13.508078233Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.508481 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO https_proxy: Nov 8 00:06:13.508549 containerd[2009]: time="2025-11-08T00:06:13.508410549Z" 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:06:13.508549 containerd[2009]: time="2025-11-08T00:06:13.508445181Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.508549 containerd[2009]: time="2025-11-08T00:06:13.508478409Z" 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:06:13.508549 containerd[2009]: time="2025-11-08T00:06:13.508503537Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.508734 containerd[2009]: time="2025-11-08T00:06:13.508688001Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.518182 containerd[2009]: time="2025-11-08T00:06:13.509145105Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 8 00:06:13.518182 containerd[2009]: time="2025-11-08T00:06:13.516083337Z" 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:06:13.518182 containerd[2009]: time="2025-11-08T00:06:13.516123069Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 8 00:06:13.520075 containerd[2009]: time="2025-11-08T00:06:13.520007769Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 8 00:06:13.520246 containerd[2009]: time="2025-11-08T00:06:13.520205433Z" level=info msg="metadata content store policy set" policy=shared Nov 8 00:06:13.531884 containerd[2009]: time="2025-11-08T00:06:13.531813981Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 8 00:06:13.532020 containerd[2009]: time="2025-11-08T00:06:13.531924513Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 8 00:06:13.532020 containerd[2009]: time="2025-11-08T00:06:13.531962925Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 8 00:06:13.532020 containerd[2009]: time="2025-11-08T00:06:13.531997929Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 8 00:06:13.532142 containerd[2009]: time="2025-11-08T00:06:13.532031397Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 8 00:06:13.532861 containerd[2009]: time="2025-11-08T00:06:13.532328805Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 8 00:06:13.532989 containerd[2009]: time="2025-11-08T00:06:13.532904373Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 8 00:06:13.533717 containerd[2009]: time="2025-11-08T00:06:13.533095005Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 8 00:06:13.535238 containerd[2009]: time="2025-11-08T00:06:13.533139993Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 8 00:06:13.535306 containerd[2009]: time="2025-11-08T00:06:13.535241013Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 8 00:06:13.535306 containerd[2009]: time="2025-11-08T00:06:13.535280661Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.535391 containerd[2009]: time="2025-11-08T00:06:13.535314861Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.535391 containerd[2009]: time="2025-11-08T00:06:13.535359753Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.535504 containerd[2009]: time="2025-11-08T00:06:13.535392537Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537602925Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537675909Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537716169Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537748713Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537792621Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537842853Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537881337Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537914145Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537944169Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.537986433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.538014957Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.538070997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.538106949Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543199 containerd[2009]: time="2025-11-08T00:06:13.538145193Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538202673Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538235649Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538267761Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538304685Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538352649Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538382061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538409289Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538652157Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538695069Z" 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:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538722441Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538764393Z" 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:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538798053Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538833657Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 8 00:06:13.543881 containerd[2009]: time="2025-11-08T00:06:13.538858365Z" level=info msg="NRI interface is disabled by configuration." Nov 8 00:06:13.544495 containerd[2009]: time="2025-11-08T00:06:13.538884333Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 8 00:06:13.544549 containerd[2009]: time="2025-11-08T00:06:13.541680393Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 8 00:06:13.544549 containerd[2009]: time="2025-11-08T00:06:13.541807749Z" level=info msg="Connect containerd service" Nov 8 00:06:13.544549 containerd[2009]: time="2025-11-08T00:06:13.542048205Z" level=info msg="using legacy CRI server" Nov 8 00:06:13.544549 containerd[2009]: time="2025-11-08T00:06:13.542069457Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 8 00:06:13.544549 containerd[2009]: time="2025-11-08T00:06:13.542244213Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 8 00:06:13.546149 containerd[2009]: time="2025-11-08T00:06:13.546019329Z" 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:06:13.548495 containerd[2009]: time="2025-11-08T00:06:13.548412669Z" level=info msg="Start subscribing containerd event" Nov 8 00:06:13.548580 containerd[2009]: time="2025-11-08T00:06:13.548509569Z" level=info msg="Start recovering state" Nov 8 00:06:13.548692 containerd[2009]: time="2025-11-08T00:06:13.548636865Z" level=info msg="Start event monitor" Nov 8 00:06:13.548692 containerd[2009]: time="2025-11-08T00:06:13.548662641Z" level=info msg="Start snapshots syncer" Nov 8 00:06:13.548692 containerd[2009]: time="2025-11-08T00:06:13.548683557Z" level=info msg="Start cni network conf syncer for default" Nov 8 00:06:13.548844 containerd[2009]: time="2025-11-08T00:06:13.548709681Z" level=info msg="Start streaming server" Nov 8 00:06:13.552082 containerd[2009]: time="2025-11-08T00:06:13.552001629Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 8 00:06:13.555194 containerd[2009]: time="2025-11-08T00:06:13.554358009Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 8 00:06:13.555194 containerd[2009]: time="2025-11-08T00:06:13.555243549Z" level=info msg="containerd successfully booted in 0.192141s" Nov 8 00:06:13.555371 systemd[1]: Started containerd.service - containerd container runtime. Nov 8 00:06:13.611178 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO http_proxy: Nov 8 00:06:13.706526 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO no_proxy: Nov 8 00:06:13.806237 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO Checking if agent identity type OnPrem can be assumed Nov 8 00:06:13.903253 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO Checking if agent identity type EC2 can be assumed Nov 8 00:06:14.002390 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO Agent will take identity from EC2 Nov 8 00:06:14.022912 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:06:14.023053 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:06:14.023053 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 8 00:06:14.023053 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 8 00:06:14.023053 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 8 00:06:14.023053 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] Starting Core Agent Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [Registrar] Starting registrar module Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [EC2Identity] EC2 registration was successful. Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [CredentialRefresher] credentialRefresher has started Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:13 INFO [CredentialRefresher] Starting credentials refresher loop Nov 8 00:06:14.023278 amazon-ssm-agent[2160]: 2025-11-08 00:06:14 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 8 00:06:14.100475 amazon-ssm-agent[2160]: 2025-11-08 00:06:14 INFO [CredentialRefresher] Next credential rotation will be in 30.683299211133335 minutes Nov 8 00:06:14.507043 sshd_keygen[2020]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 8 00:06:14.517152 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 8 00:06:14.561780 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 8 00:06:14.572838 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 8 00:06:14.594800 systemd[1]: Started sshd@0-172.31.24.87:22-139.178.89.65:42444.service - OpenSSH per-connection server daemon (139.178.89.65:42444). Nov 8 00:06:14.618358 systemd[1]: issuegen.service: Deactivated successfully. Nov 8 00:06:14.620853 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 8 00:06:14.639662 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 8 00:06:14.658411 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 8 00:06:14.669788 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 8 00:06:14.691704 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 8 00:06:14.694655 systemd[1]: Reached target getty.target - Login Prompts. Nov 8 00:06:14.802884 sshd[2216]: Accepted publickey for core from 139.178.89.65 port 42444 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:06:14.806722 sshd[2216]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:06:14.824025 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 8 00:06:14.834532 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 8 00:06:14.848279 systemd-logind[1997]: New session 1 of user core. Nov 8 00:06:14.871090 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 8 00:06:14.893690 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 8 00:06:14.901718 (systemd)[2227]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 8 00:06:15.070141 amazon-ssm-agent[2160]: 2025-11-08 00:06:15 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 8 00:06:15.133067 systemd[2227]: Queued start job for default target default.target. Nov 8 00:06:15.144830 systemd[2227]: Created slice app.slice - User Application Slice. Nov 8 00:06:15.144900 systemd[2227]: Reached target paths.target - Paths. Nov 8 00:06:15.144934 systemd[2227]: Reached target timers.target - Timers. Nov 8 00:06:15.150499 systemd[2227]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 8 00:06:15.173039 amazon-ssm-agent[2160]: 2025-11-08 00:06:15 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2234) started Nov 8 00:06:15.195007 systemd[2227]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 8 00:06:15.195307 systemd[2227]: Reached target sockets.target - Sockets. Nov 8 00:06:15.195384 systemd[2227]: Reached target basic.target - Basic System. Nov 8 00:06:15.195470 systemd[2227]: Reached target default.target - Main User Target. Nov 8 00:06:15.195533 systemd[2227]: Startup finished in 281ms. Nov 8 00:06:15.196338 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 8 00:06:15.210491 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 8 00:06:15.243791 ntpd[1991]: Listen normally on 6 eth0 [fe80::4d0:8ff:fe9a:2f69%2]:123 Nov 8 00:06:15.244317 ntpd[1991]: 8 Nov 00:06:15 ntpd[1991]: Listen normally on 6 eth0 [fe80::4d0:8ff:fe9a:2f69%2]:123 Nov 8 00:06:15.281322 amazon-ssm-agent[2160]: 2025-11-08 00:06:15 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 8 00:06:15.384614 systemd[1]: Started sshd@1-172.31.24.87:22-139.178.89.65:42446.service - OpenSSH per-connection server daemon (139.178.89.65:42446). Nov 8 00:06:15.568230 sshd[2250]: Accepted publickey for core from 139.178.89.65 port 42446 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:06:15.570375 sshd[2250]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:06:15.578015 systemd-logind[1997]: New session 2 of user core. Nov 8 00:06:15.590765 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 8 00:06:15.628482 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:06:15.632242 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 8 00:06:15.634902 systemd[1]: Startup finished in 1.184s (kernel) + 7.338s (initrd) + 8.533s (userspace) = 17.055s. Nov 8 00:06:15.641654 (kubelet)[2258]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 8 00:06:15.734069 sshd[2250]: pam_unix(sshd:session): session closed for user core Nov 8 00:06:15.740948 systemd[1]: sshd@1-172.31.24.87:22-139.178.89.65:42446.service: Deactivated successfully. Nov 8 00:06:15.746612 systemd[1]: session-2.scope: Deactivated successfully. Nov 8 00:06:15.749570 systemd-logind[1997]: Session 2 logged out. Waiting for processes to exit. Nov 8 00:06:15.752704 systemd-logind[1997]: Removed session 2. Nov 8 00:06:15.771682 systemd[1]: Started sshd@2-172.31.24.87:22-139.178.89.65:42452.service - OpenSSH per-connection server daemon (139.178.89.65:42452). Nov 8 00:06:15.944738 sshd[2267]: Accepted publickey for core from 139.178.89.65 port 42452 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:06:15.947511 sshd[2267]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:06:15.955269 systemd-logind[1997]: New session 3 of user core. Nov 8 00:06:15.969451 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 8 00:06:16.089367 sshd[2267]: pam_unix(sshd:session): session closed for user core Nov 8 00:06:16.098520 systemd[1]: sshd@2-172.31.24.87:22-139.178.89.65:42452.service: Deactivated successfully. Nov 8 00:06:16.101786 systemd[1]: session-3.scope: Deactivated successfully. Nov 8 00:06:16.104593 systemd-logind[1997]: Session 3 logged out. Waiting for processes to exit. Nov 8 00:06:16.106679 systemd-logind[1997]: Removed session 3. Nov 8 00:06:16.131690 systemd[1]: Started sshd@3-172.31.24.87:22-139.178.89.65:32924.service - OpenSSH per-connection server daemon (139.178.89.65:32924). Nov 8 00:06:16.310207 sshd[2278]: Accepted publickey for core from 139.178.89.65 port 32924 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:06:16.313836 sshd[2278]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:06:16.323779 systemd-logind[1997]: New session 4 of user core. Nov 8 00:06:16.336464 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 8 00:06:16.467510 sshd[2278]: pam_unix(sshd:session): session closed for user core Nov 8 00:06:16.472708 systemd-logind[1997]: Session 4 logged out. Waiting for processes to exit. Nov 8 00:06:16.476099 systemd[1]: sshd@3-172.31.24.87:22-139.178.89.65:32924.service: Deactivated successfully. Nov 8 00:06:16.479868 systemd[1]: session-4.scope: Deactivated successfully. Nov 8 00:06:16.481749 systemd-logind[1997]: Removed session 4. Nov 8 00:06:16.504554 systemd[1]: Started sshd@4-172.31.24.87:22-139.178.89.65:32934.service - OpenSSH per-connection server daemon (139.178.89.65:32934). Nov 8 00:06:16.680004 sshd[2285]: Accepted publickey for core from 139.178.89.65 port 32934 ssh2: RSA SHA256:tnEXpnDY8gLTej7GJ+T99WI4otIwvlI9IcMNDF42aqw Nov 8 00:06:16.683783 sshd[2285]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 8 00:06:16.692124 systemd-logind[1997]: New session 5 of user core. Nov 8 00:06:16.698542 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 8 00:06:16.821723 sudo[2289]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 8 00:06:16.822418 sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 8 00:06:16.871693 kubelet[2258]: E1108 00:06:16.871518 2258 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:06:16.875967 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 8 00:06:16.876385 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 8 00:06:16.877678 systemd[1]: kubelet.service: Consumed 1.399s CPU time. Nov 8 00:06:18.038844 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:06:18.039889 systemd[1]: kubelet.service: Consumed 1.399s CPU time. Nov 8 00:06:18.051663 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:06:18.111577 systemd[1]: Reloading requested from client PID 2325 ('systemctl') (unit session-5.scope)... Nov 8 00:06:18.111799 systemd[1]: Reloading... Nov 8 00:06:18.335205 zram_generator::config[2368]: No configuration found. Nov 8 00:06:18.588540 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:06:18.761699 systemd[1]: Reloading finished in 649 ms. Nov 8 00:06:18.857822 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 8 00:06:18.858032 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 8 00:06:18.858670 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:06:18.864866 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 8 00:06:19.180910 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 8 00:06:19.199756 (kubelet)[2429]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 8 00:06:19.381344 systemd-resolved[1938]: Clock change detected. Flushing caches. Nov 8 00:06:19.413863 kubelet[2429]: 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:06:19.413863 kubelet[2429]: 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:06:19.413863 kubelet[2429]: 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:06:19.414363 kubelet[2429]: I1108 00:06:19.414057 2429 server.go:212] "--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:06:20.086000 kubelet[2429]: I1108 00:06:20.085941 2429 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Nov 8 00:06:20.086328 kubelet[2429]: I1108 00:06:20.086195 2429 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 8 00:06:20.087016 kubelet[2429]: I1108 00:06:20.086753 2429 server.go:956] "Client rotation is on, will bootstrap in background" Nov 8 00:06:20.129670 kubelet[2429]: I1108 00:06:20.129632 2429 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 8 00:06:20.146438 kubelet[2429]: E1108 00:06:20.146380 2429 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:06:20.146618 kubelet[2429]: I1108 00:06:20.146597 2429 server.go:1423] "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:06:20.151683 kubelet[2429]: I1108 00:06:20.151018 2429 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 8 00:06:20.153328 kubelet[2429]: I1108 00:06:20.153274 2429 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 8 00:06:20.153788 kubelet[2429]: I1108 00:06:20.153438 2429 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.24.87","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 8 00:06:20.154117 kubelet[2429]: I1108 00:06:20.154094 2429 topology_manager.go:138] "Creating topology manager with none policy" Nov 8 00:06:20.154244 kubelet[2429]: I1108 00:06:20.154225 2429 container_manager_linux.go:303] "Creating device plugin manager" Nov 8 00:06:20.154664 kubelet[2429]: I1108 00:06:20.154643 2429 state_mem.go:36] "Initialized new in-memory state store" Nov 8 00:06:20.160605 kubelet[2429]: I1108 00:06:20.160569 2429 kubelet.go:480] "Attempting to sync node with API server" Nov 8 00:06:20.160810 kubelet[2429]: I1108 00:06:20.160789 2429 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 8 00:06:20.160946 kubelet[2429]: I1108 00:06:20.160928 2429 kubelet.go:386] "Adding apiserver pod source" Nov 8 00:06:20.161055 kubelet[2429]: I1108 00:06:20.161036 2429 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 8 00:06:20.161438 kubelet[2429]: E1108 00:06:20.161387 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:20.161510 kubelet[2429]: E1108 00:06:20.161459 2429 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:20.163785 kubelet[2429]: I1108 00:06:20.163744 2429 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Nov 8 00:06:20.165020 kubelet[2429]: I1108 00:06:20.164985 2429 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 8 00:06:20.165248 kubelet[2429]: W1108 00:06:20.165222 2429 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 8 00:06:20.170236 kubelet[2429]: I1108 00:06:20.170181 2429 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 8 00:06:20.170368 kubelet[2429]: I1108 00:06:20.170339 2429 server.go:1289] "Started kubelet" Nov 8 00:06:20.175711 kubelet[2429]: I1108 00:06:20.175629 2429 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 8 00:06:20.187628 kubelet[2429]: E1108 00:06:20.187578 2429 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"172.31.24.87\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Nov 8 00:06:20.191717 kubelet[2429]: I1108 00:06:20.190190 2429 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 8 00:06:20.193063 kubelet[2429]: I1108 00:06:20.192987 2429 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 8 00:06:20.196658 kubelet[2429]: I1108 00:06:20.196621 2429 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 8 00:06:20.199484 kubelet[2429]: E1108 00:06:20.187974 2429 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.24.87.1875df53cb39146b default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.24.87,UID:172.31.24.87,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.24.87,},FirstTimestamp:2025-11-08 00:06:20.170212459 +0000 UTC m=+0.822725405,LastTimestamp:2025-11-08 00:06:20.170212459 +0000 UTC m=+0.822725405,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.24.87,}" Nov 8 00:06:20.199747 kubelet[2429]: I1108 00:06:20.197118 2429 server.go:317] "Adding debug handlers to kubelet server" Nov 8 00:06:20.201766 kubelet[2429]: E1108 00:06:20.201730 2429 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 8 00:06:20.204577 kubelet[2429]: I1108 00:06:20.204540 2429 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:06:20.208200 kubelet[2429]: I1108 00:06:20.208169 2429 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 8 00:06:20.212095 kubelet[2429]: I1108 00:06:20.209971 2429 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 8 00:06:20.212295 kubelet[2429]: E1108 00:06:20.210212 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.212471 kubelet[2429]: I1108 00:06:20.212451 2429 reconciler.go:26] "Reconciler: start to sync state" Nov 8 00:06:20.215235 kubelet[2429]: I1108 00:06:20.215116 2429 factory.go:221] 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:06:20.226902 kubelet[2429]: E1108 00:06:20.226860 2429 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.24.87\" not found" node="172.31.24.87" Nov 8 00:06:20.230257 kubelet[2429]: I1108 00:06:20.230222 2429 factory.go:223] Registration of the containerd container factory successfully Nov 8 00:06:20.230679 kubelet[2429]: I1108 00:06:20.230631 2429 factory.go:223] Registration of the systemd container factory successfully Nov 8 00:06:20.259370 kubelet[2429]: I1108 00:06:20.259219 2429 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 8 00:06:20.259858 kubelet[2429]: I1108 00:06:20.259249 2429 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 8 00:06:20.259858 kubelet[2429]: I1108 00:06:20.259788 2429 state_mem.go:36] "Initialized new in-memory state store" Nov 8 00:06:20.267085 kubelet[2429]: I1108 00:06:20.266770 2429 policy_none.go:49] "None policy: Start" Nov 8 00:06:20.267085 kubelet[2429]: I1108 00:06:20.266820 2429 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 8 00:06:20.267085 kubelet[2429]: I1108 00:06:20.266844 2429 state_mem.go:35] "Initializing new in-memory state store" Nov 8 00:06:20.286743 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 8 00:06:20.313771 kubelet[2429]: E1108 00:06:20.312917 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.317023 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 8 00:06:20.322144 kubelet[2429]: I1108 00:06:20.321848 2429 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Nov 8 00:06:20.325610 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 8 00:06:20.328945 kubelet[2429]: I1108 00:06:20.328905 2429 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Nov 8 00:06:20.329112 kubelet[2429]: I1108 00:06:20.329093 2429 status_manager.go:230] "Starting to sync pod status with apiserver" Nov 8 00:06:20.329264 kubelet[2429]: I1108 00:06:20.329233 2429 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 8 00:06:20.329383 kubelet[2429]: I1108 00:06:20.329365 2429 kubelet.go:2436] "Starting kubelet main sync loop" Nov 8 00:06:20.329571 kubelet[2429]: E1108 00:06:20.329541 2429 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 8 00:06:20.336539 kubelet[2429]: E1108 00:06:20.336414 2429 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 8 00:06:20.337382 kubelet[2429]: I1108 00:06:20.337353 2429 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 8 00:06:20.341649 kubelet[2429]: I1108 00:06:20.340712 2429 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 8 00:06:20.341649 kubelet[2429]: I1108 00:06:20.341137 2429 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 8 00:06:20.353135 kubelet[2429]: E1108 00:06:20.353082 2429 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:06:20.353304 kubelet[2429]: E1108 00:06:20.353151 2429 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.24.87\" not found" Nov 8 00:06:20.442076 kubelet[2429]: I1108 00:06:20.442042 2429 kubelet_node_status.go:75] "Attempting to register node" node="172.31.24.87" Nov 8 00:06:20.450027 kubelet[2429]: I1108 00:06:20.449937 2429 kubelet_node_status.go:78] "Successfully registered node" node="172.31.24.87" Nov 8 00:06:20.450027 kubelet[2429]: E1108 00:06:20.449978 2429 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"172.31.24.87\": node \"172.31.24.87\" not found" Nov 8 00:06:20.476625 kubelet[2429]: E1108 00:06:20.476579 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.577229 kubelet[2429]: E1108 00:06:20.577175 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.677686 kubelet[2429]: E1108 00:06:20.677558 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.778359 kubelet[2429]: E1108 00:06:20.778299 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.870627 sudo[2289]: pam_unix(sudo:session): session closed for user root Nov 8 00:06:20.879069 kubelet[2429]: E1108 00:06:20.879016 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:20.896000 sshd[2285]: pam_unix(sshd:session): session closed for user core Nov 8 00:06:20.901573 systemd[1]: sshd@4-172.31.24.87:22-139.178.89.65:32934.service: Deactivated successfully. Nov 8 00:06:20.904423 systemd[1]: session-5.scope: Deactivated successfully. Nov 8 00:06:20.908290 systemd-logind[1997]: Session 5 logged out. Waiting for processes to exit. Nov 8 00:06:20.910452 systemd-logind[1997]: Removed session 5. Nov 8 00:06:20.980056 kubelet[2429]: E1108 00:06:20.979892 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.080614 kubelet[2429]: E1108 00:06:21.080552 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.089873 kubelet[2429]: I1108 00:06:21.089796 2429 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 8 00:06:21.090220 kubelet[2429]: I1108 00:06:21.090020 2429 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="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:06:21.090220 kubelet[2429]: I1108 00:06:21.090021 2429 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="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:06:21.090220 kubelet[2429]: I1108 00:06:21.090079 2429 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="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:06:21.161813 kubelet[2429]: E1108 00:06:21.161754 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:21.181339 kubelet[2429]: E1108 00:06:21.181289 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.282408 kubelet[2429]: E1108 00:06:21.282306 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.383030 kubelet[2429]: E1108 00:06:21.382974 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.483814 kubelet[2429]: E1108 00:06:21.483762 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.584659 kubelet[2429]: E1108 00:06:21.584552 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.684903 kubelet[2429]: E1108 00:06:21.684847 2429 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.24.87\" not found" Nov 8 00:06:21.787245 kubelet[2429]: I1108 00:06:21.786594 2429 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 8 00:06:21.787423 containerd[2009]: time="2025-11-08T00:06:21.787094135Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 8 00:06:21.787945 kubelet[2429]: I1108 00:06:21.787412 2429 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 8 00:06:22.162453 kubelet[2429]: E1108 00:06:22.162386 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:22.163646 kubelet[2429]: I1108 00:06:22.163547 2429 apiserver.go:52] "Watching apiserver" Nov 8 00:06:22.186374 systemd[1]: Created slice kubepods-burstable-pod0e096963_1b33_48eb_8695_6eae935cd06b.slice - libcontainer container kubepods-burstable-pod0e096963_1b33_48eb_8695_6eae935cd06b.slice. Nov 8 00:06:22.213753 kubelet[2429]: I1108 00:06:22.213707 2429 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 8 00:06:22.219554 systemd[1]: Created slice kubepods-besteffort-pod2ea5f522_8ce3_46c9_934e_a17f919cba1f.slice - libcontainer container kubepods-besteffort-pod2ea5f522_8ce3_46c9_934e_a17f919cba1f.slice. Nov 8 00:06:22.224415 kubelet[2429]: I1108 00:06:22.224353 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jtmb2\" (UniqueName: \"kubernetes.io/projected/2ea5f522-8ce3-46c9-934e-a17f919cba1f-kube-api-access-jtmb2\") pod \"kube-proxy-6ksgs\" (UID: \"2ea5f522-8ce3-46c9-934e-a17f919cba1f\") " pod="kube-system/kube-proxy-6ksgs" Nov 8 00:06:22.224535 kubelet[2429]: I1108 00:06:22.224422 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/0e096963-1b33-48eb-8695-6eae935cd06b-run\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.224535 kubelet[2429]: I1108 00:06:22.224481 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/0e096963-1b33-48eb-8695-6eae935cd06b-cni\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.224535 kubelet[2429]: I1108 00:06:22.224522 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0e096963-1b33-48eb-8695-6eae935cd06b-xtables-lock\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.224773 kubelet[2429]: I1108 00:06:22.224560 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x5d7t\" (UniqueName: \"kubernetes.io/projected/0e096963-1b33-48eb-8695-6eae935cd06b-kube-api-access-x5d7t\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.224773 kubelet[2429]: I1108 00:06:22.224594 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2ea5f522-8ce3-46c9-934e-a17f919cba1f-kube-proxy\") pod \"kube-proxy-6ksgs\" (UID: \"2ea5f522-8ce3-46c9-934e-a17f919cba1f\") " pod="kube-system/kube-proxy-6ksgs" Nov 8 00:06:22.224773 kubelet[2429]: I1108 00:06:22.224628 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2ea5f522-8ce3-46c9-934e-a17f919cba1f-lib-modules\") pod \"kube-proxy-6ksgs\" (UID: \"2ea5f522-8ce3-46c9-934e-a17f919cba1f\") " pod="kube-system/kube-proxy-6ksgs" Nov 8 00:06:22.224773 kubelet[2429]: I1108 00:06:22.224670 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/0e096963-1b33-48eb-8695-6eae935cd06b-cni-plugin\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.224773 kubelet[2429]: I1108 00:06:22.224730 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/0e096963-1b33-48eb-8695-6eae935cd06b-flannel-cfg\") pod \"kube-flannel-ds-9pvzz\" (UID: \"0e096963-1b33-48eb-8695-6eae935cd06b\") " pod="kube-flannel/kube-flannel-ds-9pvzz" Nov 8 00:06:22.225013 kubelet[2429]: I1108 00:06:22.224766 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2ea5f522-8ce3-46c9-934e-a17f919cba1f-xtables-lock\") pod \"kube-proxy-6ksgs\" (UID: \"2ea5f522-8ce3-46c9-934e-a17f919cba1f\") " pod="kube-system/kube-proxy-6ksgs" Nov 8 00:06:22.518540 containerd[2009]: time="2025-11-08T00:06:22.514972655Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9pvzz,Uid:0e096963-1b33-48eb-8695-6eae935cd06b,Namespace:kube-flannel,Attempt:0,}" Nov 8 00:06:22.532065 containerd[2009]: time="2025-11-08T00:06:22.531985547Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6ksgs,Uid:2ea5f522-8ce3-46c9-934e-a17f919cba1f,Namespace:kube-system,Attempt:0,}" Nov 8 00:06:23.125563 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount81195885.mount: Deactivated successfully. Nov 8 00:06:23.142371 containerd[2009]: time="2025-11-08T00:06:23.142290394Z" 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:06:23.144751 containerd[2009]: time="2025-11-08T00:06:23.144371782Z" 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:06:23.146657 containerd[2009]: time="2025-11-08T00:06:23.146602618Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 8 00:06:23.148210 containerd[2009]: time="2025-11-08T00:06:23.148145638Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 8 00:06:23.152809 containerd[2009]: time="2025-11-08T00:06:23.152710174Z" 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:06:23.160167 containerd[2009]: time="2025-11-08T00:06:23.160083478Z" 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:06:23.162177 containerd[2009]: time="2025-11-08T00:06:23.161850850Z" 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 646.761051ms" Nov 8 00:06:23.163070 kubelet[2429]: E1108 00:06:23.162906 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:23.167491 containerd[2009]: time="2025-11-08T00:06:23.167415166Z" 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 635.307747ms" Nov 8 00:06:23.380871 containerd[2009]: time="2025-11-08T00:06:23.380107175Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:23.380871 containerd[2009]: time="2025-11-08T00:06:23.380248931Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:23.380871 containerd[2009]: time="2025-11-08T00:06:23.380287451Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:23.382353 containerd[2009]: time="2025-11-08T00:06:23.381684491Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:23.382353 containerd[2009]: time="2025-11-08T00:06:23.381815987Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:23.382353 containerd[2009]: time="2025-11-08T00:06:23.381870179Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:23.382353 containerd[2009]: time="2025-11-08T00:06:23.382034339Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:23.382353 containerd[2009]: time="2025-11-08T00:06:23.381662099Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:23.531040 systemd[1]: Started cri-containerd-3afc9905ec0b764be54fdd2463fd4e04bcd49786b7677cebac47e8d1aa9c649f.scope - libcontainer container 3afc9905ec0b764be54fdd2463fd4e04bcd49786b7677cebac47e8d1aa9c649f. Nov 8 00:06:23.539038 systemd[1]: Started cri-containerd-f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb.scope - libcontainer container f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb. Nov 8 00:06:23.623147 containerd[2009]: time="2025-11-08T00:06:23.622376353Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6ksgs,Uid:2ea5f522-8ce3-46c9-934e-a17f919cba1f,Namespace:kube-system,Attempt:0,} returns sandbox id \"3afc9905ec0b764be54fdd2463fd4e04bcd49786b7677cebac47e8d1aa9c649f\"" Nov 8 00:06:23.628764 containerd[2009]: time="2025-11-08T00:06:23.628383229Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Nov 8 00:06:23.635591 containerd[2009]: time="2025-11-08T00:06:23.635268373Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9pvzz,Uid:0e096963-1b33-48eb-8695-6eae935cd06b,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\"" Nov 8 00:06:24.163931 kubelet[2429]: E1108 00:06:24.163818 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:24.957247 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3942361824.mount: Deactivated successfully. Nov 8 00:06:25.164256 kubelet[2429]: E1108 00:06:25.164195 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:25.573763 containerd[2009]: time="2025-11-08T00:06:25.572925506Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:25.575373 containerd[2009]: time="2025-11-08T00:06:25.575019458Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240106" Nov 8 00:06:25.577773 containerd[2009]: time="2025-11-08T00:06:25.577708922Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:25.582658 containerd[2009]: time="2025-11-08T00:06:25.582578042Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:25.584734 containerd[2009]: time="2025-11-08T00:06:25.583926698Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.955353581s" Nov 8 00:06:25.584734 containerd[2009]: time="2025-11-08T00:06:25.583985642Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Nov 8 00:06:25.587095 containerd[2009]: time="2025-11-08T00:06:25.587021330Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 8 00:06:25.592824 containerd[2009]: time="2025-11-08T00:06:25.592763822Z" level=info msg="CreateContainer within sandbox \"3afc9905ec0b764be54fdd2463fd4e04bcd49786b7677cebac47e8d1aa9c649f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 8 00:06:25.634995 containerd[2009]: time="2025-11-08T00:06:25.634913055Z" level=info msg="CreateContainer within sandbox \"3afc9905ec0b764be54fdd2463fd4e04bcd49786b7677cebac47e8d1aa9c649f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"32f4a440c8c07038049aa0fb456569a488ab744eb7a0377000ba6a85f81b5b29\"" Nov 8 00:06:25.636221 containerd[2009]: time="2025-11-08T00:06:25.636180171Z" level=info msg="StartContainer for \"32f4a440c8c07038049aa0fb456569a488ab744eb7a0377000ba6a85f81b5b29\"" Nov 8 00:06:25.693007 systemd[1]: Started cri-containerd-32f4a440c8c07038049aa0fb456569a488ab744eb7a0377000ba6a85f81b5b29.scope - libcontainer container 32f4a440c8c07038049aa0fb456569a488ab744eb7a0377000ba6a85f81b5b29. Nov 8 00:06:25.745442 containerd[2009]: time="2025-11-08T00:06:25.745254639Z" level=info msg="StartContainer for \"32f4a440c8c07038049aa0fb456569a488ab744eb7a0377000ba6a85f81b5b29\" returns successfully" Nov 8 00:06:26.166496 kubelet[2429]: E1108 00:06:26.166421 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:26.973980 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount255090503.mount: Deactivated successfully. Nov 8 00:06:27.046760 containerd[2009]: time="2025-11-08T00:06:27.046271798Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:27.048679 containerd[2009]: time="2025-11-08T00:06:27.048606434Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Nov 8 00:06:27.051998 containerd[2009]: time="2025-11-08T00:06:27.051918914Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:27.058119 containerd[2009]: time="2025-11-08T00:06:27.058012010Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:27.061456 containerd[2009]: time="2025-11-08T00:06:27.060822494Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 1.473732248s" Nov 8 00:06:27.061456 containerd[2009]: time="2025-11-08T00:06:27.060886082Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Nov 8 00:06:27.068797 containerd[2009]: time="2025-11-08T00:06:27.068723342Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 8 00:06:27.093281 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1257087194.mount: Deactivated successfully. Nov 8 00:06:27.100369 containerd[2009]: time="2025-11-08T00:06:27.100063454Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966\"" Nov 8 00:06:27.101206 containerd[2009]: time="2025-11-08T00:06:27.101131562Z" level=info msg="StartContainer for \"902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966\"" Nov 8 00:06:27.149086 systemd[1]: Started cri-containerd-902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966.scope - libcontainer container 902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966. Nov 8 00:06:27.167433 kubelet[2429]: E1108 00:06:27.167338 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:27.200496 systemd[1]: cri-containerd-902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966.scope: Deactivated successfully. Nov 8 00:06:27.203029 containerd[2009]: time="2025-11-08T00:06:27.202981202Z" level=info msg="StartContainer for \"902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966\" returns successfully" Nov 8 00:06:27.390976 kubelet[2429]: I1108 00:06:27.390824 2429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-6ksgs" podStartSLOduration=5.431373326 podStartE2EDuration="7.390799827s" podCreationTimestamp="2025-11-08 00:06:20 +0000 UTC" firstStartedPulling="2025-11-08 00:06:23.626437477 +0000 UTC m=+4.278950411" lastFinishedPulling="2025-11-08 00:06:25.58586399 +0000 UTC m=+6.238376912" observedRunningTime="2025-11-08 00:06:26.381779654 +0000 UTC m=+7.034292600" watchObservedRunningTime="2025-11-08 00:06:27.390799827 +0000 UTC m=+8.043312869" Nov 8 00:06:27.413255 containerd[2009]: time="2025-11-08T00:06:27.413175015Z" level=info msg="shim disconnected" id=902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966 namespace=k8s.io Nov 8 00:06:27.413749 containerd[2009]: time="2025-11-08T00:06:27.413255487Z" level=warning msg="cleaning up after shim disconnected" id=902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966 namespace=k8s.io Nov 8 00:06:27.413749 containerd[2009]: time="2025-11-08T00:06:27.413277831Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 8 00:06:27.817448 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-902e5a8a28eda2d9a06379cb3644297c30f9de0d1031b7e7cf51d083da7ce966-rootfs.mount: Deactivated successfully. Nov 8 00:06:28.168027 kubelet[2429]: E1108 00:06:28.167968 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:28.376497 containerd[2009]: time="2025-11-08T00:06:28.376429276Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 8 00:06:29.168988 kubelet[2429]: E1108 00:06:29.168922 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:30.169305 kubelet[2429]: E1108 00:06:30.169170 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:30.944765 containerd[2009]: time="2025-11-08T00:06:30.944431017Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:30.947510 containerd[2009]: time="2025-11-08T00:06:30.947437329Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Nov 8 00:06:30.950389 containerd[2009]: time="2025-11-08T00:06:30.950313165Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:30.961730 containerd[2009]: time="2025-11-08T00:06:30.960156765Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:30.963449 containerd[2009]: time="2025-11-08T00:06:30.963399009Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 2.586907429s" Nov 8 00:06:30.963615 containerd[2009]: time="2025-11-08T00:06:30.963584841Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Nov 8 00:06:30.971467 containerd[2009]: time="2025-11-08T00:06:30.971397741Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 8 00:06:31.000305 containerd[2009]: time="2025-11-08T00:06:31.000248285Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb\"" Nov 8 00:06:31.001863 containerd[2009]: time="2025-11-08T00:06:31.001814585Z" level=info msg="StartContainer for \"f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb\"" Nov 8 00:06:31.055011 systemd[1]: Started cri-containerd-f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb.scope - libcontainer container f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb. Nov 8 00:06:31.105410 systemd[1]: cri-containerd-f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb.scope: Deactivated successfully. Nov 8 00:06:31.108335 containerd[2009]: time="2025-11-08T00:06:31.108262566Z" level=info msg="StartContainer for \"f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb\" returns successfully" Nov 8 00:06:31.139807 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb-rootfs.mount: Deactivated successfully. Nov 8 00:06:31.170579 kubelet[2429]: E1108 00:06:31.170518 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:31.201124 kubelet[2429]: I1108 00:06:31.199873 2429 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 8 00:06:31.354335 containerd[2009]: time="2025-11-08T00:06:31.354202975Z" level=info msg="shim disconnected" id=f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb namespace=k8s.io Nov 8 00:06:31.354335 containerd[2009]: time="2025-11-08T00:06:31.354324523Z" level=warning msg="cleaning up after shim disconnected" id=f300749b032e1afde5a3a87913fb7e53fcd6c0b4326e0d6b7cd54deb510271bb namespace=k8s.io Nov 8 00:06:31.354623 containerd[2009]: time="2025-11-08T00:06:31.354347671Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 8 00:06:31.389978 containerd[2009]: time="2025-11-08T00:06:31.389624899Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 8 00:06:31.408569 containerd[2009]: time="2025-11-08T00:06:31.408466279Z" level=info msg="CreateContainer within sandbox \"f2b4140484ad9a57f66ddf9f46e6fb2dfabf1e78c589b68b65e1379e6d558feb\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"22386e5977e8e93f64cc0ac8b7031e9492d5d64ac9b623701ac62ff3f87bc425\"" Nov 8 00:06:31.409677 containerd[2009]: time="2025-11-08T00:06:31.409602463Z" level=info msg="StartContainer for \"22386e5977e8e93f64cc0ac8b7031e9492d5d64ac9b623701ac62ff3f87bc425\"" Nov 8 00:06:31.460009 systemd[1]: Started cri-containerd-22386e5977e8e93f64cc0ac8b7031e9492d5d64ac9b623701ac62ff3f87bc425.scope - libcontainer container 22386e5977e8e93f64cc0ac8b7031e9492d5d64ac9b623701ac62ff3f87bc425. Nov 8 00:06:31.506255 containerd[2009]: time="2025-11-08T00:06:31.506187380Z" level=info msg="StartContainer for \"22386e5977e8e93f64cc0ac8b7031e9492d5d64ac9b623701ac62ff3f87bc425\" returns successfully" Nov 8 00:06:32.171136 kubelet[2429]: E1108 00:06:32.171070 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:32.407464 kubelet[2429]: I1108 00:06:32.407349 2429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-9pvzz" podStartSLOduration=5.0806853 podStartE2EDuration="12.407306672s" podCreationTimestamp="2025-11-08 00:06:20 +0000 UTC" firstStartedPulling="2025-11-08 00:06:23.638993065 +0000 UTC m=+4.291506023" lastFinishedPulling="2025-11-08 00:06:30.965614461 +0000 UTC m=+11.618127395" observedRunningTime="2025-11-08 00:06:32.407044952 +0000 UTC m=+13.059557922" watchObservedRunningTime="2025-11-08 00:06:32.407306672 +0000 UTC m=+13.059819618" Nov 8 00:06:32.613429 (udev-worker)[2938]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:32.635078 systemd-networkd[1937]: flannel.1: Link UP Nov 8 00:06:32.635098 systemd-networkd[1937]: flannel.1: Gained carrier Nov 8 00:06:32.944788 systemd[1]: Created slice kubepods-besteffort-podd12d091d_cf1d_459d_801e_74c762130bd4.slice - libcontainer container kubepods-besteffort-podd12d091d_cf1d_459d_801e_74c762130bd4.slice. Nov 8 00:06:32.998146 kubelet[2429]: I1108 00:06:32.998082 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7cjlt\" (UniqueName: \"kubernetes.io/projected/d12d091d-cf1d-459d-801e-74c762130bd4-kube-api-access-7cjlt\") pod \"nginx-deployment-7fcdb87857-2mwnq\" (UID: \"d12d091d-cf1d-459d-801e-74c762130bd4\") " pod="default/nginx-deployment-7fcdb87857-2mwnq" Nov 8 00:06:33.172303 kubelet[2429]: E1108 00:06:33.172218 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:33.251169 containerd[2009]: time="2025-11-08T00:06:33.250663832Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-2mwnq,Uid:d12d091d-cf1d-459d-801e-74c762130bd4,Namespace:default,Attempt:0,}" Nov 8 00:06:33.297213 systemd-networkd[1937]: cni0: Link UP Nov 8 00:06:33.297233 systemd-networkd[1937]: cni0: Gained carrier Nov 8 00:06:33.299077 systemd-networkd[1937]: cni0: Lost carrier Nov 8 00:06:33.306943 (udev-worker)[3009]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:33.309945 systemd-networkd[1937]: veth1ca5db77: Link UP Nov 8 00:06:33.318871 kernel: cni0: port 1(veth1ca5db77) entered blocking state Nov 8 00:06:33.318992 kernel: cni0: port 1(veth1ca5db77) entered disabled state Nov 8 00:06:33.319037 kernel: veth1ca5db77: entered allmulticast mode Nov 8 00:06:33.328323 kernel: veth1ca5db77: entered promiscuous mode Nov 8 00:06:33.333307 kernel: cni0: port 1(veth1ca5db77) entered blocking state Nov 8 00:06:33.333611 kernel: cni0: port 1(veth1ca5db77) entered forwarding state Nov 8 00:06:33.335752 kernel: cni0: port 1(veth1ca5db77) entered disabled state Nov 8 00:06:33.348649 kernel: cni0: port 1(veth1ca5db77) entered blocking state Nov 8 00:06:33.348959 kernel: cni0: port 1(veth1ca5db77) entered forwarding state Nov 8 00:06:33.348480 systemd-networkd[1937]: veth1ca5db77: Gained carrier Nov 8 00:06:33.351193 systemd-networkd[1937]: cni0: Gained carrier Nov 8 00:06:33.354336 containerd[2009]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000082950), "name":"cbr0", "type":"bridge"} Nov 8 00:06:33.354336 containerd[2009]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:06:33.395944 containerd[2009]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:06:33.395455269Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:33.395944 containerd[2009]: time="2025-11-08T00:06:33.395558841Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:33.395944 containerd[2009]: time="2025-11-08T00:06:33.395634945Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:33.395944 containerd[2009]: time="2025-11-08T00:06:33.395847573Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:33.434127 systemd[1]: Started cri-containerd-7a1e29c1990447d9baa95004fa144d7c6bff71f736cbbbc1bf80c8142f608d63.scope - libcontainer container 7a1e29c1990447d9baa95004fa144d7c6bff71f736cbbbc1bf80c8142f608d63. Nov 8 00:06:33.493919 containerd[2009]: time="2025-11-08T00:06:33.493842958Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-2mwnq,Uid:d12d091d-cf1d-459d-801e-74c762130bd4,Namespace:default,Attempt:0,} returns sandbox id \"7a1e29c1990447d9baa95004fa144d7c6bff71f736cbbbc1bf80c8142f608d63\"" Nov 8 00:06:33.497115 containerd[2009]: time="2025-11-08T00:06:33.496871710Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 8 00:06:34.172447 kubelet[2429]: E1108 00:06:34.172380 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:34.396047 systemd-networkd[1937]: flannel.1: Gained IPv6LL Nov 8 00:06:34.524005 systemd-networkd[1937]: veth1ca5db77: Gained IPv6LL Nov 8 00:06:35.100265 systemd-networkd[1937]: cni0: Gained IPv6LL Nov 8 00:06:35.173610 kubelet[2429]: E1108 00:06:35.173446 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:36.173859 kubelet[2429]: E1108 00:06:36.173766 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:36.578396 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4185217107.mount: Deactivated successfully. Nov 8 00:06:37.174848 kubelet[2429]: E1108 00:06:37.174673 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:37.381354 ntpd[1991]: Listen normally on 7 flannel.1 192.168.1.0:123 Nov 8 00:06:37.381453 ntpd[1991]: Listen normally on 8 cni0 192.168.1.1:123 Nov 8 00:06:37.381953 ntpd[1991]: 8 Nov 00:06:37 ntpd[1991]: Listen normally on 7 flannel.1 192.168.1.0:123 Nov 8 00:06:37.381953 ntpd[1991]: 8 Nov 00:06:37 ntpd[1991]: Listen normally on 8 cni0 192.168.1.1:123 Nov 8 00:06:37.381953 ntpd[1991]: 8 Nov 00:06:37 ntpd[1991]: Listen normally on 9 flannel.1 [fe80::581d:91ff:fefc:1e9e%3]:123 Nov 8 00:06:37.381953 ntpd[1991]: 8 Nov 00:06:37 ntpd[1991]: Listen normally on 10 cni0 [fe80::18f1:6eff:fe77:5eab%4]:123 Nov 8 00:06:37.381953 ntpd[1991]: 8 Nov 00:06:37 ntpd[1991]: Listen normally on 11 veth1ca5db77 [fe80::f46c:c1ff:fe55:969a%5]:123 Nov 8 00:06:37.381552 ntpd[1991]: Listen normally on 9 flannel.1 [fe80::581d:91ff:fefc:1e9e%3]:123 Nov 8 00:06:37.381634 ntpd[1991]: Listen normally on 10 cni0 [fe80::18f1:6eff:fe77:5eab%4]:123 Nov 8 00:06:37.381874 ntpd[1991]: Listen normally on 11 veth1ca5db77 [fe80::f46c:c1ff:fe55:969a%5]:123 Nov 8 00:06:37.966760 containerd[2009]: time="2025-11-08T00:06:37.966429184Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:37.967556 containerd[2009]: time="2025-11-08T00:06:37.967507708Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 8 00:06:37.969254 containerd[2009]: time="2025-11-08T00:06:37.969136276Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:37.974397 containerd[2009]: time="2025-11-08T00:06:37.974317924Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:37.976664 containerd[2009]: time="2025-11-08T00:06:37.976475788Z" 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.479548422s" Nov 8 00:06:37.976664 containerd[2009]: time="2025-11-08T00:06:37.976531468Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 8 00:06:37.983295 containerd[2009]: time="2025-11-08T00:06:37.983030416Z" level=info msg="CreateContainer within sandbox \"7a1e29c1990447d9baa95004fa144d7c6bff71f736cbbbc1bf80c8142f608d63\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 8 00:06:38.007148 containerd[2009]: time="2025-11-08T00:06:38.007092252Z" level=info msg="CreateContainer within sandbox \"7a1e29c1990447d9baa95004fa144d7c6bff71f736cbbbc1bf80c8142f608d63\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"55a11cddbfe40a1ac32c90a0cc6b85269bfa64f3319e897ab8ecf290b91d3f6a\"" Nov 8 00:06:38.008475 containerd[2009]: time="2025-11-08T00:06:38.008422344Z" level=info msg="StartContainer for \"55a11cddbfe40a1ac32c90a0cc6b85269bfa64f3319e897ab8ecf290b91d3f6a\"" Nov 8 00:06:38.061026 systemd[1]: Started cri-containerd-55a11cddbfe40a1ac32c90a0cc6b85269bfa64f3319e897ab8ecf290b91d3f6a.scope - libcontainer container 55a11cddbfe40a1ac32c90a0cc6b85269bfa64f3319e897ab8ecf290b91d3f6a. Nov 8 00:06:38.105941 containerd[2009]: time="2025-11-08T00:06:38.105837589Z" level=info msg="StartContainer for \"55a11cddbfe40a1ac32c90a0cc6b85269bfa64f3319e897ab8ecf290b91d3f6a\" returns successfully" Nov 8 00:06:38.175242 kubelet[2429]: E1108 00:06:38.175159 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:38.429468 kubelet[2429]: I1108 00:06:38.429379 2429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-2mwnq" podStartSLOduration=1.94643484 podStartE2EDuration="6.429361166s" podCreationTimestamp="2025-11-08 00:06:32 +0000 UTC" firstStartedPulling="2025-11-08 00:06:33.495808006 +0000 UTC m=+14.148320940" lastFinishedPulling="2025-11-08 00:06:37.978734344 +0000 UTC m=+18.631247266" observedRunningTime="2025-11-08 00:06:38.428797202 +0000 UTC m=+19.081310160" watchObservedRunningTime="2025-11-08 00:06:38.429361166 +0000 UTC m=+19.081874124" Nov 8 00:06:39.175608 kubelet[2429]: E1108 00:06:39.175548 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:40.161410 kubelet[2429]: E1108 00:06:40.161339 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:40.176228 kubelet[2429]: E1108 00:06:40.176183 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:40.746741 systemd[1]: Created slice kubepods-besteffort-podba6a1184_eea7_4028_a21e_d80c2e6343db.slice - libcontainer container kubepods-besteffort-podba6a1184_eea7_4028_a21e_d80c2e6343db.slice. Nov 8 00:06:40.845756 kubelet[2429]: I1108 00:06:40.845668 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-448pj\" (UniqueName: \"kubernetes.io/projected/ba6a1184-eea7-4028-a21e-d80c2e6343db-kube-api-access-448pj\") pod \"nfs-server-provisioner-0\" (UID: \"ba6a1184-eea7-4028-a21e-d80c2e6343db\") " pod="default/nfs-server-provisioner-0" Nov 8 00:06:40.845919 kubelet[2429]: I1108 00:06:40.845764 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/ba6a1184-eea7-4028-a21e-d80c2e6343db-data\") pod \"nfs-server-provisioner-0\" (UID: \"ba6a1184-eea7-4028-a21e-d80c2e6343db\") " pod="default/nfs-server-provisioner-0" Nov 8 00:06:41.051975 containerd[2009]: time="2025-11-08T00:06:41.051810591Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:ba6a1184-eea7-4028-a21e-d80c2e6343db,Namespace:default,Attempt:0,}" Nov 8 00:06:41.088859 (udev-worker)[3180]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:41.090644 systemd-networkd[1937]: veth07908042: Link UP Nov 8 00:06:41.095023 kernel: cni0: port 2(veth07908042) entered blocking state Nov 8 00:06:41.095400 kernel: cni0: port 2(veth07908042) entered disabled state Nov 8 00:06:41.095570 kernel: veth07908042: entered allmulticast mode Nov 8 00:06:41.097638 kernel: veth07908042: entered promiscuous mode Nov 8 00:06:41.109815 kernel: cni0: port 2(veth07908042) entered blocking state Nov 8 00:06:41.109892 kernel: cni0: port 2(veth07908042) entered forwarding state Nov 8 00:06:41.112244 systemd-networkd[1937]: veth07908042: Gained carrier Nov 8 00:06:41.118061 containerd[2009]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000082950), "name":"cbr0", "type":"bridge"} Nov 8 00:06:41.118061 containerd[2009]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:06:41.153903 containerd[2009]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:06:41.153335008Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:41.153903 containerd[2009]: time="2025-11-08T00:06:41.153418228Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:41.153903 containerd[2009]: time="2025-11-08T00:06:41.153442936Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:41.153903 containerd[2009]: time="2025-11-08T00:06:41.153599968Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:41.177109 kubelet[2429]: E1108 00:06:41.177035 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:41.200025 systemd[1]: Started cri-containerd-15eb106bb97edb00149c6834abd975282cc0d2f7ec5c8569cdae1d0f736a4746.scope - libcontainer container 15eb106bb97edb00149c6834abd975282cc0d2f7ec5c8569cdae1d0f736a4746. Nov 8 00:06:41.257317 containerd[2009]: time="2025-11-08T00:06:41.257172916Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:ba6a1184-eea7-4028-a21e-d80c2e6343db,Namespace:default,Attempt:0,} returns sandbox id \"15eb106bb97edb00149c6834abd975282cc0d2f7ec5c8569cdae1d0f736a4746\"" Nov 8 00:06:41.261144 containerd[2009]: time="2025-11-08T00:06:41.261079804Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 8 00:06:42.178465 kubelet[2429]: E1108 00:06:42.178396 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:42.590800 systemd-networkd[1937]: veth07908042: Gained IPv6LL Nov 8 00:06:43.179124 kubelet[2429]: E1108 00:06:43.178902 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:43.394402 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 8 00:06:43.847333 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2123126743.mount: Deactivated successfully. Nov 8 00:06:44.179620 kubelet[2429]: E1108 00:06:44.179461 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:45.180544 kubelet[2429]: E1108 00:06:45.180425 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:45.381353 ntpd[1991]: Listen normally on 12 veth07908042 [fe80::3c5d:1ff:fe91:edc6%6]:123 Nov 8 00:06:45.382148 ntpd[1991]: 8 Nov 00:06:45 ntpd[1991]: Listen normally on 12 veth07908042 [fe80::3c5d:1ff:fe91:edc6%6]:123 Nov 8 00:06:46.181362 kubelet[2429]: E1108 00:06:46.181318 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:46.785640 containerd[2009]: time="2025-11-08T00:06:46.784772844Z" 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:46.787489 containerd[2009]: time="2025-11-08T00:06:46.787421340Z" 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:46.790388 containerd[2009]: time="2025-11-08T00:06:46.790307544Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:46.798727 containerd[2009]: time="2025-11-08T00:06:46.797615904Z" 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:46.799291 containerd[2009]: time="2025-11-08T00:06:46.799243848Z" 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.538098824s" Nov 8 00:06:46.799455 containerd[2009]: time="2025-11-08T00:06:46.799420584Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 8 00:06:46.808177 containerd[2009]: time="2025-11-08T00:06:46.808106268Z" level=info msg="CreateContainer within sandbox \"15eb106bb97edb00149c6834abd975282cc0d2f7ec5c8569cdae1d0f736a4746\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 8 00:06:46.849836 containerd[2009]: time="2025-11-08T00:06:46.849772260Z" level=info msg="CreateContainer within sandbox \"15eb106bb97edb00149c6834abd975282cc0d2f7ec5c8569cdae1d0f736a4746\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"2d6e89ee0d0b107b5f9bf214a8554244c670cb7c8efccd9b3ec9d0405c09abb3\"" Nov 8 00:06:46.851153 containerd[2009]: time="2025-11-08T00:06:46.851022636Z" level=info msg="StartContainer for \"2d6e89ee0d0b107b5f9bf214a8554244c670cb7c8efccd9b3ec9d0405c09abb3\"" Nov 8 00:06:46.909007 systemd[1]: Started cri-containerd-2d6e89ee0d0b107b5f9bf214a8554244c670cb7c8efccd9b3ec9d0405c09abb3.scope - libcontainer container 2d6e89ee0d0b107b5f9bf214a8554244c670cb7c8efccd9b3ec9d0405c09abb3. Nov 8 00:06:46.953117 containerd[2009]: time="2025-11-08T00:06:46.953049840Z" level=info msg="StartContainer for \"2d6e89ee0d0b107b5f9bf214a8554244c670cb7c8efccd9b3ec9d0405c09abb3\" returns successfully" Nov 8 00:06:47.182187 kubelet[2429]: E1108 00:06:47.182113 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:47.459326 kubelet[2429]: I1108 00:06:47.459095 2429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.918475543 podStartE2EDuration="7.459076571s" podCreationTimestamp="2025-11-08 00:06:40 +0000 UTC" firstStartedPulling="2025-11-08 00:06:41.260671108 +0000 UTC m=+21.913184066" lastFinishedPulling="2025-11-08 00:06:46.80127216 +0000 UTC m=+27.453785094" observedRunningTime="2025-11-08 00:06:47.457964591 +0000 UTC m=+28.110477525" watchObservedRunningTime="2025-11-08 00:06:47.459076571 +0000 UTC m=+28.111589505" Nov 8 00:06:48.182548 kubelet[2429]: E1108 00:06:48.182488 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:49.182945 kubelet[2429]: E1108 00:06:49.182870 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:50.184018 kubelet[2429]: E1108 00:06:50.183959 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:51.185048 kubelet[2429]: E1108 00:06:51.184973 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:52.185963 kubelet[2429]: E1108 00:06:52.185903 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:52.368390 systemd[1]: Created slice kubepods-besteffort-pod5ade6b48_7766_406b_b7aa_8dd473d860fd.slice - libcontainer container kubepods-besteffort-pod5ade6b48_7766_406b_b7aa_8dd473d860fd.slice. Nov 8 00:06:52.419370 kubelet[2429]: I1108 00:06:52.419245 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c9ef93e4-afd5-437a-8567-6af7384b77b6\" (UniqueName: \"kubernetes.io/nfs/5ade6b48-7766-406b-b7aa-8dd473d860fd-pvc-c9ef93e4-afd5-437a-8567-6af7384b77b6\") pod \"test-pod-1\" (UID: \"5ade6b48-7766-406b-b7aa-8dd473d860fd\") " pod="default/test-pod-1" Nov 8 00:06:52.419370 kubelet[2429]: I1108 00:06:52.419315 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gqcg9\" (UniqueName: \"kubernetes.io/projected/5ade6b48-7766-406b-b7aa-8dd473d860fd-kube-api-access-gqcg9\") pod \"test-pod-1\" (UID: \"5ade6b48-7766-406b-b7aa-8dd473d860fd\") " pod="default/test-pod-1" Nov 8 00:06:52.554738 kernel: FS-Cache: Loaded Nov 8 00:06:52.598259 kernel: RPC: Registered named UNIX socket transport module. Nov 8 00:06:52.598403 kernel: RPC: Registered udp transport module. Nov 8 00:06:52.598447 kernel: RPC: Registered tcp transport module. Nov 8 00:06:52.599212 kernel: RPC: Registered tcp-with-tls transport module. Nov 8 00:06:52.600293 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 8 00:06:52.929606 kernel: NFS: Registering the id_resolver key type Nov 8 00:06:52.929795 kernel: Key type id_resolver registered Nov 8 00:06:52.929844 kernel: Key type id_legacy registered Nov 8 00:06:52.968405 nfsidmap[3437]: 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:52.974335 nfsidmap[3438]: 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:53.186647 kubelet[2429]: E1108 00:06:53.186499 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:53.276181 containerd[2009]: time="2025-11-08T00:06:53.276063916Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5ade6b48-7766-406b-b7aa-8dd473d860fd,Namespace:default,Attempt:0,}" Nov 8 00:06:53.322940 (udev-worker)[3404]: Network interface NamePolicy= disabled on kernel command line. Nov 8 00:06:53.324863 systemd-networkd[1937]: veth1e284ae7: Link UP Nov 8 00:06:53.333275 kernel: cni0: port 3(veth1e284ae7) entered blocking state Nov 8 00:06:53.333398 kernel: cni0: port 3(veth1e284ae7) entered disabled state Nov 8 00:06:53.333452 kernel: veth1e284ae7: entered allmulticast mode Nov 8 00:06:53.334845 kernel: veth1e284ae7: entered promiscuous mode Nov 8 00:06:53.351027 kernel: cni0: port 3(veth1e284ae7) entered blocking state Nov 8 00:06:53.351183 kernel: cni0: port 3(veth1e284ae7) entered forwarding state Nov 8 00:06:53.351448 systemd-networkd[1937]: veth1e284ae7: Gained carrier Nov 8 00:06:53.355837 containerd[2009]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Nov 8 00:06:53.355837 containerd[2009]: delegateAdd: netconf sent to delegate plugin: Nov 8 00:06:53.397549 containerd[2009]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-08T00:06:53.397384720Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 8 00:06:53.397976 containerd[2009]: time="2025-11-08T00:06:53.397474984Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 8 00:06:53.397976 containerd[2009]: time="2025-11-08T00:06:53.397875544Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:53.398502 containerd[2009]: time="2025-11-08T00:06:53.398383408Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 8 00:06:53.430031 systemd[1]: Started cri-containerd-46f29fbb087036592b68e4313ca8ab54085e9b103b9f6f8bf99264d54d1083fd.scope - libcontainer container 46f29fbb087036592b68e4313ca8ab54085e9b103b9f6f8bf99264d54d1083fd. Nov 8 00:06:53.498598 containerd[2009]: time="2025-11-08T00:06:53.498514805Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5ade6b48-7766-406b-b7aa-8dd473d860fd,Namespace:default,Attempt:0,} returns sandbox id \"46f29fbb087036592b68e4313ca8ab54085e9b103b9f6f8bf99264d54d1083fd\"" Nov 8 00:06:53.502518 containerd[2009]: time="2025-11-08T00:06:53.502170701Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 8 00:06:53.861826 containerd[2009]: time="2025-11-08T00:06:53.861390955Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 8 00:06:53.863540 containerd[2009]: time="2025-11-08T00:06:53.863464627Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 8 00:06:53.871741 containerd[2009]: time="2025-11-08T00:06:53.870881935Z" 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 368.648642ms" Nov 8 00:06:53.871741 containerd[2009]: time="2025-11-08T00:06:53.870967747Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 8 00:06:53.883516 containerd[2009]: time="2025-11-08T00:06:53.883458811Z" level=info msg="CreateContainer within sandbox \"46f29fbb087036592b68e4313ca8ab54085e9b103b9f6f8bf99264d54d1083fd\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 8 00:06:53.914076 containerd[2009]: time="2025-11-08T00:06:53.913895911Z" level=info msg="CreateContainer within sandbox \"46f29fbb087036592b68e4313ca8ab54085e9b103b9f6f8bf99264d54d1083fd\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"c1b0127344b067968c81369ee7ae12b975ac94bac97158117837dd7c017e5944\"" Nov 8 00:06:53.915003 containerd[2009]: time="2025-11-08T00:06:53.914951047Z" level=info msg="StartContainer for \"c1b0127344b067968c81369ee7ae12b975ac94bac97158117837dd7c017e5944\"" Nov 8 00:06:53.969026 systemd[1]: Started cri-containerd-c1b0127344b067968c81369ee7ae12b975ac94bac97158117837dd7c017e5944.scope - libcontainer container c1b0127344b067968c81369ee7ae12b975ac94bac97158117837dd7c017e5944. Nov 8 00:06:54.016999 containerd[2009]: time="2025-11-08T00:06:54.016831540Z" level=info msg="StartContainer for \"c1b0127344b067968c81369ee7ae12b975ac94bac97158117837dd7c017e5944\" returns successfully" Nov 8 00:06:54.186942 kubelet[2429]: E1108 00:06:54.186883 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:54.481885 kubelet[2429]: I1108 00:06:54.481504 2429 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=13.108890728 podStartE2EDuration="13.481484766s" podCreationTimestamp="2025-11-08 00:06:41 +0000 UTC" firstStartedPulling="2025-11-08 00:06:53.501248081 +0000 UTC m=+34.153761015" lastFinishedPulling="2025-11-08 00:06:53.873842131 +0000 UTC m=+34.526355053" observedRunningTime="2025-11-08 00:06:54.481248006 +0000 UTC m=+35.133760952" watchObservedRunningTime="2025-11-08 00:06:54.481484766 +0000 UTC m=+35.133997700" Nov 8 00:06:55.187732 kubelet[2429]: E1108 00:06:55.187660 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:55.195953 systemd-networkd[1937]: veth1e284ae7: Gained IPv6LL Nov 8 00:06:56.188492 kubelet[2429]: E1108 00:06:56.188425 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:57.189423 kubelet[2429]: E1108 00:06:57.189360 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:57.720973 update_engine[1998]: I20251108 00:06:57.720885 1998 update_attempter.cc:509] Updating boot flags... Nov 8 00:06:57.816951 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (3586) Nov 8 00:06:58.079864 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 37 scanned by (udev-worker) (3587) Nov 8 00:06:58.191555 kubelet[2429]: E1108 00:06:58.191495 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:59.191910 kubelet[2429]: E1108 00:06:59.191853 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:06:59.381360 ntpd[1991]: Listen normally on 13 veth1e284ae7 [fe80::ac2e:3ff:fea7:abd2%7]:123 Nov 8 00:06:59.382256 ntpd[1991]: 8 Nov 00:06:59 ntpd[1991]: Listen normally on 13 veth1e284ae7 [fe80::ac2e:3ff:fea7:abd2%7]:123 Nov 8 00:07:00.161652 kubelet[2429]: E1108 00:07:00.161582 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:00.192337 kubelet[2429]: E1108 00:07:00.192300 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:01.193271 kubelet[2429]: E1108 00:07:01.193214 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:02.194104 kubelet[2429]: E1108 00:07:02.194035 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:03.194519 kubelet[2429]: E1108 00:07:03.194454 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:04.194726 kubelet[2429]: E1108 00:07:04.194656 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:05.195762 kubelet[2429]: E1108 00:07:05.195678 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:06.196186 kubelet[2429]: E1108 00:07:06.196131 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:07.196839 kubelet[2429]: E1108 00:07:07.196781 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:08.197006 kubelet[2429]: E1108 00:07:08.196938 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:09.197554 kubelet[2429]: E1108 00:07:09.197494 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:10.197641 kubelet[2429]: E1108 00:07:10.197583 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:11.198283 kubelet[2429]: E1108 00:07:11.198216 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:12.199148 kubelet[2429]: E1108 00:07:12.199090 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:13.199837 kubelet[2429]: E1108 00:07:13.199772 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:14.200224 kubelet[2429]: E1108 00:07:14.200162 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:15.200610 kubelet[2429]: E1108 00:07:15.200545 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:16.201272 kubelet[2429]: E1108 00:07:16.201203 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:17.202349 kubelet[2429]: E1108 00:07:17.202282 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:18.202868 kubelet[2429]: E1108 00:07:18.202806 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:19.203223 kubelet[2429]: E1108 00:07:19.203164 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:20.161595 kubelet[2429]: E1108 00:07:20.161539 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:20.204168 kubelet[2429]: E1108 00:07:20.204118 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:20.917895 kubelet[2429]: E1108 00:07:20.917832 2429 controller.go:195] "Failed to update lease" err="the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io 172.31.24.87)" Nov 8 00:07:21.204862 kubelet[2429]: E1108 00:07:21.204721 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:22.205672 kubelet[2429]: E1108 00:07:22.205598 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:23.206802 kubelet[2429]: E1108 00:07:23.206742 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:24.207822 kubelet[2429]: E1108 00:07:24.207764 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:25.208836 kubelet[2429]: E1108 00:07:25.208775 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:26.209483 kubelet[2429]: E1108 00:07:26.209418 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:27.210513 kubelet[2429]: E1108 00:07:27.210445 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:28.211316 kubelet[2429]: E1108 00:07:28.211252 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:29.212471 kubelet[2429]: E1108 00:07:29.212406 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:30.213190 kubelet[2429]: E1108 00:07:30.213127 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:30.914727 kubelet[2429]: E1108 00:07:30.914410 2429 controller.go:195] "Failed to update lease" err="the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io 172.31.24.87)" Nov 8 00:07:31.214002 kubelet[2429]: E1108 00:07:31.213888 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:32.215306 kubelet[2429]: E1108 00:07:32.215237 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:33.215482 kubelet[2429]: E1108 00:07:33.215404 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:34.216485 kubelet[2429]: E1108 00:07:34.216430 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:35.217241 kubelet[2429]: E1108 00:07:35.217176 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:36.218373 kubelet[2429]: E1108 00:07:36.218314 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:36.611886 kubelet[2429]: E1108 00:07:36.610659 2429 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": unexpected EOF" Nov 8 00:07:36.616908 kubelet[2429]: E1108 00:07:36.616629 2429 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": read tcp 172.31.24.87:43082->172.31.22.72:6443: read: connection reset by peer" Nov 8 00:07:36.619930 kubelet[2429]: E1108 00:07:36.619286 2429 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": dial tcp 172.31.22.72:6443: connect: connection refused" Nov 8 00:07:36.619930 kubelet[2429]: I1108 00:07:36.619365 2429 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:36.620905 kubelet[2429]: E1108 00:07:36.620233 2429 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": dial tcp 172.31.22.72:6443: connect: connection refused" interval="200ms" Nov 8 00:07:36.821799 kubelet[2429]: E1108 00:07:36.821675 2429 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": dial tcp 172.31.22.72:6443: connect: connection refused" interval="400ms" Nov 8 00:07:37.219047 kubelet[2429]: E1108 00:07:37.218983 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:37.222928 kubelet[2429]: E1108 00:07:37.222863 2429 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": dial tcp 172.31.22.72:6443: connect: connection refused" interval="800ms" Nov 8 00:07:38.219236 kubelet[2429]: E1108 00:07:38.219178 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:39.220178 kubelet[2429]: E1108 00:07:39.220063 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:40.161646 kubelet[2429]: E1108 00:07:40.161584 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:40.220501 kubelet[2429]: E1108 00:07:40.220454 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:41.221032 kubelet[2429]: E1108 00:07:41.220973 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:42.221756 kubelet[2429]: E1108 00:07:42.221667 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:43.222840 kubelet[2429]: E1108 00:07:43.222757 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:44.223494 kubelet[2429]: E1108 00:07:44.223433 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:45.224445 kubelet[2429]: E1108 00:07:45.224389 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:46.225374 kubelet[2429]: E1108 00:07:46.225318 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:47.225949 kubelet[2429]: E1108 00:07:47.225891 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:48.024239 kubelet[2429]: E1108 00:07:48.024165 2429 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.72:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.87?timeout=10s\": context deadline exceeded" interval="1.6s" Nov 8 00:07:48.226830 kubelet[2429]: E1108 00:07:48.226788 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:49.227422 kubelet[2429]: E1108 00:07:49.227361 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:50.228508 kubelet[2429]: E1108 00:07:50.228457 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:51.229637 kubelet[2429]: E1108 00:07:51.229582 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:52.230304 kubelet[2429]: E1108 00:07:52.230248 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:53.231029 kubelet[2429]: E1108 00:07:53.230968 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:54.231307 kubelet[2429]: E1108 00:07:54.231247 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 8 00:07:55.231656 kubelet[2429]: E1108 00:07:55.231601 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"