Apr 24 23:38:34.296004 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Apr 24 23:38:34.296052 kernel: Linux version 6.6.127-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 Apr 24 22:19:35 -00 2026 Apr 24 23:38:34.296078 kernel: KASLR disabled due to lack of seed Apr 24 23:38:34.296096 kernel: efi: EFI v2.7 by EDK II Apr 24 23:38:34.296112 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b001a98 MEMRESERVE=0x7852ee18 Apr 24 23:38:34.296128 kernel: ACPI: Early table checksum verification disabled Apr 24 23:38:34.296146 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Apr 24 23:38:34.296162 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Apr 24 23:38:34.296178 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Apr 24 23:38:34.296194 kernel: ACPI: DSDT 0x0000000078640000 0013D2 (v02 AMAZON AMZNDSDT 00000001 AMZN 00000001) Apr 24 23:38:34.296215 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Apr 24 23:38:34.296231 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Apr 24 23:38:34.296247 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Apr 24 23:38:34.296264 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Apr 24 23:38:34.296282 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Apr 24 23:38:34.296303 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Apr 24 23:38:34.296321 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Apr 24 23:38:34.296338 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Apr 24 23:38:34.296354 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Apr 24 23:38:34.296371 kernel: printk: bootconsole [uart0] enabled Apr 24 23:38:34.296388 kernel: NUMA: Failed to initialise from firmware Apr 24 23:38:34.296405 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Apr 24 23:38:34.296422 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Apr 24 23:38:34.296439 kernel: Zone ranges: Apr 24 23:38:34.296456 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Apr 24 23:38:34.296472 kernel: DMA32 empty Apr 24 23:38:34.296493 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Apr 24 23:38:34.296511 kernel: Movable zone start for each node Apr 24 23:38:34.296527 kernel: Early memory node ranges Apr 24 23:38:34.296544 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Apr 24 23:38:34.296561 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Apr 24 23:38:34.296578 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Apr 24 23:38:34.296595 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Apr 24 23:38:34.296611 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Apr 24 23:38:34.296628 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Apr 24 23:38:34.296645 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Apr 24 23:38:34.296661 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Apr 24 23:38:34.296678 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Apr 24 23:38:34.296699 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Apr 24 23:38:34.296717 kernel: psci: probing for conduit method from ACPI. Apr 24 23:38:34.296741 kernel: psci: PSCIv1.0 detected in firmware. Apr 24 23:38:34.296759 kernel: psci: Using standard PSCI v0.2 function IDs Apr 24 23:38:34.296777 kernel: psci: Trusted OS migration not required Apr 24 23:38:34.296798 kernel: psci: SMC Calling Convention v1.1 Apr 24 23:38:34.296817 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Apr 24 23:38:34.296835 kernel: percpu: Embedded 30 pages/cpu s85736 r8192 d28952 u122880 Apr 24 23:38:34.296853 kernel: pcpu-alloc: s85736 r8192 d28952 u122880 alloc=30*4096 Apr 24 23:38:34.296871 kernel: pcpu-alloc: [0] 0 [0] 1 Apr 24 23:38:34.296888 kernel: Detected PIPT I-cache on CPU0 Apr 24 23:38:34.296906 kernel: CPU features: detected: GIC system register CPU interface Apr 24 23:38:34.296924 kernel: CPU features: detected: Spectre-v2 Apr 24 23:38:34.298377 kernel: CPU features: detected: Spectre-v3a Apr 24 23:38:34.298400 kernel: CPU features: detected: Spectre-BHB Apr 24 23:38:34.298419 kernel: CPU features: detected: ARM erratum 1742098 Apr 24 23:38:34.298445 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Apr 24 23:38:34.298464 kernel: alternatives: applying boot alternatives Apr 24 23:38:34.298485 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=63304dd98a277d4592d17e0085ae3f91ca70cc8ec6dedfdd357a1e9755f9a8b3 Apr 24 23:38:34.298504 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 24 23:38:34.298522 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 24 23:38:34.298540 kernel: Fallback order for Node 0: 0 Apr 24 23:38:34.298558 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Apr 24 23:38:34.298576 kernel: Policy zone: Normal Apr 24 23:38:34.298593 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 24 23:38:34.298611 kernel: software IO TLB: area num 2. Apr 24 23:38:34.298648 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Apr 24 23:38:34.298677 kernel: Memory: 3820096K/4030464K available (10304K kernel code, 2180K rwdata, 8116K rodata, 39424K init, 897K bss, 210368K reserved, 0K cma-reserved) Apr 24 23:38:34.298695 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 24 23:38:34.298714 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 24 23:38:34.298732 kernel: rcu: RCU event tracing is enabled. Apr 24 23:38:34.298751 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 24 23:38:34.298770 kernel: Trampoline variant of Tasks RCU enabled. Apr 24 23:38:34.298788 kernel: Tracing variant of Tasks RCU enabled. Apr 24 23:38:34.298806 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 24 23:38:34.298824 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 24 23:38:34.298842 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 24 23:38:34.298859 kernel: GICv3: 96 SPIs implemented Apr 24 23:38:34.298882 kernel: GICv3: 0 Extended SPIs implemented Apr 24 23:38:34.298900 kernel: Root IRQ handler: gic_handle_irq Apr 24 23:38:34.298917 kernel: GICv3: GICv3 features: 16 PPIs Apr 24 23:38:34.298957 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Apr 24 23:38:34.298979 kernel: ITS [mem 0x10080000-0x1009ffff] Apr 24 23:38:34.298998 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Apr 24 23:38:34.299016 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Apr 24 23:38:34.299034 kernel: GICv3: using LPI property table @0x00000004000d0000 Apr 24 23:38:34.299052 kernel: ITS: Using hypervisor restricted LPI range [128] Apr 24 23:38:34.299070 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Apr 24 23:38:34.299088 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 24 23:38:34.299105 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Apr 24 23:38:34.299130 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Apr 24 23:38:34.299148 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Apr 24 23:38:34.299166 kernel: Console: colour dummy device 80x25 Apr 24 23:38:34.299184 kernel: printk: console [tty1] enabled Apr 24 23:38:34.299202 kernel: ACPI: Core revision 20230628 Apr 24 23:38:34.299221 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Apr 24 23:38:34.299239 kernel: pid_max: default: 32768 minimum: 301 Apr 24 23:38:34.299257 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 24 23:38:34.299275 kernel: landlock: Up and running. Apr 24 23:38:34.299297 kernel: SELinux: Initializing. Apr 24 23:38:34.299316 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 24 23:38:34.299334 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 24 23:38:34.299352 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 24 23:38:34.299370 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 24 23:38:34.299389 kernel: rcu: Hierarchical SRCU implementation. Apr 24 23:38:34.299407 kernel: rcu: Max phase no-delay instances is 400. Apr 24 23:38:34.299426 kernel: Platform MSI: ITS@0x10080000 domain created Apr 24 23:38:34.299444 kernel: PCI/MSI: ITS@0x10080000 domain created Apr 24 23:38:34.299467 kernel: Remapping and enabling EFI services. Apr 24 23:38:34.299485 kernel: smp: Bringing up secondary CPUs ... Apr 24 23:38:34.299503 kernel: Detected PIPT I-cache on CPU1 Apr 24 23:38:34.299522 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Apr 24 23:38:34.299540 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Apr 24 23:38:34.299558 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Apr 24 23:38:34.299578 kernel: smp: Brought up 1 node, 2 CPUs Apr 24 23:38:34.304971 kernel: SMP: Total of 2 processors activated. Apr 24 23:38:34.305018 kernel: CPU features: detected: 32-bit EL0 Support Apr 24 23:38:34.305046 kernel: CPU features: detected: 32-bit EL1 Support Apr 24 23:38:34.305067 kernel: CPU features: detected: CRC32 instructions Apr 24 23:38:34.305086 kernel: CPU: All CPU(s) started at EL1 Apr 24 23:38:34.305116 kernel: alternatives: applying system-wide alternatives Apr 24 23:38:34.305139 kernel: devtmpfs: initialized Apr 24 23:38:34.305158 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 24 23:38:34.305178 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 24 23:38:34.305197 kernel: pinctrl core: initialized pinctrl subsystem Apr 24 23:38:34.305216 kernel: SMBIOS 3.0.0 present. Apr 24 23:38:34.305240 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Apr 24 23:38:34.305259 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 24 23:38:34.305278 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 24 23:38:34.305298 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 24 23:38:34.305317 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 24 23:38:34.305336 kernel: audit: initializing netlink subsys (disabled) Apr 24 23:38:34.305355 kernel: audit: type=2000 audit(0.288:1): state=initialized audit_enabled=0 res=1 Apr 24 23:38:34.305374 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 24 23:38:34.305398 kernel: cpuidle: using governor menu Apr 24 23:38:34.305417 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 24 23:38:34.305436 kernel: ASID allocator initialised with 65536 entries Apr 24 23:38:34.305455 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 24 23:38:34.305474 kernel: Serial: AMBA PL011 UART driver Apr 24 23:38:34.305493 kernel: Modules: 17488 pages in range for non-PLT usage Apr 24 23:38:34.305512 kernel: Modules: 509008 pages in range for PLT usage Apr 24 23:38:34.305531 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 24 23:38:34.305550 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 24 23:38:34.305573 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 24 23:38:34.305593 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 24 23:38:34.305612 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 24 23:38:34.305631 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 24 23:38:34.305651 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 24 23:38:34.305670 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 24 23:38:34.305689 kernel: ACPI: Added _OSI(Module Device) Apr 24 23:38:34.305708 kernel: ACPI: Added _OSI(Processor Device) Apr 24 23:38:34.305727 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 24 23:38:34.305750 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 24 23:38:34.305769 kernel: ACPI: Interpreter enabled Apr 24 23:38:34.305788 kernel: ACPI: Using GIC for interrupt routing Apr 24 23:38:34.305807 kernel: ACPI: MCFG table detected, 1 entries Apr 24 23:38:34.305826 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00]) Apr 24 23:38:34.306200 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 24 23:38:34.306428 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 24 23:38:34.306663 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 24 23:38:34.306889 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x200fffff] reserved by PNP0C02:00 Apr 24 23:38:34.307419 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x200fffff] for [bus 00] Apr 24 23:38:34.307459 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Apr 24 23:38:34.307481 kernel: acpiphp: Slot [1] registered Apr 24 23:38:34.307502 kernel: acpiphp: Slot [2] registered Apr 24 23:38:34.307521 kernel: acpiphp: Slot [3] registered Apr 24 23:38:34.307542 kernel: acpiphp: Slot [4] registered Apr 24 23:38:34.307562 kernel: acpiphp: Slot [5] registered Apr 24 23:38:34.307594 kernel: acpiphp: Slot [6] registered Apr 24 23:38:34.307614 kernel: acpiphp: Slot [7] registered Apr 24 23:38:34.307633 kernel: acpiphp: Slot [8] registered Apr 24 23:38:34.307653 kernel: acpiphp: Slot [9] registered Apr 24 23:38:34.307673 kernel: acpiphp: Slot [10] registered Apr 24 23:38:34.307694 kernel: acpiphp: Slot [11] registered Apr 24 23:38:34.307714 kernel: acpiphp: Slot [12] registered Apr 24 23:38:34.307734 kernel: acpiphp: Slot [13] registered Apr 24 23:38:34.307753 kernel: acpiphp: Slot [14] registered Apr 24 23:38:34.307773 kernel: acpiphp: Slot [15] registered Apr 24 23:38:34.307798 kernel: acpiphp: Slot [16] registered Apr 24 23:38:34.307819 kernel: acpiphp: Slot [17] registered Apr 24 23:38:34.307838 kernel: acpiphp: Slot [18] registered Apr 24 23:38:34.307857 kernel: acpiphp: Slot [19] registered Apr 24 23:38:34.307877 kernel: acpiphp: Slot [20] registered Apr 24 23:38:34.307897 kernel: acpiphp: Slot [21] registered Apr 24 23:38:34.307916 kernel: acpiphp: Slot [22] registered Apr 24 23:38:34.307981 kernel: acpiphp: Slot [23] registered Apr 24 23:38:34.308006 kernel: acpiphp: Slot [24] registered Apr 24 23:38:34.308032 kernel: acpiphp: Slot [25] registered Apr 24 23:38:34.308052 kernel: acpiphp: Slot [26] registered Apr 24 23:38:34.308071 kernel: acpiphp: Slot [27] registered Apr 24 23:38:34.308089 kernel: acpiphp: Slot [28] registered Apr 24 23:38:34.308108 kernel: acpiphp: Slot [29] registered Apr 24 23:38:34.308127 kernel: acpiphp: Slot [30] registered Apr 24 23:38:34.308146 kernel: acpiphp: Slot [31] registered Apr 24 23:38:34.308165 kernel: PCI host bridge to bus 0000:00 Apr 24 23:38:34.308426 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Apr 24 23:38:34.308629 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 24 23:38:34.308822 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Apr 24 23:38:34.313784 kernel: pci_bus 0000:00: root bus resource [bus 00] Apr 24 23:38:34.314079 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Apr 24 23:38:34.314321 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Apr 24 23:38:34.314541 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Apr 24 23:38:34.314814 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Apr 24 23:38:34.315082 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Apr 24 23:38:34.317657 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 24 23:38:34.317912 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Apr 24 23:38:34.318176 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Apr 24 23:38:34.318415 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Apr 24 23:38:34.318667 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Apr 24 23:38:34.318910 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 24 23:38:34.319277 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Apr 24 23:38:34.319476 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 24 23:38:34.319672 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Apr 24 23:38:34.319698 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 24 23:38:34.319718 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 24 23:38:34.319738 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 24 23:38:34.319757 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 24 23:38:34.319784 kernel: iommu: Default domain type: Translated Apr 24 23:38:34.319804 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 24 23:38:34.319823 kernel: efivars: Registered efivars operations Apr 24 23:38:34.319842 kernel: vgaarb: loaded Apr 24 23:38:34.319860 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 24 23:38:34.319879 kernel: VFS: Disk quotas dquot_6.6.0 Apr 24 23:38:34.319899 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 24 23:38:34.319918 kernel: pnp: PnP ACPI init Apr 24 23:38:34.320193 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Apr 24 23:38:34.320230 kernel: pnp: PnP ACPI: found 1 devices Apr 24 23:38:34.320250 kernel: NET: Registered PF_INET protocol family Apr 24 23:38:34.320270 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 24 23:38:34.320289 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 24 23:38:34.320308 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 24 23:38:34.320328 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 24 23:38:34.320347 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 24 23:38:34.320366 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 24 23:38:34.320390 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 24 23:38:34.320409 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 24 23:38:34.320429 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 24 23:38:34.320448 kernel: PCI: CLS 0 bytes, default 64 Apr 24 23:38:34.320467 kernel: kvm [1]: HYP mode not available Apr 24 23:38:34.320486 kernel: Initialise system trusted keyrings Apr 24 23:38:34.320504 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 24 23:38:34.320523 kernel: Key type asymmetric registered Apr 24 23:38:34.320542 kernel: Asymmetric key parser 'x509' registered Apr 24 23:38:34.320565 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 24 23:38:34.320585 kernel: io scheduler mq-deadline registered Apr 24 23:38:34.320604 kernel: io scheduler kyber registered Apr 24 23:38:34.320622 kernel: io scheduler bfq registered Apr 24 23:38:34.320851 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Apr 24 23:38:34.320879 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 24 23:38:34.320898 kernel: ACPI: button: Power Button [PWRB] Apr 24 23:38:34.320918 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Apr 24 23:38:34.323058 kernel: ACPI: button: Sleep Button [SLPB] Apr 24 23:38:34.323102 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 24 23:38:34.323125 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Apr 24 23:38:34.323418 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Apr 24 23:38:34.323447 kernel: printk: console [ttyS0] disabled Apr 24 23:38:34.323468 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Apr 24 23:38:34.323487 kernel: printk: console [ttyS0] enabled Apr 24 23:38:34.323507 kernel: printk: bootconsole [uart0] disabled Apr 24 23:38:34.323526 kernel: thunder_xcv, ver 1.0 Apr 24 23:38:34.323544 kernel: thunder_bgx, ver 1.0 Apr 24 23:38:34.323570 kernel: nicpf, ver 1.0 Apr 24 23:38:34.323590 kernel: nicvf, ver 1.0 Apr 24 23:38:34.323812 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 24 23:38:34.324063 kernel: rtc-efi rtc-efi.0: setting system clock to 2026-04-24T23:38:33 UTC (1777073913) Apr 24 23:38:34.324093 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 24 23:38:34.324114 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Apr 24 23:38:34.324134 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 24 23:38:34.324153 kernel: watchdog: Hard watchdog permanently disabled Apr 24 23:38:34.324181 kernel: NET: Registered PF_INET6 protocol family Apr 24 23:38:34.324201 kernel: Segment Routing with IPv6 Apr 24 23:38:34.324221 kernel: In-situ OAM (IOAM) with IPv6 Apr 24 23:38:34.324239 kernel: NET: Registered PF_PACKET protocol family Apr 24 23:38:34.324258 kernel: Key type dns_resolver registered Apr 24 23:38:34.324278 kernel: registered taskstats version 1 Apr 24 23:38:34.324297 kernel: Loading compiled-in X.509 certificates Apr 24 23:38:34.324317 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.127-flatcar: 96a6e7da7ac9a3ef656057ccd8e13f251b310c24' Apr 24 23:38:34.324336 kernel: Key type .fscrypt registered Apr 24 23:38:34.324359 kernel: Key type fscrypt-provisioning registered Apr 24 23:38:34.324378 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 24 23:38:34.324397 kernel: ima: Allocated hash algorithm: sha1 Apr 24 23:38:34.324416 kernel: ima: No architecture policies found Apr 24 23:38:34.324435 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 24 23:38:34.324454 kernel: clk: Disabling unused clocks Apr 24 23:38:34.324473 kernel: Freeing unused kernel memory: 39424K Apr 24 23:38:34.324492 kernel: Run /init as init process Apr 24 23:38:34.324511 kernel: with arguments: Apr 24 23:38:34.324536 kernel: /init Apr 24 23:38:34.324555 kernel: with environment: Apr 24 23:38:34.324573 kernel: HOME=/ Apr 24 23:38:34.324592 kernel: TERM=linux Apr 24 23:38:34.324616 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) Apr 24 23:38:34.324640 systemd[1]: Detected virtualization amazon. Apr 24 23:38:34.324661 systemd[1]: Detected architecture arm64. Apr 24 23:38:34.324681 systemd[1]: Running in initrd. Apr 24 23:38:34.324706 systemd[1]: No hostname configured, using default hostname. Apr 24 23:38:34.324726 systemd[1]: Hostname set to . Apr 24 23:38:34.324747 systemd[1]: Initializing machine ID from VM UUID. Apr 24 23:38:34.324767 systemd[1]: Queued start job for default target initrd.target. Apr 24 23:38:34.324788 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 24 23:38:34.324809 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 24 23:38:34.324831 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 24 23:38:34.324852 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 24 23:38:34.324878 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 24 23:38:34.324900 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 24 23:38:34.327493 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 24 23:38:34.327527 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 24 23:38:34.327550 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 24 23:38:34.327571 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 24 23:38:34.327602 systemd[1]: Reached target paths.target - Path Units. Apr 24 23:38:34.327623 systemd[1]: Reached target slices.target - Slice Units. Apr 24 23:38:34.327644 systemd[1]: Reached target swap.target - Swaps. Apr 24 23:38:34.327665 systemd[1]: Reached target timers.target - Timer Units. Apr 24 23:38:34.327686 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 24 23:38:34.327707 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 24 23:38:34.327728 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 24 23:38:34.327749 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 24 23:38:34.327770 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 24 23:38:34.327795 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 24 23:38:34.327816 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 24 23:38:34.327837 systemd[1]: Reached target sockets.target - Socket Units. Apr 24 23:38:34.327859 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 24 23:38:34.327881 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 24 23:38:34.327901 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 24 23:38:34.327922 systemd[1]: Starting systemd-fsck-usr.service... Apr 24 23:38:34.328047 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 24 23:38:34.328071 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 24 23:38:34.328101 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 24 23:38:34.328122 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 24 23:38:34.328144 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 24 23:38:34.328164 systemd[1]: Finished systemd-fsck-usr.service. Apr 24 23:38:34.328187 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 24 23:38:34.328212 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 24 23:38:34.328234 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 24 23:38:34.328310 systemd-journald[252]: Collecting audit messages is disabled. Apr 24 23:38:34.328364 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 24 23:38:34.328387 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 24 23:38:34.328408 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 24 23:38:34.328429 kernel: Bridge firewalling registered Apr 24 23:38:34.328449 systemd-journald[252]: Journal started Apr 24 23:38:34.328487 systemd-journald[252]: Runtime Journal (/run/log/journal/ec228b9340b33f8ef78719cb0b5063fd) is 8.0M, max 75.3M, 67.3M free. Apr 24 23:38:34.266814 systemd-modules-load[253]: Inserted module 'overlay' Apr 24 23:38:34.326523 systemd-modules-load[253]: Inserted module 'br_netfilter' Apr 24 23:38:34.338867 systemd[1]: Started systemd-journald.service - Journal Service. Apr 24 23:38:34.342016 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 24 23:38:34.352324 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 24 23:38:34.364287 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 24 23:38:34.376170 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 24 23:38:34.396300 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 24 23:38:34.410422 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 24 23:38:34.420561 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 24 23:38:34.423494 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 24 23:38:34.438236 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 24 23:38:34.467106 dracut-cmdline[285]: dracut-dracut-053 Apr 24 23:38:34.475801 dracut-cmdline[285]: 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=63304dd98a277d4592d17e0085ae3f91ca70cc8ec6dedfdd357a1e9755f9a8b3 Apr 24 23:38:34.524107 systemd-resolved[289]: Positive Trust Anchors: Apr 24 23:38:34.524142 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 24 23:38:34.524205 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 Apr 24 23:38:34.637970 kernel: SCSI subsystem initialized Apr 24 23:38:34.644974 kernel: Loading iSCSI transport class v2.0-870. Apr 24 23:38:34.657975 kernel: iscsi: registered transport (tcp) Apr 24 23:38:34.680330 kernel: iscsi: registered transport (qla4xxx) Apr 24 23:38:34.680404 kernel: QLogic iSCSI HBA Driver Apr 24 23:38:34.758982 kernel: random: crng init done Apr 24 23:38:34.759508 systemd-resolved[289]: Defaulting to hostname 'linux'. Apr 24 23:38:34.763469 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 24 23:38:34.768622 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 24 23:38:34.787805 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 24 23:38:34.802321 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 24 23:38:34.838564 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 24 23:38:34.838657 kernel: device-mapper: uevent: version 1.0.3 Apr 24 23:38:34.840759 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 24 23:38:34.908002 kernel: raid6: neonx8 gen() 6643 MB/s Apr 24 23:38:34.924978 kernel: raid6: neonx4 gen() 6483 MB/s Apr 24 23:38:34.941977 kernel: raid6: neonx2 gen() 5399 MB/s Apr 24 23:38:34.958980 kernel: raid6: neonx1 gen() 3938 MB/s Apr 24 23:38:34.975975 kernel: raid6: int64x8 gen() 3791 MB/s Apr 24 23:38:34.992977 kernel: raid6: int64x4 gen() 3681 MB/s Apr 24 23:38:35.009975 kernel: raid6: int64x2 gen() 3559 MB/s Apr 24 23:38:35.028054 kernel: raid6: int64x1 gen() 2759 MB/s Apr 24 23:38:35.028110 kernel: raid6: using algorithm neonx8 gen() 6643 MB/s Apr 24 23:38:35.046982 kernel: raid6: .... xor() 4852 MB/s, rmw enabled Apr 24 23:38:35.047039 kernel: raid6: using neon recovery algorithm Apr 24 23:38:35.054973 kernel: xor: measuring software checksum speed Apr 24 23:38:35.057237 kernel: 8regs : 10263 MB/sec Apr 24 23:38:35.057271 kernel: 32regs : 11916 MB/sec Apr 24 23:38:35.058543 kernel: arm64_neon : 9573 MB/sec Apr 24 23:38:35.058575 kernel: xor: using function: 32regs (11916 MB/sec) Apr 24 23:38:35.144986 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 24 23:38:35.167021 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 24 23:38:35.179319 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 24 23:38:35.224145 systemd-udevd[471]: Using default interface naming scheme 'v255'. Apr 24 23:38:35.234023 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 24 23:38:35.251218 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 24 23:38:35.285340 dracut-pre-trigger[480]: rd.md=0: removing MD RAID activation Apr 24 23:38:35.343526 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 24 23:38:35.354276 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 24 23:38:35.478645 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 24 23:38:35.489221 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 24 23:38:35.544155 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 24 23:38:35.551141 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 24 23:38:35.559361 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 24 23:38:35.564398 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 24 23:38:35.579051 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 24 23:38:35.625641 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 24 23:38:35.692373 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 24 23:38:35.692442 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Apr 24 23:38:35.698137 kernel: ena 0000:00:05.0: ENA device version: 0.10 Apr 24 23:38:35.698498 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Apr 24 23:38:35.709800 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 24 23:38:35.715075 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80110000, mac addr 06:41:b3:ea:02:6d Apr 24 23:38:35.710300 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 24 23:38:35.721664 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Apr 24 23:38:35.721707 kernel: nvme nvme0: pci function 0000:00:04.0 Apr 24 23:38:35.725284 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 24 23:38:35.727770 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 24 23:38:35.728098 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 24 23:38:35.730446 (udev-worker)[527]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:38:35.730777 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 24 23:38:35.746563 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 24 23:38:35.765988 kernel: nvme nvme0: 2/0/0 default/read/poll queues Apr 24 23:38:35.776336 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 24 23:38:35.776400 kernel: GPT:9289727 != 33554431 Apr 24 23:38:35.777777 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 24 23:38:35.778669 kernel: GPT:9289727 != 33554431 Apr 24 23:38:35.780193 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 24 23:38:35.781163 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 24 23:38:35.795742 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 24 23:38:35.807237 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 24 23:38:35.856277 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 24 23:38:35.889004 kernel: BTRFS: device fsid 5f4cf890-f9e2-4e04-aa84-1bcfb6e5643e devid 1 transid 36 /dev/nvme0n1p3 scanned by (udev-worker) (518) Apr 24 23:38:35.897002 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (524) Apr 24 23:38:35.980610 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Apr 24 23:38:36.017109 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Apr 24 23:38:36.034099 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 24 23:38:36.051161 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Apr 24 23:38:36.053833 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Apr 24 23:38:36.069346 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 24 23:38:36.085143 disk-uuid[662]: Primary Header is updated. Apr 24 23:38:36.085143 disk-uuid[662]: Secondary Entries is updated. Apr 24 23:38:36.085143 disk-uuid[662]: Secondary Header is updated. Apr 24 23:38:36.101960 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 24 23:38:36.107977 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 24 23:38:36.115970 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 24 23:38:37.123026 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 24 23:38:37.124695 disk-uuid[663]: The operation has completed successfully. Apr 24 23:38:37.319454 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 24 23:38:37.320094 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 24 23:38:37.378206 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 24 23:38:37.389337 sh[1007]: Success Apr 24 23:38:37.408967 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 24 23:38:37.530761 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 24 23:38:37.543183 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 24 23:38:37.556143 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 24 23:38:37.600115 kernel: BTRFS info (device dm-0): first mount of filesystem 5f4cf890-f9e2-4e04-aa84-1bcfb6e5643e Apr 24 23:38:37.600179 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 24 23:38:37.600207 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 24 23:38:37.602034 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 24 23:38:37.603418 kernel: BTRFS info (device dm-0): using free space tree Apr 24 23:38:37.645977 kernel: BTRFS info (device dm-0): enabling ssd optimizations Apr 24 23:38:37.671668 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 24 23:38:37.672090 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 24 23:38:37.686359 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 24 23:38:37.691744 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 24 23:38:37.731789 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7d1fb622-285b-4375-96d6-a0d989283452 Apr 24 23:38:37.731864 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 24 23:38:37.733288 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 24 23:38:37.740970 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 24 23:38:37.760855 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 24 23:38:37.771176 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7d1fb622-285b-4375-96d6-a0d989283452 Apr 24 23:38:37.786376 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 24 23:38:37.801340 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 24 23:38:37.883034 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 24 23:38:37.893202 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 24 23:38:37.949644 systemd-networkd[1202]: lo: Link UP Apr 24 23:38:37.949665 systemd-networkd[1202]: lo: Gained carrier Apr 24 23:38:37.952536 systemd-networkd[1202]: Enumeration completed Apr 24 23:38:37.953491 systemd-networkd[1202]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 24 23:38:37.953498 systemd-networkd[1202]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 24 23:38:37.956666 systemd-networkd[1202]: eth0: Link UP Apr 24 23:38:37.956674 systemd-networkd[1202]: eth0: Gained carrier Apr 24 23:38:37.956691 systemd-networkd[1202]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 24 23:38:37.960463 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 24 23:38:37.972106 systemd[1]: Reached target network.target - Network. Apr 24 23:38:37.986433 systemd-networkd[1202]: eth0: DHCPv4 address 172.31.30.31/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 24 23:38:38.096773 ignition[1140]: Ignition 2.19.0 Apr 24 23:38:38.096793 ignition[1140]: Stage: fetch-offline Apr 24 23:38:38.098673 ignition[1140]: no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:38.098703 ignition[1140]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:38.107989 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 24 23:38:38.101100 ignition[1140]: Ignition finished successfully Apr 24 23:38:38.129726 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 24 23:38:38.154155 ignition[1211]: Ignition 2.19.0 Apr 24 23:38:38.154688 ignition[1211]: Stage: fetch Apr 24 23:38:38.155375 ignition[1211]: no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:38.155400 ignition[1211]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:38.155551 ignition[1211]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:38.172983 ignition[1211]: PUT result: OK Apr 24 23:38:38.176168 ignition[1211]: parsed url from cmdline: "" Apr 24 23:38:38.176184 ignition[1211]: no config URL provided Apr 24 23:38:38.176201 ignition[1211]: reading system config file "/usr/lib/ignition/user.ign" Apr 24 23:38:38.176228 ignition[1211]: no config at "/usr/lib/ignition/user.ign" Apr 24 23:38:38.176291 ignition[1211]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:38.186142 unknown[1211]: fetched base config from "system" Apr 24 23:38:38.178029 ignition[1211]: PUT result: OK Apr 24 23:38:38.186160 unknown[1211]: fetched base config from "system" Apr 24 23:38:38.178101 ignition[1211]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Apr 24 23:38:38.186175 unknown[1211]: fetched user config from "aws" Apr 24 23:38:38.180804 ignition[1211]: GET result: OK Apr 24 23:38:38.194052 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 24 23:38:38.180884 ignition[1211]: parsing config with SHA512: aee36ba353b338a8b6b6785bd3004cceba1cc239e6e88dba707897d7d24a4e5dc3ea62b243b57ed74d4b1beae34827c79cc328a9120ab4fc606fb7e380975fcd Apr 24 23:38:38.186572 ignition[1211]: fetch: fetch complete Apr 24 23:38:38.186584 ignition[1211]: fetch: fetch passed Apr 24 23:38:38.187443 ignition[1211]: Ignition finished successfully Apr 24 23:38:38.217847 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 24 23:38:38.254269 ignition[1217]: Ignition 2.19.0 Apr 24 23:38:38.254297 ignition[1217]: Stage: kargs Apr 24 23:38:38.256144 ignition[1217]: no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:38.256171 ignition[1217]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:38.256581 ignition[1217]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:38.258168 ignition[1217]: PUT result: OK Apr 24 23:38:38.269338 ignition[1217]: kargs: kargs passed Apr 24 23:38:38.271062 ignition[1217]: Ignition finished successfully Apr 24 23:38:38.274759 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 24 23:38:38.286255 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 24 23:38:38.316417 ignition[1224]: Ignition 2.19.0 Apr 24 23:38:38.316444 ignition[1224]: Stage: disks Apr 24 23:38:38.318331 ignition[1224]: no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:38.318359 ignition[1224]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:38.318599 ignition[1224]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:38.322236 ignition[1224]: PUT result: OK Apr 24 23:38:38.329476 ignition[1224]: disks: disks passed Apr 24 23:38:38.333619 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 24 23:38:38.329572 ignition[1224]: Ignition finished successfully Apr 24 23:38:38.336662 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 24 23:38:38.345271 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 24 23:38:38.347983 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 24 23:38:38.350199 systemd[1]: Reached target sysinit.target - System Initialization. Apr 24 23:38:38.352484 systemd[1]: Reached target basic.target - Basic System. Apr 24 23:38:38.368403 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 24 23:38:38.407520 systemd-fsck[1232]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 24 23:38:38.416000 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 24 23:38:38.427135 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 24 23:38:38.528958 kernel: EXT4-fs (nvme0n1p9): mounted filesystem edaa698b-3baa-4242-8691-64cb9f35f18f r/w with ordered data mode. Quota mode: none. Apr 24 23:38:38.530406 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 24 23:38:38.535080 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 24 23:38:38.549145 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 24 23:38:38.556687 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 24 23:38:38.562090 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 24 23:38:38.562188 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 24 23:38:38.570706 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 24 23:38:38.588974 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/nvme0n1p6 scanned by mount (1251) Apr 24 23:38:38.597243 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7d1fb622-285b-4375-96d6-a0d989283452 Apr 24 23:38:38.597367 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 24 23:38:38.597400 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 24 23:38:38.597807 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 24 23:38:38.615312 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 24 23:38:38.620134 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 24 23:38:38.625488 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 24 23:38:38.876810 initrd-setup-root[1276]: cut: /sysroot/etc/passwd: No such file or directory Apr 24 23:38:38.889435 initrd-setup-root[1283]: cut: /sysroot/etc/group: No such file or directory Apr 24 23:38:38.898770 initrd-setup-root[1290]: cut: /sysroot/etc/shadow: No such file or directory Apr 24 23:38:38.908018 initrd-setup-root[1297]: cut: /sysroot/etc/gshadow: No such file or directory Apr 24 23:38:39.064081 systemd-networkd[1202]: eth0: Gained IPv6LL Apr 24 23:38:39.139244 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 24 23:38:39.149149 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 24 23:38:39.168203 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 24 23:38:39.186691 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 24 23:38:39.190979 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 7d1fb622-285b-4375-96d6-a0d989283452 Apr 24 23:38:39.229434 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 24 23:38:39.243816 ignition[1364]: INFO : Ignition 2.19.0 Apr 24 23:38:39.243816 ignition[1364]: INFO : Stage: mount Apr 24 23:38:39.248571 ignition[1364]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:39.248571 ignition[1364]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:39.248571 ignition[1364]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:39.255858 ignition[1364]: INFO : PUT result: OK Apr 24 23:38:39.260296 ignition[1364]: INFO : mount: mount passed Apr 24 23:38:39.266819 ignition[1364]: INFO : Ignition finished successfully Apr 24 23:38:39.263082 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 24 23:38:39.278272 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 24 23:38:39.306345 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 24 23:38:39.330983 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1377) Apr 24 23:38:39.331045 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 7d1fb622-285b-4375-96d6-a0d989283452 Apr 24 23:38:39.334839 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 24 23:38:39.334876 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 24 23:38:39.341970 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 24 23:38:39.346362 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 24 23:38:39.384984 ignition[1394]: INFO : Ignition 2.19.0 Apr 24 23:38:39.384984 ignition[1394]: INFO : Stage: files Apr 24 23:38:39.388509 ignition[1394]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:39.388509 ignition[1394]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:39.388509 ignition[1394]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:39.399266 ignition[1394]: INFO : PUT result: OK Apr 24 23:38:39.403685 ignition[1394]: DEBUG : files: compiled without relabeling support, skipping Apr 24 23:38:39.407079 ignition[1394]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 24 23:38:39.407079 ignition[1394]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 24 23:38:39.440648 ignition[1394]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 24 23:38:39.443953 ignition[1394]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 24 23:38:39.447495 unknown[1394]: wrote ssh authorized keys file for user: core Apr 24 23:38:39.452209 ignition[1394]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 24 23:38:39.456316 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Apr 24 23:38:39.460253 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.35.1-arm64.raw: attempt #1 Apr 24 23:38:39.951662 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 24 23:38:40.369541 ignition[1394]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Apr 24 23:38:40.374235 ignition[1394]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 24 23:38:40.374235 ignition[1394]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 24 23:38:40.374235 ignition[1394]: INFO : files: files passed Apr 24 23:38:40.374235 ignition[1394]: INFO : Ignition finished successfully Apr 24 23:38:40.383814 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 24 23:38:40.399255 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 24 23:38:40.411057 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 24 23:38:40.423448 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 24 23:38:40.428250 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 24 23:38:40.444727 initrd-setup-root-after-ignition[1422]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 24 23:38:40.444727 initrd-setup-root-after-ignition[1422]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 24 23:38:40.454567 initrd-setup-root-after-ignition[1426]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 24 23:38:40.460134 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 24 23:38:40.465875 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 24 23:38:40.477726 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 24 23:38:40.536032 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 24 23:38:40.537439 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 24 23:38:40.544250 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 24 23:38:40.547113 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 24 23:38:40.551592 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 24 23:38:40.564273 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 24 23:38:40.594974 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 24 23:38:40.604404 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 24 23:38:40.634267 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 24 23:38:40.637147 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 24 23:38:40.645241 systemd[1]: Stopped target timers.target - Timer Units. Apr 24 23:38:40.647860 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 24 23:38:40.648152 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 24 23:38:40.658314 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 24 23:38:40.660817 systemd[1]: Stopped target basic.target - Basic System. Apr 24 23:38:40.663526 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 24 23:38:40.671827 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 24 23:38:40.672156 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 24 23:38:40.680059 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 24 23:38:40.684570 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 24 23:38:40.689355 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 24 23:38:40.694055 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 24 23:38:40.696235 systemd[1]: Stopped target swap.target - Swaps. Apr 24 23:38:40.702318 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 24 23:38:40.702639 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 24 23:38:40.711181 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 24 23:38:40.717044 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 24 23:38:40.719176 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 24 23:38:40.719806 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 24 23:38:40.725063 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 24 23:38:40.725287 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 24 23:38:40.729943 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 24 23:38:40.730209 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 24 23:38:40.737651 systemd[1]: ignition-files.service: Deactivated successfully. Apr 24 23:38:40.737856 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 24 23:38:40.761249 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 24 23:38:40.765624 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 24 23:38:40.765883 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 24 23:38:40.771680 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 24 23:38:40.781159 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 24 23:38:40.785093 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 24 23:38:40.788084 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 24 23:38:40.788306 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 24 23:38:40.810256 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 24 23:38:40.813878 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 24 23:38:40.838786 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 24 23:38:40.841607 ignition[1446]: INFO : Ignition 2.19.0 Apr 24 23:38:40.841607 ignition[1446]: INFO : Stage: umount Apr 24 23:38:40.845407 ignition[1446]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 24 23:38:40.845407 ignition[1446]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 24 23:38:40.855435 ignition[1446]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 24 23:38:40.859107 ignition[1446]: INFO : PUT result: OK Apr 24 23:38:40.864078 ignition[1446]: INFO : umount: umount passed Apr 24 23:38:40.864078 ignition[1446]: INFO : Ignition finished successfully Apr 24 23:38:40.871596 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 24 23:38:40.873439 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 24 23:38:40.877407 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 24 23:38:40.877517 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 24 23:38:40.878229 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 24 23:38:40.878307 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 24 23:38:40.878589 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 24 23:38:40.883277 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 24 23:38:40.887854 systemd[1]: Stopped target network.target - Network. Apr 24 23:38:40.889879 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 24 23:38:40.890834 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 24 23:38:40.894771 systemd[1]: Stopped target paths.target - Path Units. Apr 24 23:38:40.896751 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 24 23:38:40.905367 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 24 23:38:40.908263 systemd[1]: Stopped target slices.target - Slice Units. Apr 24 23:38:40.911819 systemd[1]: Stopped target sockets.target - Socket Units. Apr 24 23:38:40.926732 systemd[1]: iscsid.socket: Deactivated successfully. Apr 24 23:38:40.927511 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 24 23:38:40.937137 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 24 23:38:40.937223 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 24 23:38:40.943454 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 24 23:38:40.943569 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 24 23:38:40.950441 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 24 23:38:40.950544 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 24 23:38:40.955343 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 24 23:38:40.957672 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 24 23:38:40.972189 systemd-networkd[1202]: eth0: DHCPv6 lease lost Apr 24 23:38:40.979146 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 24 23:38:40.979666 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 24 23:38:40.992424 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 24 23:38:40.992960 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 24 23:38:41.003404 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 24 23:38:41.003499 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 24 23:38:41.020145 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 24 23:38:41.022663 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 24 23:38:41.022777 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 24 23:38:41.035177 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 24 23:38:41.035289 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 24 23:38:41.037748 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 24 23:38:41.037854 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 24 23:38:41.040338 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 24 23:38:41.040438 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 24 23:38:41.043408 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 24 23:38:41.052216 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 24 23:38:41.052416 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 24 23:38:41.076603 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 24 23:38:41.076798 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 24 23:38:41.098315 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 24 23:38:41.099124 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 24 23:38:41.105612 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 24 23:38:41.106127 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 24 23:38:41.110768 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 24 23:38:41.110907 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 24 23:38:41.123510 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 24 23:38:41.123855 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 24 23:38:41.130274 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 24 23:38:41.130377 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 24 23:38:41.133509 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 24 23:38:41.133596 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 24 23:38:41.136121 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 24 23:38:41.136204 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 24 23:38:41.164887 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 24 23:38:41.167450 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 24 23:38:41.167571 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 24 23:38:41.170413 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 24 23:38:41.170526 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 24 23:38:41.183293 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 24 23:38:41.183552 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 24 23:38:41.193293 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 24 23:38:41.208970 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 24 23:38:41.226347 systemd[1]: Switching root. Apr 24 23:38:41.294980 systemd-journald[252]: Journal stopped Apr 24 23:38:43.416440 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Apr 24 23:38:43.416576 kernel: SELinux: policy capability network_peer_controls=1 Apr 24 23:38:43.416622 kernel: SELinux: policy capability open_perms=1 Apr 24 23:38:43.416653 kernel: SELinux: policy capability extended_socket_class=1 Apr 24 23:38:43.416685 kernel: SELinux: policy capability always_check_network=0 Apr 24 23:38:43.416715 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 24 23:38:43.416745 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 24 23:38:43.416776 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 24 23:38:43.419226 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 24 23:38:43.419285 kernel: audit: type=1403 audit(1777073921.665:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 24 23:38:43.419330 systemd[1]: Successfully loaded SELinux policy in 73.357ms. Apr 24 23:38:43.419378 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.872ms. Apr 24 23:38:43.419413 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) Apr 24 23:38:43.419444 systemd[1]: Detected virtualization amazon. Apr 24 23:38:43.419476 systemd[1]: Detected architecture arm64. Apr 24 23:38:43.419507 systemd[1]: Detected first boot. Apr 24 23:38:43.419539 systemd[1]: Initializing machine ID from VM UUID. Apr 24 23:38:43.419572 zram_generator::config[1488]: No configuration found. Apr 24 23:38:43.419610 systemd[1]: Populated /etc with preset unit settings. Apr 24 23:38:43.419646 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 24 23:38:43.419682 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 24 23:38:43.419714 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 24 23:38:43.419747 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 24 23:38:43.419785 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 24 23:38:43.419817 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 24 23:38:43.419850 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 24 23:38:43.419892 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 24 23:38:43.419924 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 24 23:38:43.422038 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 24 23:38:43.422079 systemd[1]: Created slice user.slice - User and Session Slice. Apr 24 23:38:43.422112 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 24 23:38:43.422152 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 24 23:38:43.422183 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 24 23:38:43.422215 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 24 23:38:43.422248 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 24 23:38:43.422280 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 24 23:38:43.422312 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 24 23:38:43.422344 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 24 23:38:43.422374 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 24 23:38:43.422408 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 24 23:38:43.422446 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 24 23:38:43.422479 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 24 23:38:43.422511 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 24 23:38:43.422543 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 24 23:38:43.422575 systemd[1]: Reached target slices.target - Slice Units. Apr 24 23:38:43.422628 systemd[1]: Reached target swap.target - Swaps. Apr 24 23:38:43.422661 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 24 23:38:43.422691 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 24 23:38:43.422728 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 24 23:38:43.422760 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 24 23:38:43.422792 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 24 23:38:43.422822 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 24 23:38:43.422863 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 24 23:38:43.422893 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 24 23:38:43.422923 systemd[1]: Mounting media.mount - External Media Directory... Apr 24 23:38:43.422980 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 24 23:38:43.423013 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 24 23:38:43.423050 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 24 23:38:43.423082 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). Apr 24 23:38:43.423112 systemd[1]: Reached target machines.target - Containers. Apr 24 23:38:43.423142 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 24 23:38:43.423173 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 24 23:38:43.423203 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 24 23:38:43.423232 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 24 23:38:43.423263 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 24 23:38:43.423296 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 24 23:38:43.423331 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 24 23:38:43.423362 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 24 23:38:43.423392 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 24 23:38:43.423422 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 24 23:38:43.423452 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 24 23:38:43.423485 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 24 23:38:43.423517 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 24 23:38:43.423547 systemd[1]: Stopped systemd-fsck-usr.service. Apr 24 23:38:43.423581 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 24 23:38:43.423611 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 24 23:38:43.423639 kernel: fuse: init (API version 7.39) Apr 24 23:38:43.423669 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 24 23:38:43.423699 kernel: loop: module loaded Apr 24 23:38:43.423728 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 24 23:38:43.423760 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 24 23:38:43.423792 systemd[1]: verity-setup.service: Deactivated successfully. Apr 24 23:38:43.423822 systemd[1]: Stopped verity-setup.service. Apr 24 23:38:43.423856 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 24 23:38:43.423889 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 24 23:38:43.423919 systemd[1]: Mounted media.mount - External Media Directory. Apr 24 23:38:43.425999 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 24 23:38:43.426039 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 24 23:38:43.426078 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 24 23:38:43.426109 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 24 23:38:43.426139 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 24 23:38:43.426216 systemd-journald[1573]: Collecting audit messages is disabled. Apr 24 23:38:43.426280 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 24 23:38:43.426311 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 24 23:38:43.426340 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 24 23:38:43.426376 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 24 23:38:43.426407 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 24 23:38:43.426436 systemd-journald[1573]: Journal started Apr 24 23:38:43.426485 systemd-journald[1573]: Runtime Journal (/run/log/journal/ec228b9340b33f8ef78719cb0b5063fd) is 8.0M, max 75.3M, 67.3M free. Apr 24 23:38:42.848254 systemd[1]: Queued start job for default target multi-user.target. Apr 24 23:38:43.430233 systemd[1]: Started systemd-journald.service - Journal Service. Apr 24 23:38:42.883028 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Apr 24 23:38:42.883811 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 24 23:38:43.436710 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 24 23:38:43.437067 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 24 23:38:43.443907 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 24 23:38:43.445097 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 24 23:38:43.465718 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 24 23:38:43.480279 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 24 23:38:43.489159 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 24 23:38:43.492215 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 24 23:38:43.500281 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 24 23:38:43.508055 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 24 23:38:43.511629 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 24 23:38:43.515695 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 24 23:38:43.525662 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 24 23:38:43.540268 kernel: ACPI: bus type drm_connector registered Apr 24 23:38:43.534096 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 24 23:38:43.536793 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 24 23:38:43.536863 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 24 23:38:43.543821 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 24 23:38:43.557211 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 24 23:38:43.566175 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 24 23:38:43.568675 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 24 23:38:43.585331 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 24 23:38:43.591443 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 24 23:38:43.594094 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 24 23:38:43.598530 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 24 23:38:43.605269 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 24 23:38:43.610050 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 24 23:38:43.612014 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 24 23:38:43.622114 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 24 23:38:43.659645 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 24 23:38:43.662535 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 24 23:38:43.670286 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 24 23:38:43.682039 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 24 23:38:43.695039 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 24 23:38:43.714551 kernel: loop0: detected capacity change from 0 to 197488 Apr 24 23:38:43.728153 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 24 23:38:43.736268 systemd-journald[1573]: Time spent on flushing to /var/log/journal/ec228b9340b33f8ef78719cb0b5063fd is 201.872ms for 888 entries. Apr 24 23:38:43.736268 systemd-journald[1573]: System Journal (/var/log/journal/ec228b9340b33f8ef78719cb0b5063fd) is 8.0M, max 195.6M, 187.6M free. Apr 24 23:38:43.965598 systemd-journald[1573]: Received client request to flush runtime journal. Apr 24 23:38:43.965695 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 24 23:38:43.965745 kernel: loop1: detected capacity change from 0 to 114328 Apr 24 23:38:43.869712 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 24 23:38:43.873835 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 24 23:38:43.889346 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 24 23:38:43.911343 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 24 23:38:43.915516 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 24 23:38:43.921041 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 24 23:38:43.941715 udevadm[1633]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Apr 24 23:38:43.970633 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 24 23:38:43.997684 systemd-tmpfiles[1632]: ACLs are not supported, ignoring. Apr 24 23:38:43.999046 systemd-tmpfiles[1632]: ACLs are not supported, ignoring. Apr 24 23:38:44.013087 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 24 23:38:44.035977 kernel: loop2: detected capacity change from 0 to 114432 Apr 24 23:38:44.112842 kernel: loop3: detected capacity change from 0 to 52536 Apr 24 23:38:44.183122 kernel: loop4: detected capacity change from 0 to 197488 Apr 24 23:38:44.218979 kernel: loop5: detected capacity change from 0 to 114328 Apr 24 23:38:44.248990 kernel: loop6: detected capacity change from 0 to 114432 Apr 24 23:38:44.272987 kernel: loop7: detected capacity change from 0 to 52536 Apr 24 23:38:44.294649 (sd-merge)[1642]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Apr 24 23:38:44.295663 (sd-merge)[1642]: Merged extensions into '/usr'. Apr 24 23:38:44.306620 systemd[1]: Reloading requested from client PID 1617 ('systemd-sysext') (unit systemd-sysext.service)... Apr 24 23:38:44.306815 systemd[1]: Reloading... Apr 24 23:38:44.507446 zram_generator::config[1664]: No configuration found. Apr 24 23:38:44.899827 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. Apr 24 23:38:44.955555 ldconfig[1610]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 24 23:38:45.016170 systemd[1]: Reloading finished in 708 ms. Apr 24 23:38:45.057006 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 24 23:38:45.060037 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 24 23:38:45.063225 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 24 23:38:45.079283 systemd[1]: Starting ensure-sysext.service... Apr 24 23:38:45.094182 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 24 23:38:45.105115 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 24 23:38:45.121713 systemd[1]: Reloading requested from client PID 1721 ('systemctl') (unit ensure-sysext.service)... Apr 24 23:38:45.121739 systemd[1]: Reloading... Apr 24 23:38:45.136752 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 24 23:38:45.137484 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 24 23:38:45.144509 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 24 23:38:45.147295 systemd-tmpfiles[1722]: ACLs are not supported, ignoring. Apr 24 23:38:45.147456 systemd-tmpfiles[1722]: ACLs are not supported, ignoring. Apr 24 23:38:45.158495 systemd-tmpfiles[1722]: Detected autofs mount point /boot during canonicalization of boot. Apr 24 23:38:45.158523 systemd-tmpfiles[1722]: Skipping /boot Apr 24 23:38:45.185260 systemd-udevd[1723]: Using default interface naming scheme 'v255'. Apr 24 23:38:45.199240 systemd-tmpfiles[1722]: Detected autofs mount point /boot during canonicalization of boot. Apr 24 23:38:45.199603 systemd-tmpfiles[1722]: Skipping /boot Apr 24 23:38:45.355985 zram_generator::config[1759]: No configuration found. Apr 24 23:38:45.463827 (udev-worker)[1764]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:38:45.670620 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. Apr 24 23:38:45.690991 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (1774) Apr 24 23:38:45.834094 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 24 23:38:45.835662 systemd[1]: Reloading finished in 713 ms. Apr 24 23:38:45.872622 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 24 23:38:45.879066 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 24 23:38:45.927306 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Apr 24 23:38:45.936265 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 24 23:38:45.945652 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 24 23:38:45.958240 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 24 23:38:45.967324 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 24 23:38:45.974414 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 24 23:38:45.996210 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 24 23:38:46.001551 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 24 23:38:46.017002 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 24 23:38:46.029598 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 24 23:38:46.033294 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 24 23:38:46.041258 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 24 23:38:46.051682 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 24 23:38:46.056116 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 24 23:38:46.056524 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 24 23:38:46.092918 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 24 23:38:46.098457 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 24 23:38:46.101081 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 24 23:38:46.107505 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 24 23:38:46.111162 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 24 23:38:46.138255 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 24 23:38:46.178306 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 24 23:38:46.180776 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 24 23:38:46.180909 systemd[1]: Reached target time-set.target - System Time Set. Apr 24 23:38:46.212790 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 24 23:38:46.250034 systemd[1]: Finished ensure-sysext.service. Apr 24 23:38:46.257350 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 24 23:38:46.257662 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 24 23:38:46.276787 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 24 23:38:46.280433 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 24 23:38:46.282062 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 24 23:38:46.319648 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 24 23:38:46.320026 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 24 23:38:46.327660 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 24 23:38:46.338307 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 24 23:38:46.338698 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 24 23:38:46.343574 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 24 23:38:46.368451 augenrules[1956]: No rules Apr 24 23:38:46.378752 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Apr 24 23:38:46.392321 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 24 23:38:46.407386 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 24 23:38:46.422316 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 24 23:38:46.424862 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 24 23:38:46.425011 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 24 23:38:46.425058 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). Apr 24 23:38:46.448211 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 24 23:38:46.480958 lvm[1964]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 24 23:38:46.503015 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 24 23:38:46.550012 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 24 23:38:46.553544 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 24 23:38:46.559373 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 24 23:38:46.569304 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 24 23:38:46.600125 lvm[1978]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 24 23:38:46.644807 systemd-networkd[1908]: lo: Link UP Apr 24 23:38:46.645434 systemd-networkd[1908]: lo: Gained carrier Apr 24 23:38:46.648541 systemd-networkd[1908]: Enumeration completed Apr 24 23:38:46.648923 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 24 23:38:46.650059 systemd-networkd[1908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 24 23:38:46.650073 systemd-networkd[1908]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 24 23:38:46.653197 systemd-networkd[1908]: eth0: Link UP Apr 24 23:38:46.654411 systemd-networkd[1908]: eth0: Gained carrier Apr 24 23:38:46.655922 systemd-resolved[1909]: Positive Trust Anchors: Apr 24 23:38:46.656100 systemd-resolved[1909]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 24 23:38:46.656167 systemd-resolved[1909]: 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 Apr 24 23:38:46.656343 systemd-networkd[1908]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 24 23:38:46.665359 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 24 23:38:46.676025 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 24 23:38:46.680285 systemd-networkd[1908]: eth0: DHCPv4 address 172.31.30.31/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 24 23:38:46.684166 systemd-resolved[1909]: Defaulting to hostname 'linux'. Apr 24 23:38:46.689781 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 24 23:38:46.692469 systemd[1]: Reached target network.target - Network. Apr 24 23:38:46.694510 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 24 23:38:46.697142 systemd[1]: Reached target sysinit.target - System Initialization. Apr 24 23:38:46.699624 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 24 23:38:46.702362 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 24 23:38:46.705372 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 24 23:38:46.708650 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 24 23:38:46.711570 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 24 23:38:46.714359 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 24 23:38:46.714418 systemd[1]: Reached target paths.target - Path Units. Apr 24 23:38:46.716442 systemd[1]: Reached target timers.target - Timer Units. Apr 24 23:38:46.719317 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 24 23:38:46.725108 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 24 23:38:46.734805 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 24 23:38:46.738263 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 24 23:38:46.740915 systemd[1]: Reached target sockets.target - Socket Units. Apr 24 23:38:46.743115 systemd[1]: Reached target basic.target - Basic System. Apr 24 23:38:46.745239 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 24 23:38:46.745289 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 24 23:38:46.756844 systemd[1]: Starting containerd.service - containerd container runtime... Apr 24 23:38:46.761806 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 24 23:38:46.767196 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 24 23:38:46.776368 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 24 23:38:46.782700 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 24 23:38:46.785086 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). Apr 24 23:38:46.790213 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 24 23:38:46.797696 systemd[1]: Started ntpd.service - Network Time Service. Apr 24 23:38:46.805207 systemd[1]: Starting setup-oem.service - Setup OEM... Apr 24 23:38:46.813709 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 24 23:38:46.824212 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 24 23:38:46.839171 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 24 23:38:46.844786 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 24 23:38:46.845700 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 24 23:38:46.849249 systemd[1]: Starting update-engine.service - Update Engine... Apr 24 23:38:46.859480 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 24 23:38:46.916741 jq[1987]: false Apr 24 23:38:46.929787 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 24 23:38:46.930201 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 24 23:38:46.957572 jq[1998]: true Apr 24 23:38:46.994156 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 24 23:38:46.997079 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 24 23:38:47.033200 extend-filesystems[1988]: Found loop4 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found loop5 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found loop6 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found loop7 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found nvme0n1 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found nvme0n1p1 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found nvme0n1p2 Apr 24 23:38:47.033200 extend-filesystems[1988]: Found nvme0n1p3 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.031 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetch failed with 404: resource not found Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.032 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.038 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.038 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.043 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.043 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.044 INFO Fetch successful Apr 24 23:38:47.056769 coreos-metadata[1985]: Apr 24 23:38:47.044 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Apr 24 23:38:47.061823 extend-filesystems[1988]: Found usr Apr 24 23:38:47.061823 extend-filesystems[1988]: Found nvme0n1p4 Apr 24 23:38:47.061823 extend-filesystems[1988]: Found nvme0n1p6 Apr 24 23:38:47.061823 extend-filesystems[1988]: Found nvme0n1p7 Apr 24 23:38:47.061823 extend-filesystems[1988]: Found nvme0n1p9 Apr 24 23:38:47.061823 extend-filesystems[1988]: Checking size of /dev/nvme0n1p9 Apr 24 23:38:47.061402 dbus-daemon[1986]: [system] SELinux support is enabled Apr 24 23:38:47.103003 update_engine[1997]: I20260424 23:38:47.042316 1997 main.cc:92] Flatcar Update Engine starting Apr 24 23:38:47.059409 (ntainerd)[2005]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 24 23:38:47.103728 coreos-metadata[1985]: Apr 24 23:38:47.080 INFO Fetch successful Apr 24 23:38:47.103728 coreos-metadata[1985]: Apr 24 23:38:47.080 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Apr 24 23:38:47.103728 coreos-metadata[1985]: Apr 24 23:38:47.080 INFO Fetch successful Apr 24 23:38:47.096415 dbus-daemon[1986]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1908 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Apr 24 23:38:47.061679 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 24 23:38:47.106402 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 24 23:38:47.073133 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). Apr 24 23:38:47.073189 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 24 23:38:47.117349 update_engine[1997]: I20260424 23:38:47.115775 1997 update_check_scheduler.cc:74] Next update check in 2m16s Apr 24 23:38:47.079225 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). Apr 24 23:38:47.079262 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: ntpd 4.2.8p17@1.4004-o Fri Apr 24 21:50:58 UTC 2026 (1): Starting Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: ---------------------------------------------------- Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: ntp-4 is maintained by Network Time Foundation, Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: corporation. Support and training for ntp-4 are Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: available at https://www.nwtime.org/support Apr 24 23:38:47.132651 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: ---------------------------------------------------- Apr 24 23:38:47.121352 systemd[1]: Started update-engine.service - Update Engine. Apr 24 23:38:47.124041 ntpd[1990]: ntpd 4.2.8p17@1.4004-o Fri Apr 24 21:50:58 UTC 2026 (1): Starting Apr 24 23:38:47.133188 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 24 23:38:47.124092 ntpd[1990]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 24 23:38:47.143342 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 24 23:38:47.124113 ntpd[1990]: ---------------------------------------------------- Apr 24 23:38:47.156375 systemd[1]: motdgen.service: Deactivated successfully. Apr 24 23:38:47.124133 ntpd[1990]: ntp-4 is maintained by Network Time Foundation, Apr 24 23:38:47.159531 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 24 23:38:47.124152 ntpd[1990]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 24 23:38:47.124171 ntpd[1990]: corporation. Support and training for ntp-4 are Apr 24 23:38:47.174225 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: proto: precision = 0.096 usec (-23) Apr 24 23:38:47.124189 ntpd[1990]: available at https://www.nwtime.org/support Apr 24 23:38:47.124208 ntpd[1990]: ---------------------------------------------------- Apr 24 23:38:47.171020 ntpd[1990]: proto: precision = 0.096 usec (-23) Apr 24 23:38:47.184003 ntpd[1990]: basedate set to 2026-04-12 Apr 24 23:38:47.195138 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: basedate set to 2026-04-12 Apr 24 23:38:47.195138 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: gps base set to 2026-04-12 (week 2414) Apr 24 23:38:47.184049 ntpd[1990]: gps base set to 2026-04-12 (week 2414) Apr 24 23:38:47.203894 ntpd[1990]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listen and drop on 0 v6wildcard [::]:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listen normally on 3 eth0 172.31.30.31:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listen normally on 4 lo [::1]:123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: bind(21) AF_INET6 fe80::441:b3ff:feea:26d%2#123 flags 0x11 failed: Cannot assign requested address Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: unable to create socket on eth0 (5) for fe80::441:b3ff:feea:26d%2#123 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: failed to init interface for address fe80::441:b3ff:feea:26d%2 Apr 24 23:38:47.205207 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: Listening on routing socket on fd #21 for interface updates Apr 24 23:38:47.204017 ntpd[1990]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 24 23:38:47.204282 ntpd[1990]: Listen normally on 2 lo 127.0.0.1:123 Apr 24 23:38:47.204345 ntpd[1990]: Listen normally on 3 eth0 172.31.30.31:123 Apr 24 23:38:47.204411 ntpd[1990]: Listen normally on 4 lo [::1]:123 Apr 24 23:38:47.204485 ntpd[1990]: bind(21) AF_INET6 fe80::441:b3ff:feea:26d%2#123 flags 0x11 failed: Cannot assign requested address Apr 24 23:38:47.204524 ntpd[1990]: unable to create socket on eth0 (5) for fe80::441:b3ff:feea:26d%2#123 Apr 24 23:38:47.204555 ntpd[1990]: failed to init interface for address fe80::441:b3ff:feea:26d%2 Apr 24 23:38:47.204608 ntpd[1990]: Listening on routing socket on fd #21 for interface updates Apr 24 23:38:47.212005 extend-filesystems[1988]: Resized partition /dev/nvme0n1p9 Apr 24 23:38:47.224088 jq[2010]: true Apr 24 23:38:47.234088 extend-filesystems[2036]: resize2fs 1.47.1 (20-May-2024) Apr 24 23:38:47.248512 systemd[1]: Finished setup-oem.service - Setup OEM. Apr 24 23:38:47.268971 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 3587067 blocks Apr 24 23:38:47.282803 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 23:38:47.283281 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 23:38:47.283281 ntpd[1990]: 24 Apr 23:38:47 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 23:38:47.282863 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 24 23:38:47.329726 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 24 23:38:47.332824 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 24 23:38:47.442628 systemd-logind[1995]: Watching system buttons on /dev/input/event0 (Power Button) Apr 24 23:38:47.442679 systemd-logind[1995]: Watching system buttons on /dev/input/event1 (Sleep Button) Apr 24 23:38:47.443135 systemd-logind[1995]: New seat seat0. Apr 24 23:38:47.446019 systemd[1]: Started systemd-logind.service - User Login Management. Apr 24 23:38:47.463571 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (1774) Apr 24 23:38:47.494021 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 3587067 Apr 24 23:38:47.514983 extend-filesystems[2036]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Apr 24 23:38:47.514983 extend-filesystems[2036]: old_desc_blocks = 1, new_desc_blocks = 2 Apr 24 23:38:47.514983 extend-filesystems[2036]: The filesystem on /dev/nvme0n1p9 is now 3587067 (4k) blocks long. Apr 24 23:38:47.525448 extend-filesystems[1988]: Resized filesystem in /dev/nvme0n1p9 Apr 24 23:38:47.521302 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 24 23:38:47.521690 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 24 23:38:47.552281 bash[2078]: Updated "/home/core/.ssh/authorized_keys" Apr 24 23:38:47.557430 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 24 23:38:47.607120 systemd[1]: Starting sshkeys.service... Apr 24 23:38:47.674240 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 24 23:38:47.710587 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 24 23:38:47.712517 dbus-daemon[1986]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=2026 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Apr 24 23:38:47.713926 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 24 23:38:47.718515 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 24 23:38:47.727513 systemd[1]: Starting polkit.service - Authorization Manager... Apr 24 23:38:47.766643 polkitd[2111]: Started polkitd version 121 Apr 24 23:38:47.773964 containerd[2005]: time="2026-04-24T23:38:47.772672006Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Apr 24 23:38:47.841319 polkitd[2111]: Loading rules from directory /etc/polkit-1/rules.d Apr 24 23:38:47.841777 polkitd[2111]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 24 23:38:47.846284 polkitd[2111]: Finished loading, compiling and executing 2 rules Apr 24 23:38:47.849061 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 24 23:38:47.849513 systemd[1]: Started polkit.service - Authorization Manager. Apr 24 23:38:47.853083 polkitd[2111]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 24 23:38:47.893389 systemd-resolved[1909]: System hostname changed to 'ip-172-31-30-31'. Apr 24 23:38:47.893927 systemd-hostnamed[2026]: Hostname set to (transient) Apr 24 23:38:47.922963 containerd[2005]: time="2026-04-24T23:38:47.922827095Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.927393 containerd[2005]: time="2026-04-24T23:38:47.927312107Z" 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.127-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 24 23:38:47.927393 containerd[2005]: time="2026-04-24T23:38:47.927380075Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 24 23:38:47.927393 containerd[2005]: time="2026-04-24T23:38:47.927416315Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 24 23:38:47.927804 containerd[2005]: time="2026-04-24T23:38:47.927755087Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 24 23:38:47.927866 containerd[2005]: time="2026-04-24T23:38:47.927818999Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.927979115Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928029299Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928366667Z" 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 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928408739Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928440443Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928465259Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.929965 containerd[2005]: time="2026-04-24T23:38:47.928674947Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.930433 locksmithd[2028]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 24 23:38:47.934358 containerd[2005]: time="2026-04-24T23:38:47.934287131Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 24 23:38:47.934626 containerd[2005]: time="2026-04-24T23:38:47.934556387Z" 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 Apr 24 23:38:47.934690 containerd[2005]: time="2026-04-24T23:38:47.934621655Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 24 23:38:47.934917 containerd[2005]: time="2026-04-24T23:38:47.934857503Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 24 23:38:47.935444 containerd[2005]: time="2026-04-24T23:38:47.935013311Z" level=info msg="metadata content store policy set" policy=shared Apr 24 23:38:47.948118 containerd[2005]: time="2026-04-24T23:38:47.948041831Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 24 23:38:47.948238 containerd[2005]: time="2026-04-24T23:38:47.948152267Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 24 23:38:47.948238 containerd[2005]: time="2026-04-24T23:38:47.948190559Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 24 23:38:47.948238 containerd[2005]: time="2026-04-24T23:38:47.948225239Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 24 23:38:47.948469 containerd[2005]: time="2026-04-24T23:38:47.948258407Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 24 23:38:47.948593 containerd[2005]: time="2026-04-24T23:38:47.948546623Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 24 23:38:47.949035 containerd[2005]: time="2026-04-24T23:38:47.948984947Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 24 23:38:47.949276 containerd[2005]: time="2026-04-24T23:38:47.949232855Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 24 23:38:47.949418 containerd[2005]: time="2026-04-24T23:38:47.949281527Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 24 23:38:47.949418 containerd[2005]: time="2026-04-24T23:38:47.949314383Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 24 23:38:47.949418 containerd[2005]: time="2026-04-24T23:38:47.949349399Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949418 containerd[2005]: time="2026-04-24T23:38:47.949379615Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949419083Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949458107Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949499051Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949530263Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949564763Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949596455Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949643063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.949658 containerd[2005]: time="2026-04-24T23:38:47.949676087Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949705523Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949736495Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949765103Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949795787Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949837283Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949869551Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.950254 containerd[2005]: time="2026-04-24T23:38:47.949899383Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953163 containerd[2005]: time="2026-04-24T23:38:47.953072063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953163 containerd[2005]: time="2026-04-24T23:38:47.953156027Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953194631Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953226491Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953268263Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953333951Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953365439Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.953460 containerd[2005]: time="2026-04-24T23:38:47.953392499Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 24 23:38:47.954226 containerd[2005]: time="2026-04-24T23:38:47.953635679Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 24 23:38:47.956039 containerd[2005]: time="2026-04-24T23:38:47.955962455Z" 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 Apr 24 23:38:47.956039 containerd[2005]: time="2026-04-24T23:38:47.956032031Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 24 23:38:47.956248 containerd[2005]: time="2026-04-24T23:38:47.956071775Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 24 23:38:47.956248 containerd[2005]: time="2026-04-24T23:38:47.956096795Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.956248 containerd[2005]: time="2026-04-24T23:38:47.956128307Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 24 23:38:47.956248 containerd[2005]: time="2026-04-24T23:38:47.956152667Z" level=info msg="NRI interface is disabled by configuration." Apr 24 23:38:47.956248 containerd[2005]: time="2026-04-24T23:38:47.956184767Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 24 23:38:47.961900 containerd[2005]: time="2026-04-24T23:38:47.956861195Z" 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}" Apr 24 23:38:47.961900 containerd[2005]: time="2026-04-24T23:38:47.961107287Z" level=info msg="Connect containerd service" Apr 24 23:38:47.961900 containerd[2005]: time="2026-04-24T23:38:47.961207451Z" level=info msg="using legacy CRI server" Apr 24 23:38:47.961900 containerd[2005]: time="2026-04-24T23:38:47.961229411Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 24 23:38:47.961900 containerd[2005]: time="2026-04-24T23:38:47.961458743Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 24 23:38:47.964640 containerd[2005]: time="2026-04-24T23:38:47.964556351Z" 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" Apr 24 23:38:47.966976 containerd[2005]: time="2026-04-24T23:38:47.966807647Z" level=info msg="Start subscribing containerd event" Apr 24 23:38:47.966976 containerd[2005]: time="2026-04-24T23:38:47.966945575Z" level=info msg="Start recovering state" Apr 24 23:38:47.967135 containerd[2005]: time="2026-04-24T23:38:47.967088099Z" level=info msg="Start event monitor" Apr 24 23:38:47.967135 containerd[2005]: time="2026-04-24T23:38:47.967112867Z" level=info msg="Start snapshots syncer" Apr 24 23:38:47.967224 containerd[2005]: time="2026-04-24T23:38:47.967134731Z" level=info msg="Start cni network conf syncer for default" Apr 24 23:38:47.967224 containerd[2005]: time="2026-04-24T23:38:47.967153583Z" level=info msg="Start streaming server" Apr 24 23:38:47.978812 containerd[2005]: time="2026-04-24T23:38:47.974310071Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 24 23:38:47.978812 containerd[2005]: time="2026-04-24T23:38:47.974460779Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 24 23:38:47.978812 containerd[2005]: time="2026-04-24T23:38:47.974612735Z" level=info msg="containerd successfully booted in 0.211190s" Apr 24 23:38:47.974729 systemd[1]: Started containerd.service - containerd container runtime. Apr 24 23:38:47.993131 coreos-metadata[2103]: Apr 24 23:38:47.991 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 24 23:38:48.001986 coreos-metadata[2103]: Apr 24 23:38:48.000 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Apr 24 23:38:48.008119 coreos-metadata[2103]: Apr 24 23:38:48.005 INFO Fetch successful Apr 24 23:38:48.008119 coreos-metadata[2103]: Apr 24 23:38:48.006 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 24 23:38:48.013125 coreos-metadata[2103]: Apr 24 23:38:48.013 INFO Fetch successful Apr 24 23:38:48.024360 unknown[2103]: wrote ssh authorized keys file for user: core Apr 24 23:38:48.100366 update-ssh-keys[2171]: Updated "/home/core/.ssh/authorized_keys" Apr 24 23:38:48.099997 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 24 23:38:48.112004 systemd[1]: Finished sshkeys.service. Apr 24 23:38:48.125250 ntpd[1990]: bind(24) AF_INET6 fe80::441:b3ff:feea:26d%2#123 flags 0x11 failed: Cannot assign requested address Apr 24 23:38:48.126413 ntpd[1990]: 24 Apr 23:38:48 ntpd[1990]: bind(24) AF_INET6 fe80::441:b3ff:feea:26d%2#123 flags 0x11 failed: Cannot assign requested address Apr 24 23:38:48.126413 ntpd[1990]: 24 Apr 23:38:48 ntpd[1990]: unable to create socket on eth0 (6) for fe80::441:b3ff:feea:26d%2#123 Apr 24 23:38:48.126413 ntpd[1990]: 24 Apr 23:38:48 ntpd[1990]: failed to init interface for address fe80::441:b3ff:feea:26d%2 Apr 24 23:38:48.125309 ntpd[1990]: unable to create socket on eth0 (6) for fe80::441:b3ff:feea:26d%2#123 Apr 24 23:38:48.125339 ntpd[1990]: failed to init interface for address fe80::441:b3ff:feea:26d%2 Apr 24 23:38:48.410138 systemd-networkd[1908]: eth0: Gained IPv6LL Apr 24 23:38:48.415579 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 24 23:38:48.421263 systemd[1]: Reached target network-online.target - Network is Online. Apr 24 23:38:48.435486 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Apr 24 23:38:48.453547 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 24 23:38:48.468404 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 24 23:38:48.529674 amazon-ssm-agent[2186]: Initializing new seelog logger Apr 24 23:38:48.531245 amazon-ssm-agent[2186]: New Seelog Logger Creation Complete Apr 24 23:38:48.531245 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.531245 amazon-ssm-agent[2186]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.531245 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 processing appconfig overrides Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 processing appconfig overrides Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.533972 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 processing appconfig overrides Apr 24 23:38:48.534464 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO Proxy environment variables: Apr 24 23:38:48.538761 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.541944 amazon-ssm-agent[2186]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 24 23:38:48.541944 amazon-ssm-agent[2186]: 2026/04/24 23:38:48 processing appconfig overrides Apr 24 23:38:48.560799 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 24 23:38:48.635212 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO https_proxy: Apr 24 23:38:48.735276 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO http_proxy: Apr 24 23:38:48.836143 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO no_proxy: Apr 24 23:38:48.932863 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO Checking if agent identity type OnPrem can be assumed Apr 24 23:38:49.031443 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO Checking if agent identity type EC2 can be assumed Apr 24 23:38:49.081014 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO Agent will take identity from EC2 Apr 24 23:38:49.082146 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 24 23:38:49.082288 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 24 23:38:49.082410 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 24 23:38:49.082524 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Apr 24 23:38:49.082677 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Apr 24 23:38:49.082793 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] Starting Core Agent Apr 24 23:38:49.082907 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [amazon-ssm-agent] registrar detected. Attempting registration Apr 24 23:38:49.083051 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [Registrar] Starting registrar module Apr 24 23:38:49.083192 amazon-ssm-agent[2186]: 2026-04-24 23:38:48 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Apr 24 23:38:49.083309 amazon-ssm-agent[2186]: 2026-04-24 23:38:49 INFO [EC2Identity] EC2 registration was successful. Apr 24 23:38:49.084310 amazon-ssm-agent[2186]: 2026-04-24 23:38:49 INFO [CredentialRefresher] credentialRefresher has started Apr 24 23:38:49.084310 amazon-ssm-agent[2186]: 2026-04-24 23:38:49 INFO [CredentialRefresher] Starting credentials refresher loop Apr 24 23:38:49.084310 amazon-ssm-agent[2186]: 2026-04-24 23:38:49 INFO EC2RoleProvider Successfully connected with instance profile role credentials Apr 24 23:38:49.130307 amazon-ssm-agent[2186]: 2026-04-24 23:38:49 INFO [CredentialRefresher] Next credential rotation will be in 30.891606056266667 minutes Apr 24 23:38:49.956242 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 24 23:38:50.131770 amazon-ssm-agent[2186]: 2026-04-24 23:38:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Apr 24 23:38:50.233698 amazon-ssm-agent[2186]: 2026-04-24 23:38:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2206) started Apr 24 23:38:50.337980 amazon-ssm-agent[2186]: 2026-04-24 23:38:50 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Apr 24 23:38:50.615335 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 24 23:38:50.634676 (kubelet)[2221]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 24 23:38:50.670471 sshd_keygen[2027]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 24 23:38:50.719575 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 24 23:38:50.728466 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 24 23:38:50.738780 systemd[1]: Started sshd@0-172.31.30.31:22-20.229.252.112:59808.service - OpenSSH per-connection server daemon (20.229.252.112:59808). Apr 24 23:38:50.761665 systemd[1]: issuegen.service: Deactivated successfully. Apr 24 23:38:50.765303 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 24 23:38:50.783420 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 24 23:38:50.822240 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 24 23:38:50.835172 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 24 23:38:50.847539 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 24 23:38:50.853468 systemd[1]: Reached target getty.target - Login Prompts. Apr 24 23:38:50.853835 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 24 23:38:50.854117 systemd[1]: Startup finished in 1.196s (kernel) + 7.826s (initrd) + 9.261s (userspace) = 18.285s. Apr 24 23:38:51.125130 ntpd[1990]: Listen normally on 7 eth0 [fe80::441:b3ff:feea:26d%2]:123 Apr 24 23:38:51.125624 ntpd[1990]: 24 Apr 23:38:51 ntpd[1990]: Listen normally on 7 eth0 [fe80::441:b3ff:feea:26d%2]:123 Apr 24 23:38:51.655505 kubelet[2221]: E0424 23:38:51.655412 2221 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" Apr 24 23:38:51.660063 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 24 23:38:51.660399 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 24 23:38:51.662161 systemd[1]: kubelet.service: Consumed 1.263s CPU time. Apr 24 23:38:51.801432 sshd[2231]: Accepted publickey for core from 20.229.252.112 port 59808 ssh2: RSA SHA256:EpOBCscCvamodiF49drNiIRDMxdv0LtYbixE7WaoRrA Apr 24 23:38:51.804059 sshd[2231]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 24 23:38:51.822007 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 24 23:38:51.835426 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 24 23:38:51.840689 systemd-logind[1995]: New session 1 of user core. Apr 24 23:38:51.859872 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 24 23:38:51.870924 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 24 23:38:51.886630 (systemd)[2252]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 24 23:38:52.121831 systemd[2252]: Queued start job for default target default.target. Apr 24 23:38:52.132698 systemd[2252]: Created slice app.slice - User Application Slice. Apr 24 23:38:52.132764 systemd[2252]: Reached target paths.target - Paths. Apr 24 23:38:52.132799 systemd[2252]: Reached target timers.target - Timers. Apr 24 23:38:52.135655 systemd[2252]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 24 23:38:52.158412 systemd[2252]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 24 23:38:52.158698 systemd[2252]: Reached target sockets.target - Sockets. Apr 24 23:38:52.158748 systemd[2252]: Reached target basic.target - Basic System. Apr 24 23:38:52.158842 systemd[2252]: Reached target default.target - Main User Target. Apr 24 23:38:52.158910 systemd[2252]: Startup finished in 259ms. Apr 24 23:38:52.159085 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 24 23:38:52.172540 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 24 23:38:52.906461 systemd[1]: Started sshd@1-172.31.30.31:22-20.229.252.112:59814.service - OpenSSH per-connection server daemon (20.229.252.112:59814). Apr 24 23:38:53.937983 sshd[2263]: Accepted publickey for core from 20.229.252.112 port 59814 ssh2: RSA SHA256:EpOBCscCvamodiF49drNiIRDMxdv0LtYbixE7WaoRrA Apr 24 23:38:53.939696 sshd[2263]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 24 23:38:53.947062 systemd-logind[1995]: New session 2 of user core. Apr 24 23:38:53.957204 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 24 23:38:53.868085 systemd-resolved[1909]: Clock change detected. Flushing caches. Apr 24 23:38:53.875653 systemd-journald[1573]: Time jumped backwards, rotating. Apr 24 23:38:54.393563 sshd[2263]: pam_unix(sshd:session): session closed for user core Apr 24 23:38:54.399740 systemd[1]: sshd@1-172.31.30.31:22-20.229.252.112:59814.service: Deactivated successfully. Apr 24 23:38:54.402847 systemd[1]: session-2.scope: Deactivated successfully. Apr 24 23:38:54.404063 systemd-logind[1995]: Session 2 logged out. Waiting for processes to exit. Apr 24 23:38:54.407489 systemd-logind[1995]: Removed session 2. Apr 24 23:38:54.575661 systemd[1]: Started sshd@2-172.31.30.31:22-20.229.252.112:59820.service - OpenSSH per-connection server daemon (20.229.252.112:59820). Apr 24 23:38:55.615845 sshd[2271]: Accepted publickey for core from 20.229.252.112 port 59820 ssh2: RSA SHA256:EpOBCscCvamodiF49drNiIRDMxdv0LtYbixE7WaoRrA Apr 24 23:38:55.618512 sshd[2271]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 24 23:38:55.626215 systemd-logind[1995]: New session 3 of user core. Apr 24 23:38:55.638387 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 24 23:38:56.321094 sshd[2271]: pam_unix(sshd:session): session closed for user core Apr 24 23:38:56.327903 systemd-logind[1995]: Session 3 logged out. Waiting for processes to exit. Apr 24 23:38:56.329373 systemd[1]: sshd@2-172.31.30.31:22-20.229.252.112:59820.service: Deactivated successfully. Apr 24 23:38:56.333246 systemd[1]: session-3.scope: Deactivated successfully. Apr 24 23:38:56.335011 systemd-logind[1995]: Removed session 3. Apr 24 23:38:56.502495 systemd[1]: Started sshd@3-172.31.30.31:22-20.229.252.112:58036.service - OpenSSH per-connection server daemon (20.229.252.112:58036). Apr 24 23:38:57.546289 sshd[2278]: Accepted publickey for core from 20.229.252.112 port 58036 ssh2: RSA SHA256:EpOBCscCvamodiF49drNiIRDMxdv0LtYbixE7WaoRrA Apr 24 23:38:57.548952 sshd[2278]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 24 23:38:57.556313 systemd-logind[1995]: New session 4 of user core. Apr 24 23:38:57.568380 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 24 23:38:58.259708 sshd[2278]: pam_unix(sshd:session): session closed for user core Apr 24 23:38:58.264996 systemd-logind[1995]: Session 4 logged out. Waiting for processes to exit. Apr 24 23:38:58.265406 systemd[1]: sshd@3-172.31.30.31:22-20.229.252.112:58036.service: Deactivated successfully. Apr 24 23:38:58.268206 systemd[1]: session-4.scope: Deactivated successfully. Apr 24 23:38:58.272307 systemd-logind[1995]: Removed session 4. Apr 24 23:38:58.443623 systemd[1]: Started sshd@4-172.31.30.31:22-20.229.252.112:58038.service - OpenSSH per-connection server daemon (20.229.252.112:58038). Apr 24 23:38:59.474800 sshd[2285]: Accepted publickey for core from 20.229.252.112 port 58038 ssh2: RSA SHA256:EpOBCscCvamodiF49drNiIRDMxdv0LtYbixE7WaoRrA Apr 24 23:38:59.477423 sshd[2285]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 24 23:38:59.485649 systemd-logind[1995]: New session 5 of user core. Apr 24 23:38:59.495379 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 24 23:39:00.058571 sudo[2288]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 24 23:39:00.059274 sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 24 23:39:01.258382 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 24 23:39:01.258752 systemd[1]: kubelet.service: Consumed 1.263s CPU time. Apr 24 23:39:01.267628 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 24 23:39:01.345336 systemd[1]: Reloading requested from client PID 2326 ('systemctl') (unit session-5.scope)... Apr 24 23:39:01.345363 systemd[1]: Reloading... Apr 24 23:39:01.567219 zram_generator::config[2363]: No configuration found. Apr 24 23:39:01.837373 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. Apr 24 23:39:02.011653 systemd[1]: Reloading finished in 665 ms. Apr 24 23:39:02.101322 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Apr 24 23:39:02.101901 systemd[1]: kubelet.service: Failed with result 'signal'. Apr 24 23:39:02.102533 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 24 23:39:02.113643 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 24 23:39:02.436723 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 24 23:39:02.450923 (kubelet)[2429]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 24 23:39:02.524720 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. Apr 24 23:39:03.306143 kubelet[2429]: I0424 23:39:03.305514 2429 server.go:525] "Kubelet version" kubeletVersion="v1.35.1" Apr 24 23:39:03.306143 kubelet[2429]: I0424 23:39:03.305585 2429 server.go:527] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 24 23:39:03.306143 kubelet[2429]: I0424 23:39:03.305629 2429 watchdog_linux.go:95] "Systemd watchdog is not enabled" Apr 24 23:39:03.306143 kubelet[2429]: I0424 23:39:03.305643 2429 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Apr 24 23:39:03.306518 kubelet[2429]: I0424 23:39:03.306491 2429 server.go:951] "Client rotation is on, will bootstrap in background" Apr 24 23:39:03.312359 kubelet[2429]: I0424 23:39:03.312301 2429 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 24 23:39:03.321214 kubelet[2429]: E0424 23:39:03.320516 2429 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Apr 24 23:39:03.321367 kubelet[2429]: I0424 23:39:03.321273 2429 server.go:1395] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Apr 24 23:39:03.327137 kubelet[2429]: I0424 23:39:03.327064 2429 server.go:775] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Apr 24 23:39:03.327677 kubelet[2429]: I0424 23:39:03.327626 2429 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 24 23:39:03.327933 kubelet[2429]: I0424 23:39:03.327678 2429 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.30.31","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.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"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} Apr 24 23:39:03.328090 kubelet[2429]: I0424 23:39:03.327944 2429 topology_manager.go:143] "Creating topology manager with none policy" Apr 24 23:39:03.328090 kubelet[2429]: I0424 23:39:03.327962 2429 container_manager_linux.go:308] "Creating device plugin manager" Apr 24 23:39:03.328202 kubelet[2429]: I0424 23:39:03.328156 2429 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager" Apr 24 23:39:03.333150 kubelet[2429]: I0424 23:39:03.331726 2429 state_mem.go:41] "Initialized" logger="CPUManager state memory" Apr 24 23:39:03.333150 kubelet[2429]: I0424 23:39:03.331996 2429 kubelet.go:482] "Attempting to sync node with API server" Apr 24 23:39:03.333150 kubelet[2429]: I0424 23:39:03.332034 2429 kubelet.go:383] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 24 23:39:03.333150 kubelet[2429]: I0424 23:39:03.332069 2429 kubelet.go:394] "Adding apiserver pod source" Apr 24 23:39:03.333150 kubelet[2429]: I0424 23:39:03.332094 2429 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 24 23:39:03.333150 kubelet[2429]: E0424 23:39:03.332920 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:03.333150 kubelet[2429]: E0424 23:39:03.333020 2429 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:03.338376 kubelet[2429]: I0424 23:39:03.338339 2429 kuberuntime_manager.go:294] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Apr 24 23:39:03.340751 kubelet[2429]: I0424 23:39:03.340709 2429 kubelet.go:943] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Apr 24 23:39:03.340948 kubelet[2429]: I0424 23:39:03.340925 2429 kubelet.go:970] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Apr 24 23:39:03.341135 kubelet[2429]: W0424 23:39:03.341092 2429 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 24 23:39:03.345902 kubelet[2429]: I0424 23:39:03.345869 2429 server.go:1257] "Started kubelet" Apr 24 23:39:03.351143 kubelet[2429]: I0424 23:39:03.349557 2429 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 24 23:39:03.351143 kubelet[2429]: I0424 23:39:03.349730 2429 server_v1.go:49] "podresources" method="list" useActivePods=true Apr 24 23:39:03.351143 kubelet[2429]: I0424 23:39:03.350353 2429 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 24 23:39:03.351143 kubelet[2429]: I0424 23:39:03.350456 2429 server.go:182] "Starting to listen" address="0.0.0.0" port=10250 Apr 24 23:39:03.351588 kubelet[2429]: I0424 23:39:03.351558 2429 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Apr 24 23:39:03.352314 kubelet[2429]: I0424 23:39:03.352264 2429 server.go:317] "Adding debug handlers to kubelet server" Apr 24 23:39:03.373211 kubelet[2429]: I0424 23:39:03.372345 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" Apr 24 23:39:03.378807 kubelet[2429]: I0424 23:39:03.378750 2429 volume_manager.go:311] "Starting Kubelet Volume Manager" Apr 24 23:39:03.379459 kubelet[2429]: E0424 23:39:03.379412 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:03.380274 kubelet[2429]: I0424 23:39:03.380240 2429 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Apr 24 23:39:03.380349 kubelet[2429]: I0424 23:39:03.380336 2429 reconciler.go:29] "Reconciler: start to sync state" Apr 24 23:39:03.384233 kubelet[2429]: E0424 23:39:03.383185 2429 reflector.go:204] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Service" Apr 24 23:39:03.386595 kubelet[2429]: I0424 23:39:03.386551 2429 factory.go:223] Registration of the systemd container factory successfully Apr 24 23:39:03.387040 kubelet[2429]: I0424 23:39:03.386999 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 Apr 24 23:39:03.390470 kubelet[2429]: E0424 23:39:03.390406 2429 controller.go:201] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.30.31\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Apr 24 23:39:03.390812 kubelet[2429]: E0424 23:39:03.390779 2429 reflector.go:204] "Failed to watch" err="failed to list *v1.Node: nodes \"172.31.30.31\" 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:161" type="*v1.Node" Apr 24 23:39:03.393524 kubelet[2429]: E0424 23:39:03.391188 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.30.31.18a96f560cbaf9b3 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.30.31,UID:,APIVersion:v1,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.30.31,},FirstTimestamp:2026-04-24 23:39:03.345822131 +0000 UTC m=+0.888025782,LastTimestamp:2026-04-24 23:39:03.345822131 +0000 UTC m=+0.888025782,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.30.31,}" Apr 24 23:39:03.397446 kubelet[2429]: E0424 23:39:03.397402 2429 kubelet.go:1656] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 24 23:39:03.398509 kubelet[2429]: I0424 23:39:03.398347 2429 factory.go:223] Registration of the containerd container factory successfully Apr 24 23:39:03.402539 kubelet[2429]: E0424 23:39:03.402090 2429 reflector.go:204] "Failed to watch" err="failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.CSIDriver" Apr 24 23:39:03.421918 kubelet[2429]: E0424 23:39:03.421778 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.30.31.18a96f560fcd958f default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.30.31,UID:,APIVersion:v1,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:172.31.30.31,},FirstTimestamp:2026-04-24 23:39:03.397373327 +0000 UTC m=+0.939576978,LastTimestamp:2026-04-24 23:39:03.397373327 +0000 UTC m=+0.939576978,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.30.31,}" Apr 24 23:39:03.435249 kubelet[2429]: I0424 23:39:03.435216 2429 cpu_manager.go:225] "Starting" policy="none" Apr 24 23:39:03.435403 kubelet[2429]: I0424 23:39:03.435381 2429 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s" Apr 24 23:39:03.435507 kubelet[2429]: I0424 23:39:03.435486 2429 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Apr 24 23:39:03.438973 kubelet[2429]: I0424 23:39:03.438942 2429 policy_none.go:50] "Start" Apr 24 23:39:03.439182 kubelet[2429]: I0424 23:39:03.439160 2429 memory_manager.go:187] "Starting memorymanager" policy="None" Apr 24 23:39:03.439291 kubelet[2429]: I0424 23:39:03.439271 2429 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Apr 24 23:39:03.444861 kubelet[2429]: I0424 23:39:03.444825 2429 policy_none.go:44] "Start" Apr 24 23:39:03.471226 sudo[2288]: pam_unix(sudo:session): session closed for user root Apr 24 23:39:03.474488 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 24 23:39:03.480650 kubelet[2429]: E0424 23:39:03.480206 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:03.493217 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 24 23:39:03.504675 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 24 23:39:03.515981 kubelet[2429]: E0424 23:39:03.515919 2429 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Apr 24 23:39:03.516319 kubelet[2429]: I0424 23:39:03.516265 2429 eviction_manager.go:194] "Eviction manager: starting control loop" Apr 24 23:39:03.516386 kubelet[2429]: I0424 23:39:03.516297 2429 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 24 23:39:03.517391 kubelet[2429]: I0424 23:39:03.517272 2429 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Apr 24 23:39:03.528226 kubelet[2429]: E0424 23:39:03.527786 2429 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Apr 24 23:39:03.528226 kubelet[2429]: E0424 23:39:03.527869 2429 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.30.31\" not found" Apr 24 23:39:03.540864 kubelet[2429]: I0424 23:39:03.540564 2429 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Apr 24 23:39:03.543150 kubelet[2429]: I0424 23:39:03.542832 2429 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Apr 24 23:39:03.543150 kubelet[2429]: I0424 23:39:03.542873 2429 status_manager.go:249] "Starting to sync pod status with apiserver" Apr 24 23:39:03.543150 kubelet[2429]: I0424 23:39:03.542907 2429 kubelet.go:2501] "Starting kubelet main sync loop" Apr 24 23:39:03.543150 kubelet[2429]: E0424 23:39:03.542977 2429 kubelet.go:2525] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Apr 24 23:39:03.597181 kubelet[2429]: E0424 23:39:03.595790 2429 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.30.31\" not found" node="172.31.30.31" Apr 24 23:39:03.620762 kubelet[2429]: I0424 23:39:03.620147 2429 kubelet_node_status.go:74] "Attempting to register node" node="172.31.30.31" Apr 24 23:39:03.626625 kubelet[2429]: I0424 23:39:03.626573 2429 kubelet_node_status.go:77] "Successfully registered node" node="172.31.30.31" Apr 24 23:39:03.626848 kubelet[2429]: E0424 23:39:03.626816 2429 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.30.31\": node \"172.31.30.31\" not found" Apr 24 23:39:03.641476 sshd[2285]: pam_unix(sshd:session): session closed for user core Apr 24 23:39:03.647319 kubelet[2429]: E0424 23:39:03.647263 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:03.649886 systemd[1]: sshd@4-172.31.30.31:22-20.229.252.112:58038.service: Deactivated successfully. Apr 24 23:39:03.655831 systemd[1]: session-5.scope: Deactivated successfully. Apr 24 23:39:03.660524 systemd-logind[1995]: Session 5 logged out. Waiting for processes to exit. Apr 24 23:39:03.663676 systemd-logind[1995]: Removed session 5. Apr 24 23:39:03.748066 kubelet[2429]: E0424 23:39:03.747970 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:03.848283 kubelet[2429]: E0424 23:39:03.848143 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:03.948836 kubelet[2429]: E0424 23:39:03.948787 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.049568 kubelet[2429]: E0424 23:39:04.049515 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.150243 kubelet[2429]: E0424 23:39:04.150186 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.250988 kubelet[2429]: E0424 23:39:04.250923 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.309518 kubelet[2429]: I0424 23:39:04.309452 2429 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 24 23:39:04.309773 kubelet[2429]: I0424 23:39:04.309682 2429 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Apr 24 23:39:04.309773 kubelet[2429]: I0424 23:39:04.309707 2429 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Apr 24 23:39:04.333091 kubelet[2429]: E0424 23:39:04.333039 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:04.351058 kubelet[2429]: E0424 23:39:04.351001 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.451536 kubelet[2429]: E0424 23:39:04.451377 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.552163 kubelet[2429]: E0424 23:39:04.552093 2429 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.30.31\" not found" Apr 24 23:39:04.653812 kubelet[2429]: I0424 23:39:04.653607 2429 kuberuntime_manager.go:2062] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 24 23:39:04.654912 containerd[2005]: time="2026-04-24T23:39:04.654272941Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 24 23:39:04.655449 kubelet[2429]: I0424 23:39:04.654609 2429 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 24 23:39:05.333928 kubelet[2429]: E0424 23:39:05.333867 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:05.336212 kubelet[2429]: I0424 23:39:05.336183 2429 apiserver.go:52] "Watching apiserver" Apr 24 23:39:05.369274 systemd[1]: Created slice kubepods-burstable-pod651a6fce_e9e3_4e51_bd4b_51f97cd5902b.slice - libcontainer container kubepods-burstable-pod651a6fce_e9e3_4e51_bd4b_51f97cd5902b.slice. Apr 24 23:39:05.381279 kubelet[2429]: I0424 23:39:05.381211 2429 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Apr 24 23:39:05.388690 systemd[1]: Created slice kubepods-besteffort-pod49327e39_663a_4445_a85c_95482149cc04.slice - libcontainer container kubepods-besteffort-pod49327e39_663a_4445_a85c_95482149cc04.slice. Apr 24 23:39:05.391033 kubelet[2429]: I0424 23:39:05.390987 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-run\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.391228 kubelet[2429]: I0424 23:39:05.391079 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-cni-plugin\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.391293 kubelet[2429]: I0424 23:39:05.391274 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-cni\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.391485 kubelet[2429]: I0424 23:39:05.391438 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-xtables-lock\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.391658 kubelet[2429]: I0424 23:39:05.391607 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lgg9r\" (UniqueName: \"kubernetes.io/projected/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-kube-api-access-lgg9r\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.392148 kubelet[2429]: I0424 23:39:05.391691 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/49327e39-663a-4445-a85c-95482149cc04-kube-proxy\") pod \"kube-proxy-9phlz\" (UID: \"49327e39-663a-4445-a85c-95482149cc04\") " pod="kube-system/kube-proxy-9phlz" Apr 24 23:39:05.392148 kubelet[2429]: I0424 23:39:05.391774 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/651a6fce-e9e3-4e51-bd4b-51f97cd5902b-flannel-cfg\") pod \"kube-flannel-ds-4kqjn\" (UID: \"651a6fce-e9e3-4e51-bd4b-51f97cd5902b\") " pod="kube-flannel/kube-flannel-ds-4kqjn" Apr 24 23:39:05.392148 kubelet[2429]: I0424 23:39:05.391815 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/49327e39-663a-4445-a85c-95482149cc04-xtables-lock\") pod \"kube-proxy-9phlz\" (UID: \"49327e39-663a-4445-a85c-95482149cc04\") " pod="kube-system/kube-proxy-9phlz" Apr 24 23:39:05.392148 kubelet[2429]: I0424 23:39:05.391880 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/49327e39-663a-4445-a85c-95482149cc04-lib-modules\") pod \"kube-proxy-9phlz\" (UID: \"49327e39-663a-4445-a85c-95482149cc04\") " pod="kube-system/kube-proxy-9phlz" Apr 24 23:39:05.392148 kubelet[2429]: I0424 23:39:05.391973 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dk77g\" (UniqueName: \"kubernetes.io/projected/49327e39-663a-4445-a85c-95482149cc04-kube-api-access-dk77g\") pod \"kube-proxy-9phlz\" (UID: \"49327e39-663a-4445-a85c-95482149cc04\") " pod="kube-system/kube-proxy-9phlz" Apr 24 23:39:05.689134 containerd[2005]: time="2026-04-24T23:39:05.689032226Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4kqjn,Uid:651a6fce-e9e3-4e51-bd4b-51f97cd5902b,Namespace:kube-flannel,Attempt:0,}" Apr 24 23:39:05.705043 containerd[2005]: time="2026-04-24T23:39:05.704968814Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9phlz,Uid:49327e39-663a-4445-a85c-95482149cc04,Namespace:kube-system,Attempt:0,}" Apr 24 23:39:06.227021 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2216068728.mount: Deactivated successfully. Apr 24 23:39:06.238993 containerd[2005]: time="2026-04-24T23:39:06.238908805Z" 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\"}" Apr 24 23:39:06.241023 containerd[2005]: time="2026-04-24T23:39:06.240931993Z" 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\"}" Apr 24 23:39:06.242032 containerd[2005]: time="2026-04-24T23:39:06.241976437Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 24 23:39:06.243170 containerd[2005]: time="2026-04-24T23:39:06.243123169Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Apr 24 23:39:06.245839 containerd[2005]: time="2026-04-24T23:39:06.244408585Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 24 23:39:06.250587 containerd[2005]: time="2026-04-24T23:39:06.250501417Z" 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\"}" Apr 24 23:39:06.254008 containerd[2005]: time="2026-04-24T23:39:06.253717597Z" 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 548.616339ms" Apr 24 23:39:06.255922 containerd[2005]: time="2026-04-24T23:39:06.255871009Z" 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 566.638047ms" Apr 24 23:39:06.334182 kubelet[2429]: E0424 23:39:06.334092 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:06.512612 containerd[2005]: time="2026-04-24T23:39:06.511038182Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 24 23:39:06.512612 containerd[2005]: time="2026-04-24T23:39:06.511176542Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 24 23:39:06.512612 containerd[2005]: time="2026-04-24T23:39:06.511203818Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:06.514942 containerd[2005]: time="2026-04-24T23:39:06.513486374Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:06.514942 containerd[2005]: time="2026-04-24T23:39:06.512350178Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 24 23:39:06.514942 containerd[2005]: time="2026-04-24T23:39:06.512498570Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 24 23:39:06.514942 containerd[2005]: time="2026-04-24T23:39:06.512537078Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:06.517684 containerd[2005]: time="2026-04-24T23:39:06.516787274Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:06.706600 systemd[1]: Started cri-containerd-4e92aa97ab7fa12ceab6ff66b9ea2a93fb0fea75ff5bef9b2b1be6becc552c8d.scope - libcontainer container 4e92aa97ab7fa12ceab6ff66b9ea2a93fb0fea75ff5bef9b2b1be6becc552c8d. Apr 24 23:39:06.711359 systemd[1]: Started cri-containerd-e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94.scope - libcontainer container e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94. Apr 24 23:39:06.778918 containerd[2005]: time="2026-04-24T23:39:06.777846016Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9phlz,Uid:49327e39-663a-4445-a85c-95482149cc04,Namespace:kube-system,Attempt:0,} returns sandbox id \"4e92aa97ab7fa12ceab6ff66b9ea2a93fb0fea75ff5bef9b2b1be6becc552c8d\"" Apr 24 23:39:06.786267 containerd[2005]: time="2026-04-24T23:39:06.786075976Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.4\"" Apr 24 23:39:06.805727 containerd[2005]: time="2026-04-24T23:39:06.805607020Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4kqjn,Uid:651a6fce-e9e3-4e51-bd4b-51f97cd5902b,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\"" Apr 24 23:39:07.334798 kubelet[2429]: E0424 23:39:07.334612 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:08.071465 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2482975094.mount: Deactivated successfully. Apr 24 23:39:08.335418 kubelet[2429]: E0424 23:39:08.335092 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:08.459016 containerd[2005]: time="2026-04-24T23:39:08.458934832Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.35.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:08.461473 containerd[2005]: time="2026-04-24T23:39:08.461407372Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.35.4: active requests=0, bytes read=22340584" Apr 24 23:39:08.463637 containerd[2005]: time="2026-04-24T23:39:08.463583572Z" level=info msg="ImageCreate event name:\"sha256:8c75fb69e773da539298848d12a0a12029818ee910a62f2abd68aa1a5805991c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:08.475164 containerd[2005]: time="2026-04-24T23:39:08.474644020Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:c5daa23c72474e5e4062c320177d3b485fd42e7010f052bc80d657c4c00a0672\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:08.476940 containerd[2005]: time="2026-04-24T23:39:08.476861596Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.35.4\" with image id \"sha256:8c75fb69e773da539298848d12a0a12029818ee910a62f2abd68aa1a5805991c\", repo tag \"registry.k8s.io/kube-proxy:v1.35.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:c5daa23c72474e5e4062c320177d3b485fd42e7010f052bc80d657c4c00a0672\", size \"22339603\" in 1.690395296s" Apr 24 23:39:08.476940 containerd[2005]: time="2026-04-24T23:39:08.476931544Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.4\" returns image reference \"sha256:8c75fb69e773da539298848d12a0a12029818ee910a62f2abd68aa1a5805991c\"" Apr 24 23:39:08.480573 containerd[2005]: time="2026-04-24T23:39:08.480473680Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Apr 24 23:39:08.489036 containerd[2005]: time="2026-04-24T23:39:08.488983816Z" level=info msg="CreateContainer within sandbox \"4e92aa97ab7fa12ceab6ff66b9ea2a93fb0fea75ff5bef9b2b1be6becc552c8d\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 24 23:39:08.513566 containerd[2005]: time="2026-04-24T23:39:08.513345580Z" level=info msg="CreateContainer within sandbox \"4e92aa97ab7fa12ceab6ff66b9ea2a93fb0fea75ff5bef9b2b1be6becc552c8d\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"77fff5e3dc9e3b1cba15c480a2539917b908f36fed8bf93234cc6098971ec651\"" Apr 24 23:39:08.515558 containerd[2005]: time="2026-04-24T23:39:08.515508412Z" level=info msg="StartContainer for \"77fff5e3dc9e3b1cba15c480a2539917b908f36fed8bf93234cc6098971ec651\"" Apr 24 23:39:08.585441 systemd[1]: Started cri-containerd-77fff5e3dc9e3b1cba15c480a2539917b908f36fed8bf93234cc6098971ec651.scope - libcontainer container 77fff5e3dc9e3b1cba15c480a2539917b908f36fed8bf93234cc6098971ec651. Apr 24 23:39:08.639667 containerd[2005]: time="2026-04-24T23:39:08.639406781Z" level=info msg="StartContainer for \"77fff5e3dc9e3b1cba15c480a2539917b908f36fed8bf93234cc6098971ec651\" returns successfully" Apr 24 23:39:09.336360 kubelet[2429]: E0424 23:39:09.336251 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:09.780379 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2638583539.mount: Deactivated successfully. Apr 24 23:39:09.838433 containerd[2005]: time="2026-04-24T23:39:09.838354579Z" 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\"}" Apr 24 23:39:09.840320 containerd[2005]: time="2026-04-24T23:39:09.839690383Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Apr 24 23:39:09.841620 containerd[2005]: time="2026-04-24T23:39:09.841556839Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:09.849719 containerd[2005]: time="2026-04-24T23:39:09.849651343Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:09.854557 containerd[2005]: time="2026-04-24T23:39:09.854351323Z" 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.373786815s" Apr 24 23:39:09.854557 containerd[2005]: time="2026-04-24T23:39:09.854423479Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Apr 24 23:39:09.860845 containerd[2005]: time="2026-04-24T23:39:09.860623663Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 24 23:39:09.880909 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3728145374.mount: Deactivated successfully. Apr 24 23:39:09.886470 containerd[2005]: time="2026-04-24T23:39:09.886300219Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d\"" Apr 24 23:39:09.888767 containerd[2005]: time="2026-04-24T23:39:09.887304283Z" level=info msg="StartContainer for \"cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d\"" Apr 24 23:39:09.939429 systemd[1]: Started cri-containerd-cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d.scope - libcontainer container cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d. Apr 24 23:39:09.986214 containerd[2005]: time="2026-04-24T23:39:09.986120636Z" level=info msg="StartContainer for \"cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d\" returns successfully" Apr 24 23:39:09.988996 systemd[1]: cri-containerd-cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d.scope: Deactivated successfully. Apr 24 23:39:10.158720 containerd[2005]: time="2026-04-24T23:39:10.158383144Z" level=info msg="shim disconnected" id=cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d namespace=k8s.io Apr 24 23:39:10.158720 containerd[2005]: time="2026-04-24T23:39:10.158457700Z" level=warning msg="cleaning up after shim disconnected" id=cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d namespace=k8s.io Apr 24 23:39:10.158720 containerd[2005]: time="2026-04-24T23:39:10.158477764Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 24 23:39:10.178945 containerd[2005]: time="2026-04-24T23:39:10.178788869Z" level=warning msg="cleanup warnings time=\"2026-04-24T23:39:10Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Apr 24 23:39:10.338058 kubelet[2429]: E0424 23:39:10.337580 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:10.592278 containerd[2005]: time="2026-04-24T23:39:10.592054315Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Apr 24 23:39:10.599398 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-cc101bbbe5c8535b91b78a7db3b2ac7e22d2c997ee9e7bcc4dc64138b6b91b7d-rootfs.mount: Deactivated successfully. Apr 24 23:39:10.613858 kubelet[2429]: I0424 23:39:10.613576 2429 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-system/kube-proxy-9phlz" podStartSLOduration=5.919328767 podStartE2EDuration="7.613555519s" podCreationTimestamp="2026-04-24 23:39:03 +0000 UTC" firstStartedPulling="2026-04-24 23:39:06.78537436 +0000 UTC m=+4.327577987" lastFinishedPulling="2026-04-24 23:39:08.479601028 +0000 UTC m=+6.021804739" observedRunningTime="2026-04-24 23:39:09.602835426 +0000 UTC m=+7.145039077" watchObservedRunningTime="2026-04-24 23:39:10.613555519 +0000 UTC m=+8.155759158" Apr 24 23:39:11.338447 kubelet[2429]: E0424 23:39:11.338375 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:12.339627 kubelet[2429]: E0424 23:39:12.338656 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:13.105400 containerd[2005]: time="2026-04-24T23:39:13.105304339Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:13.108254 containerd[2005]: time="2026-04-24T23:39:13.108171127Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Apr 24 23:39:13.110441 containerd[2005]: time="2026-04-24T23:39:13.110358055Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:13.120156 containerd[2005]: time="2026-04-24T23:39:13.119423959Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:13.122860 containerd[2005]: time="2026-04-24T23:39:13.122633587Z" 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.530433184s" Apr 24 23:39:13.122860 containerd[2005]: time="2026-04-24T23:39:13.122708959Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Apr 24 23:39:13.132658 containerd[2005]: time="2026-04-24T23:39:13.131965459Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 24 23:39:13.154027 containerd[2005]: time="2026-04-24T23:39:13.153969139Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9\"" Apr 24 23:39:13.155981 containerd[2005]: time="2026-04-24T23:39:13.155838451Z" level=info msg="StartContainer for \"2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9\"" Apr 24 23:39:13.211445 systemd[1]: Started cri-containerd-2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9.scope - libcontainer container 2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9. Apr 24 23:39:13.258740 containerd[2005]: time="2026-04-24T23:39:13.258562340Z" level=info msg="StartContainer for \"2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9\" returns successfully" Apr 24 23:39:13.259501 systemd[1]: cri-containerd-2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9.scope: Deactivated successfully. Apr 24 23:39:13.298248 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9-rootfs.mount: Deactivated successfully. Apr 24 23:39:13.339743 kubelet[2429]: E0424 23:39:13.339684 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:13.346131 kubelet[2429]: I0424 23:39:13.341946 2429 kubelet_node_status.go:427] "Fast updating node status as it just became ready" Apr 24 23:39:13.443800 containerd[2005]: time="2026-04-24T23:39:13.443710533Z" level=info msg="shim disconnected" id=2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9 namespace=k8s.io Apr 24 23:39:13.443800 containerd[2005]: time="2026-04-24T23:39:13.443784105Z" level=warning msg="cleaning up after shim disconnected" id=2d827d9e3a689a9115d7ec08eaa8b1b817418009a6051cddbf85abf4798704e9 namespace=k8s.io Apr 24 23:39:13.444060 containerd[2005]: time="2026-04-24T23:39:13.443805921Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 24 23:39:13.605673 containerd[2005]: time="2026-04-24T23:39:13.605596474Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 24 23:39:13.625383 containerd[2005]: time="2026-04-24T23:39:13.625297354Z" level=info msg="CreateContainer within sandbox \"e30ad556c644f056be8d44a4f7e4244f7ef47ab53e2da9e120638a73599d3c94\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b1ceb1ad790820217cb32c53d6dc9ab097f4e025e40d73094222bad1de905c0f\"" Apr 24 23:39:13.628195 containerd[2005]: time="2026-04-24T23:39:13.626598226Z" level=info msg="StartContainer for \"b1ceb1ad790820217cb32c53d6dc9ab097f4e025e40d73094222bad1de905c0f\"" Apr 24 23:39:13.669467 systemd[1]: Started cri-containerd-b1ceb1ad790820217cb32c53d6dc9ab097f4e025e40d73094222bad1de905c0f.scope - libcontainer container b1ceb1ad790820217cb32c53d6dc9ab097f4e025e40d73094222bad1de905c0f. Apr 24 23:39:13.717193 containerd[2005]: time="2026-04-24T23:39:13.716990014Z" level=info msg="StartContainer for \"b1ceb1ad790820217cb32c53d6dc9ab097f4e025e40d73094222bad1de905c0f\" returns successfully" Apr 24 23:39:14.340585 kubelet[2429]: E0424 23:39:14.340520 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:14.837084 (udev-worker)[2938]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:39:14.859233 systemd-networkd[1908]: flannel.1: Link UP Apr 24 23:39:14.859248 systemd-networkd[1908]: flannel.1: Gained carrier Apr 24 23:39:15.340840 kubelet[2429]: E0424 23:39:15.340773 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:16.341162 kubelet[2429]: E0424 23:39:16.341072 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:16.758385 systemd-networkd[1908]: flannel.1: Gained IPv6LL Apr 24 23:39:17.342241 kubelet[2429]: E0424 23:39:17.342170 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:17.672130 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 24 23:39:18.342918 kubelet[2429]: E0424 23:39:18.342851 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:18.867307 ntpd[1990]: Listen normally on 8 flannel.1 192.168.1.0:123 Apr 24 23:39:18.869467 ntpd[1990]: 24 Apr 23:39:18 ntpd[1990]: Listen normally on 8 flannel.1 192.168.1.0:123 Apr 24 23:39:18.869467 ntpd[1990]: 24 Apr 23:39:18 ntpd[1990]: Listen normally on 9 flannel.1 [fe80::c833:ecff:fe21:32fa%3]:123 Apr 24 23:39:18.867431 ntpd[1990]: Listen normally on 9 flannel.1 [fe80::c833:ecff:fe21:32fa%3]:123 Apr 24 23:39:19.343488 kubelet[2429]: E0424 23:39:19.343357 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:20.344498 kubelet[2429]: E0424 23:39:20.344426 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:21.344898 kubelet[2429]: E0424 23:39:21.344827 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:21.576124 kubelet[2429]: I0424 23:39:21.575998 2429 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-4kqjn" podStartSLOduration=11.784923803 podStartE2EDuration="18.575977925s" podCreationTimestamp="2026-04-24 23:39:03 +0000 UTC" firstStartedPulling="2026-04-24 23:39:06.808644556 +0000 UTC m=+4.350848207" lastFinishedPulling="2026-04-24 23:39:13.59969869 +0000 UTC m=+11.141902329" observedRunningTime="2026-04-24 23:39:14.625755047 +0000 UTC m=+12.167958698" watchObservedRunningTime="2026-04-24 23:39:21.575977925 +0000 UTC m=+19.118181564" Apr 24 23:39:21.592807 systemd[1]: Created slice kubepods-besteffort-pod6b69f3c2_1063_4ffe_a39e_f6df32f09da1.slice - libcontainer container kubepods-besteffort-pod6b69f3c2_1063_4ffe_a39e_f6df32f09da1.slice. Apr 24 23:39:21.601135 kubelet[2429]: I0424 23:39:21.600929 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zrsmk\" (UniqueName: \"kubernetes.io/projected/6b69f3c2-1063-4ffe-a39e-f6df32f09da1-kube-api-access-zrsmk\") pod \"nginx-deployment-6cc69d4fc7-sqjp7\" (UID: \"6b69f3c2-1063-4ffe-a39e-f6df32f09da1\") " pod="default/nginx-deployment-6cc69d4fc7-sqjp7" Apr 24 23:39:21.901224 containerd[2005]: time="2026-04-24T23:39:21.901149091Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-sqjp7,Uid:6b69f3c2-1063-4ffe-a39e-f6df32f09da1,Namespace:default,Attempt:0,}" Apr 24 23:39:21.943624 systemd-networkd[1908]: cni0: Link UP Apr 24 23:39:21.943647 systemd-networkd[1908]: cni0: Gained carrier Apr 24 23:39:21.949438 (udev-worker)[3033]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:39:21.949592 systemd-networkd[1908]: cni0: Lost carrier Apr 24 23:39:21.974380 (udev-worker)[3035]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:39:21.976719 systemd-networkd[1908]: vethd7768797: Link UP Apr 24 23:39:21.981083 kernel: cni0: port 1(vethd7768797) entered blocking state Apr 24 23:39:21.981226 kernel: cni0: port 1(vethd7768797) entered disabled state Apr 24 23:39:21.981266 kernel: vethd7768797: entered allmulticast mode Apr 24 23:39:21.983679 kernel: vethd7768797: entered promiscuous mode Apr 24 23:39:21.986882 kernel: cni0: port 1(vethd7768797) entered blocking state Apr 24 23:39:21.986962 kernel: cni0: port 1(vethd7768797) entered forwarding state Apr 24 23:39:21.989146 kernel: cni0: port 1(vethd7768797) entered disabled state Apr 24 23:39:22.010237 kernel: cni0: port 1(vethd7768797) entered blocking state Apr 24 23:39:22.010316 kernel: cni0: port 1(vethd7768797) entered forwarding state Apr 24 23:39:22.009303 systemd-networkd[1908]: vethd7768797: Gained carrier Apr 24 23:39:22.011633 systemd-networkd[1908]: cni0: Gained carrier Apr 24 23:39:22.015630 containerd[2005]: 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"} Apr 24 23:39:22.015630 containerd[2005]: delegateAdd: netconf sent to delegate plugin: Apr 24 23:39:22.053441 containerd[2005]: {"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="2026-04-24T23:39:22.053221924Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 24 23:39:22.053441 containerd[2005]: time="2026-04-24T23:39:22.053353228Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 24 23:39:22.053800 containerd[2005]: time="2026-04-24T23:39:22.053405800Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:22.054084 containerd[2005]: time="2026-04-24T23:39:22.053980948Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:22.091438 systemd[1]: Started cri-containerd-f3cebfa4ad3c1e7356bedd8145ec9f60faa9a5f1c900f9d97cc77f42b02c5783.scope - libcontainer container f3cebfa4ad3c1e7356bedd8145ec9f60faa9a5f1c900f9d97cc77f42b02c5783. Apr 24 23:39:22.152578 containerd[2005]: time="2026-04-24T23:39:22.152135164Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-sqjp7,Uid:6b69f3c2-1063-4ffe-a39e-f6df32f09da1,Namespace:default,Attempt:0,} returns sandbox id \"f3cebfa4ad3c1e7356bedd8145ec9f60faa9a5f1c900f9d97cc77f42b02c5783\"" Apr 24 23:39:22.156675 containerd[2005]: time="2026-04-24T23:39:22.156575668Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 24 23:39:22.345570 kubelet[2429]: E0424 23:39:22.345495 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:23.332412 kubelet[2429]: E0424 23:39:23.332332 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:23.347428 kubelet[2429]: E0424 23:39:23.347323 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:23.350886 systemd-networkd[1908]: vethd7768797: Gained IPv6LL Apr 24 23:39:23.415280 systemd-networkd[1908]: cni0: Gained IPv6LL Apr 24 23:39:24.348338 kubelet[2429]: E0424 23:39:24.348274 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:25.148553 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1523902724.mount: Deactivated successfully. Apr 24 23:39:25.349363 kubelet[2429]: E0424 23:39:25.349283 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:25.867460 ntpd[1990]: Listen normally on 10 cni0 192.168.1.1:123 Apr 24 23:39:25.867606 ntpd[1990]: Listen normally on 11 cni0 [fe80::5034:e0ff:fe9f:8584%4]:123 Apr 24 23:39:25.867996 ntpd[1990]: 24 Apr 23:39:25 ntpd[1990]: Listen normally on 10 cni0 192.168.1.1:123 Apr 24 23:39:25.867996 ntpd[1990]: 24 Apr 23:39:25 ntpd[1990]: Listen normally on 11 cni0 [fe80::5034:e0ff:fe9f:8584%4]:123 Apr 24 23:39:25.867996 ntpd[1990]: 24 Apr 23:39:25 ntpd[1990]: Listen normally on 12 vethd7768797 [fe80::406b:11ff:fe65:2f7c%5]:123 Apr 24 23:39:25.867690 ntpd[1990]: Listen normally on 12 vethd7768797 [fe80::406b:11ff:fe65:2f7c%5]:123 Apr 24 23:39:26.350776 kubelet[2429]: E0424 23:39:26.350407 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:26.517609 containerd[2005]: time="2026-04-24T23:39:26.517541278Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:26.519502 containerd[2005]: time="2026-04-24T23:39:26.519444826Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=65841543" Apr 24 23:39:26.520780 containerd[2005]: time="2026-04-24T23:39:26.520577722Z" level=info msg="ImageCreate event name:\"sha256:4e83b0a153edcfe297a96c14b2b93b7730f0a5dbb64dcf4efd27b58460fd74d1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:26.528035 containerd[2005]: time="2026-04-24T23:39:26.527432890Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:6f170eb5f8ea893d94fe3c05cef69270f16614a99d3d922ce8a320efc43b8591\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:26.530337 containerd[2005]: time="2026-04-24T23:39:26.529843894Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4e83b0a153edcfe297a96c14b2b93b7730f0a5dbb64dcf4efd27b58460fd74d1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:6f170eb5f8ea893d94fe3c05cef69270f16614a99d3d922ce8a320efc43b8591\", size \"65841421\" in 4.373150434s" Apr 24 23:39:26.530337 containerd[2005]: time="2026-04-24T23:39:26.529902550Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4e83b0a153edcfe297a96c14b2b93b7730f0a5dbb64dcf4efd27b58460fd74d1\"" Apr 24 23:39:26.537720 containerd[2005]: time="2026-04-24T23:39:26.537559054Z" level=info msg="CreateContainer within sandbox \"f3cebfa4ad3c1e7356bedd8145ec9f60faa9a5f1c900f9d97cc77f42b02c5783\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 24 23:39:26.560848 containerd[2005]: time="2026-04-24T23:39:26.560783854Z" level=info msg="CreateContainer within sandbox \"f3cebfa4ad3c1e7356bedd8145ec9f60faa9a5f1c900f9d97cc77f42b02c5783\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"307757bfd360af10985e6f5e2476d9f39be814b7264ada1c815e908aa4eeaf4e\"" Apr 24 23:39:26.561675 containerd[2005]: time="2026-04-24T23:39:26.561503194Z" level=info msg="StartContainer for \"307757bfd360af10985e6f5e2476d9f39be814b7264ada1c815e908aa4eeaf4e\"" Apr 24 23:39:26.612540 systemd[1]: Started cri-containerd-307757bfd360af10985e6f5e2476d9f39be814b7264ada1c815e908aa4eeaf4e.scope - libcontainer container 307757bfd360af10985e6f5e2476d9f39be814b7264ada1c815e908aa4eeaf4e. Apr 24 23:39:26.663302 containerd[2005]: time="2026-04-24T23:39:26.662313034Z" level=info msg="StartContainer for \"307757bfd360af10985e6f5e2476d9f39be814b7264ada1c815e908aa4eeaf4e\" returns successfully" Apr 24 23:39:27.351427 kubelet[2429]: E0424 23:39:27.351371 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:27.660952 kubelet[2429]: I0424 23:39:27.660528 2429 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nginx-deployment-6cc69d4fc7-sqjp7" podStartSLOduration=2.284670857 podStartE2EDuration="6.660508727s" podCreationTimestamp="2026-04-24 23:39:21 +0000 UTC" firstStartedPulling="2026-04-24 23:39:22.15582298 +0000 UTC m=+19.698026619" lastFinishedPulling="2026-04-24 23:39:26.53166085 +0000 UTC m=+24.073864489" observedRunningTime="2026-04-24 23:39:27.660489311 +0000 UTC m=+25.202692950" watchObservedRunningTime="2026-04-24 23:39:27.660508727 +0000 UTC m=+25.202712366" Apr 24 23:39:28.352455 kubelet[2429]: E0424 23:39:28.352396 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:29.354237 kubelet[2429]: E0424 23:39:29.354171 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:30.354829 kubelet[2429]: E0424 23:39:30.354764 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:31.355487 kubelet[2429]: E0424 23:39:31.355430 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:32.318174 update_engine[1997]: I20260424 23:39:32.317330 1997 update_attempter.cc:509] Updating boot flags... Apr 24 23:39:32.355643 kubelet[2429]: E0424 23:39:32.355583 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:32.394233 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (3234) Apr 24 23:39:32.676200 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (3234) Apr 24 23:39:33.356803 kubelet[2429]: E0424 23:39:33.356740 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:34.357961 kubelet[2429]: E0424 23:39:34.357876 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:35.359135 kubelet[2429]: E0424 23:39:35.359051 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:35.411676 systemd[1]: Created slice kubepods-besteffort-pod30386fb4_97ea_47b8_875d_26e2c9f59ea1.slice - libcontainer container kubepods-besteffort-pod30386fb4_97ea_47b8_875d_26e2c9f59ea1.slice. Apr 24 23:39:35.486939 kubelet[2429]: I0424 23:39:35.486830 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/30386fb4-97ea-47b8-875d-26e2c9f59ea1-data\") pod \"nfs-server-provisioner-0\" (UID: \"30386fb4-97ea-47b8-875d-26e2c9f59ea1\") " pod="default/nfs-server-provisioner-0" Apr 24 23:39:35.486939 kubelet[2429]: I0424 23:39:35.486899 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xx5gx\" (UniqueName: \"kubernetes.io/projected/30386fb4-97ea-47b8-875d-26e2c9f59ea1-kube-api-access-xx5gx\") pod \"nfs-server-provisioner-0\" (UID: \"30386fb4-97ea-47b8-875d-26e2c9f59ea1\") " pod="default/nfs-server-provisioner-0" Apr 24 23:39:35.719988 containerd[2005]: time="2026-04-24T23:39:35.719919331Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:30386fb4-97ea-47b8-875d-26e2c9f59ea1,Namespace:default,Attempt:0,}" Apr 24 23:39:35.759792 systemd-networkd[1908]: veth7d814de0: Link UP Apr 24 23:39:35.763522 kernel: cni0: port 2(veth7d814de0) entered blocking state Apr 24 23:39:35.763591 kernel: cni0: port 2(veth7d814de0) entered disabled state Apr 24 23:39:35.764843 kernel: veth7d814de0: entered allmulticast mode Apr 24 23:39:35.767130 kernel: veth7d814de0: entered promiscuous mode Apr 24 23:39:35.767279 (udev-worker)[3233]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:39:35.778942 kernel: cni0: port 2(veth7d814de0) entered blocking state Apr 24 23:39:35.779030 kernel: cni0: port 2(veth7d814de0) entered forwarding state Apr 24 23:39:35.779297 systemd-networkd[1908]: veth7d814de0: Gained carrier Apr 24 23:39:35.787632 containerd[2005]: 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"} Apr 24 23:39:35.787632 containerd[2005]: delegateAdd: netconf sent to delegate plugin: Apr 24 23:39:35.829129 containerd[2005]: {"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="2026-04-24T23:39:35.827606792Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 24 23:39:35.829129 containerd[2005]: time="2026-04-24T23:39:35.827707412Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 24 23:39:35.829129 containerd[2005]: time="2026-04-24T23:39:35.827734664Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:35.829129 containerd[2005]: time="2026-04-24T23:39:35.827884052Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:35.870432 systemd[1]: Started cri-containerd-1ca74a1c5800a93e73e9913b655550e7b7c6bb2aafe993afbbeab60bacaefeab.scope - libcontainer container 1ca74a1c5800a93e73e9913b655550e7b7c6bb2aafe993afbbeab60bacaefeab. Apr 24 23:39:35.929129 containerd[2005]: time="2026-04-24T23:39:35.929049656Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:30386fb4-97ea-47b8-875d-26e2c9f59ea1,Namespace:default,Attempt:0,} returns sandbox id \"1ca74a1c5800a93e73e9913b655550e7b7c6bb2aafe993afbbeab60bacaefeab\"" Apr 24 23:39:35.933919 containerd[2005]: time="2026-04-24T23:39:35.933875240Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 24 23:39:36.359874 kubelet[2429]: E0424 23:39:36.359781 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:37.047213 systemd-networkd[1908]: veth7d814de0: Gained IPv6LL Apr 24 23:39:37.361056 kubelet[2429]: E0424 23:39:37.360665 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:38.361472 kubelet[2429]: E0424 23:39:38.361421 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:38.507760 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3226352234.mount: Deactivated successfully. Apr 24 23:39:39.363654 kubelet[2429]: E0424 23:39:39.363471 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:39.867387 ntpd[1990]: Listen normally on 13 veth7d814de0 [fe80::b426:13ff:fedd:f9ab%6]:123 Apr 24 23:39:39.867868 ntpd[1990]: 24 Apr 23:39:39 ntpd[1990]: Listen normally on 13 veth7d814de0 [fe80::b426:13ff:fedd:f9ab%6]:123 Apr 24 23:39:40.363887 kubelet[2429]: E0424 23:39:40.363804 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:41.364023 kubelet[2429]: E0424 23:39:41.363950 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:42.052131 containerd[2005]: time="2026-04-24T23:39:42.052040951Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:42.055258 containerd[2005]: time="2026-04-24T23:39:42.055174919Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Apr 24 23:39:42.056739 containerd[2005]: time="2026-04-24T23:39:42.056661335Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:42.062911 containerd[2005]: time="2026-04-24T23:39:42.062846147Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:42.065614 containerd[2005]: time="2026-04-24T23:39:42.065220539Z" 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 6.130984111s" Apr 24 23:39:42.065614 containerd[2005]: time="2026-04-24T23:39:42.065279195Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 24 23:39:42.072415 containerd[2005]: time="2026-04-24T23:39:42.072348227Z" level=info msg="CreateContainer within sandbox \"1ca74a1c5800a93e73e9913b655550e7b7c6bb2aafe993afbbeab60bacaefeab\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 24 23:39:42.108438 containerd[2005]: time="2026-04-24T23:39:42.108380927Z" level=info msg="CreateContainer within sandbox \"1ca74a1c5800a93e73e9913b655550e7b7c6bb2aafe993afbbeab60bacaefeab\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"79474730d50b79715f52eae06ba23b0def6e809220a419aaa01bfe68831272bc\"" Apr 24 23:39:42.109759 containerd[2005]: time="2026-04-24T23:39:42.109685555Z" level=info msg="StartContainer for \"79474730d50b79715f52eae06ba23b0def6e809220a419aaa01bfe68831272bc\"" Apr 24 23:39:42.167474 systemd[1]: Started cri-containerd-79474730d50b79715f52eae06ba23b0def6e809220a419aaa01bfe68831272bc.scope - libcontainer container 79474730d50b79715f52eae06ba23b0def6e809220a419aaa01bfe68831272bc. Apr 24 23:39:42.215370 containerd[2005]: time="2026-04-24T23:39:42.214511244Z" level=info msg="StartContainer for \"79474730d50b79715f52eae06ba23b0def6e809220a419aaa01bfe68831272bc\" returns successfully" Apr 24 23:39:42.364261 kubelet[2429]: E0424 23:39:42.364190 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:43.332549 kubelet[2429]: E0424 23:39:43.332471 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:43.365378 kubelet[2429]: E0424 23:39:43.365331 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:44.366299 kubelet[2429]: E0424 23:39:44.366232 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:45.367259 kubelet[2429]: E0424 23:39:45.367191 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:46.367737 kubelet[2429]: E0424 23:39:46.367677 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:47.368201 kubelet[2429]: E0424 23:39:47.368136 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:47.617426 kubelet[2429]: I0424 23:39:47.617305 2429 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.483474455 podStartE2EDuration="12.61728519s" podCreationTimestamp="2026-04-24 23:39:35 +0000 UTC" firstStartedPulling="2026-04-24 23:39:35.933048908 +0000 UTC m=+33.475252547" lastFinishedPulling="2026-04-24 23:39:42.066859643 +0000 UTC m=+39.609063282" observedRunningTime="2026-04-24 23:39:42.710264906 +0000 UTC m=+40.252468557" watchObservedRunningTime="2026-04-24 23:39:47.61728519 +0000 UTC m=+45.159488817" Apr 24 23:39:47.632071 systemd[1]: Created slice kubepods-besteffort-pod3814ea4d_a261_46ca_b25e_e6abf4969a95.slice - libcontainer container kubepods-besteffort-pod3814ea4d_a261_46ca_b25e_e6abf4969a95.slice. Apr 24 23:39:47.664903 kubelet[2429]: I0424 23:39:47.664822 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4feac5f2-7018-4891-91a3-c8f8b11a46b5\" (UniqueName: \"kubernetes.io/nfs/3814ea4d-a261-46ca-b25e-e6abf4969a95-pvc-4feac5f2-7018-4891-91a3-c8f8b11a46b5\") pod \"test-pod-1\" (UID: \"3814ea4d-a261-46ca-b25e-e6abf4969a95\") " pod="default/test-pod-1" Apr 24 23:39:47.664903 kubelet[2429]: I0424 23:39:47.664908 2429 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bv9gf\" (UniqueName: \"kubernetes.io/projected/3814ea4d-a261-46ca-b25e-e6abf4969a95-kube-api-access-bv9gf\") pod \"test-pod-1\" (UID: \"3814ea4d-a261-46ca-b25e-e6abf4969a95\") " pod="default/test-pod-1" Apr 24 23:39:47.804224 kernel: FS-Cache: Loaded Apr 24 23:39:47.847852 kernel: RPC: Registered named UNIX socket transport module. Apr 24 23:39:47.847979 kernel: RPC: Registered udp transport module. Apr 24 23:39:47.848022 kernel: RPC: Registered tcp transport module. Apr 24 23:39:47.848880 kernel: RPC: Registered tcp-with-tls transport module. Apr 24 23:39:47.850077 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 24 23:39:48.192999 kernel: NFS: Registering the id_resolver key type Apr 24 23:39:48.193182 kernel: Key type id_resolver registered Apr 24 23:39:48.193230 kernel: Key type id_legacy registered Apr 24 23:39:48.236210 nfsidmap[3663]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 24 23:39:48.243693 nfsidmap[3664]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 24 23:39:48.368671 kubelet[2429]: E0424 23:39:48.368563 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:48.541219 containerd[2005]: time="2026-04-24T23:39:48.540759355Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3814ea4d-a261-46ca-b25e-e6abf4969a95,Namespace:default,Attempt:0,}" Apr 24 23:39:48.581273 systemd-networkd[1908]: vethf1e1c100: Link UP Apr 24 23:39:48.582685 kernel: cni0: port 3(vethf1e1c100) entered blocking state Apr 24 23:39:48.582753 kernel: cni0: port 3(vethf1e1c100) entered disabled state Apr 24 23:39:48.582791 kernel: vethf1e1c100: entered allmulticast mode Apr 24 23:39:48.585187 kernel: vethf1e1c100: entered promiscuous mode Apr 24 23:39:48.585600 (udev-worker)[3655]: Network interface NamePolicy= disabled on kernel command line. Apr 24 23:39:48.597071 kernel: cni0: port 3(vethf1e1c100) entered blocking state Apr 24 23:39:48.597205 kernel: cni0: port 3(vethf1e1c100) entered forwarding state Apr 24 23:39:48.596641 systemd-networkd[1908]: vethf1e1c100: Gained carrier Apr 24 23:39:48.608360 containerd[2005]: 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)(0x400000e840), "name":"cbr0", "type":"bridge"} Apr 24 23:39:48.608360 containerd[2005]: delegateAdd: netconf sent to delegate plugin: Apr 24 23:39:48.644092 containerd[2005]: {"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="2026-04-24T23:39:48.643424672Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 24 23:39:48.644092 containerd[2005]: time="2026-04-24T23:39:48.643528484Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 24 23:39:48.644092 containerd[2005]: time="2026-04-24T23:39:48.643581512Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:48.644092 containerd[2005]: time="2026-04-24T23:39:48.643748780Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 24 23:39:48.680436 systemd[1]: Started cri-containerd-f1ce3b2e1c51dc5cdb5682f9aa41d0eb9a03f118fc9116fb7eaa472155f44348.scope - libcontainer container f1ce3b2e1c51dc5cdb5682f9aa41d0eb9a03f118fc9116fb7eaa472155f44348. Apr 24 23:39:48.740483 containerd[2005]: time="2026-04-24T23:39:48.740331872Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:3814ea4d-a261-46ca-b25e-e6abf4969a95,Namespace:default,Attempt:0,} returns sandbox id \"f1ce3b2e1c51dc5cdb5682f9aa41d0eb9a03f118fc9116fb7eaa472155f44348\"" Apr 24 23:39:48.742593 containerd[2005]: time="2026-04-24T23:39:48.742463672Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 24 23:39:49.044418 containerd[2005]: time="2026-04-24T23:39:49.044339118Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 24 23:39:49.045874 containerd[2005]: time="2026-04-24T23:39:49.045813738Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 24 23:39:49.052182 containerd[2005]: time="2026-04-24T23:39:49.052127514Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4e83b0a153edcfe297a96c14b2b93b7730f0a5dbb64dcf4efd27b58460fd74d1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:6f170eb5f8ea893d94fe3c05cef69270f16614a99d3d922ce8a320efc43b8591\", size \"65841421\" in 309.575078ms" Apr 24 23:39:49.052513 containerd[2005]: time="2026-04-24T23:39:49.052356450Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4e83b0a153edcfe297a96c14b2b93b7730f0a5dbb64dcf4efd27b58460fd74d1\"" Apr 24 23:39:49.059067 containerd[2005]: time="2026-04-24T23:39:49.059005662Z" level=info msg="CreateContainer within sandbox \"f1ce3b2e1c51dc5cdb5682f9aa41d0eb9a03f118fc9116fb7eaa472155f44348\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 24 23:39:49.081178 containerd[2005]: time="2026-04-24T23:39:49.081092142Z" level=info msg="CreateContainer within sandbox \"f1ce3b2e1c51dc5cdb5682f9aa41d0eb9a03f118fc9116fb7eaa472155f44348\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"d0428bb862a53c3eb34496ef31a3588f4f93b969d8c214edcee76dead6ab6386\"" Apr 24 23:39:49.082173 containerd[2005]: time="2026-04-24T23:39:49.082052166Z" level=info msg="StartContainer for \"d0428bb862a53c3eb34496ef31a3588f4f93b969d8c214edcee76dead6ab6386\"" Apr 24 23:39:49.136428 systemd[1]: Started cri-containerd-d0428bb862a53c3eb34496ef31a3588f4f93b969d8c214edcee76dead6ab6386.scope - libcontainer container d0428bb862a53c3eb34496ef31a3588f4f93b969d8c214edcee76dead6ab6386. Apr 24 23:39:49.184389 containerd[2005]: time="2026-04-24T23:39:49.184316994Z" level=info msg="StartContainer for \"d0428bb862a53c3eb34496ef31a3588f4f93b969d8c214edcee76dead6ab6386\" returns successfully" Apr 24 23:39:49.369245 kubelet[2429]: E0424 23:39:49.369186 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:49.731435 kubelet[2429]: I0424 23:39:49.731081 2429 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=12.419555011 podStartE2EDuration="12.731062953s" podCreationTimestamp="2026-04-24 23:39:37 +0000 UTC" firstStartedPulling="2026-04-24 23:39:48.741887144 +0000 UTC m=+46.284090783" lastFinishedPulling="2026-04-24 23:39:49.053395086 +0000 UTC m=+46.595598725" observedRunningTime="2026-04-24 23:39:49.730746465 +0000 UTC m=+47.272950116" watchObservedRunningTime="2026-04-24 23:39:49.731062953 +0000 UTC m=+47.273266592" Apr 24 23:39:50.369897 kubelet[2429]: E0424 23:39:50.369835 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:50.614439 systemd-networkd[1908]: vethf1e1c100: Gained IPv6LL Apr 24 23:39:51.371027 kubelet[2429]: E0424 23:39:51.370959 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:52.371421 kubelet[2429]: E0424 23:39:52.371355 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:53.371539 kubelet[2429]: E0424 23:39:53.371472 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:53.867422 ntpd[1990]: Listen normally on 14 vethf1e1c100 [fe80::14b6:d6ff:fee0:d2ea%7]:123 Apr 24 23:39:53.868645 ntpd[1990]: 24 Apr 23:39:53 ntpd[1990]: Listen normally on 14 vethf1e1c100 [fe80::14b6:d6ff:fee0:d2ea%7]:123 Apr 24 23:39:54.372123 kubelet[2429]: E0424 23:39:54.372040 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:55.372248 kubelet[2429]: E0424 23:39:55.372185 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:56.373407 kubelet[2429]: E0424 23:39:56.373346 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:57.374013 kubelet[2429]: E0424 23:39:57.373951 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:58.374579 kubelet[2429]: E0424 23:39:58.374495 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:39:59.375592 kubelet[2429]: E0424 23:39:59.375523 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:00.376576 kubelet[2429]: E0424 23:40:00.376492 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:01.377023 kubelet[2429]: E0424 23:40:01.376955 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:02.377198 kubelet[2429]: E0424 23:40:02.377135 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:03.333021 kubelet[2429]: E0424 23:40:03.332932 2429 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:03.378070 kubelet[2429]: E0424 23:40:03.378025 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:04.378626 kubelet[2429]: E0424 23:40:04.378546 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:05.379404 kubelet[2429]: E0424 23:40:05.379342 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:06.380299 kubelet[2429]: E0424 23:40:06.380232 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:07.380936 kubelet[2429]: E0424 23:40:07.380858 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:08.381866 kubelet[2429]: E0424 23:40:08.381802 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:09.382221 kubelet[2429]: E0424 23:40:09.382159 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:10.382786 kubelet[2429]: E0424 23:40:10.382725 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:11.383548 kubelet[2429]: E0424 23:40:11.383479 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:12.384210 kubelet[2429]: E0424 23:40:12.384148 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:13.385253 kubelet[2429]: E0424 23:40:13.385134 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:14.386347 kubelet[2429]: E0424 23:40:14.386284 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:15.387358 kubelet[2429]: E0424 23:40:15.387301 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:16.388066 kubelet[2429]: E0424 23:40:16.388008 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:17.388722 kubelet[2429]: E0424 23:40:17.388658 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:18.389473 kubelet[2429]: E0424 23:40:18.389413 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:19.390627 kubelet[2429]: E0424 23:40:19.390551 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:20.391191 kubelet[2429]: E0424 23:40:20.391136 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:21.392200 kubelet[2429]: E0424 23:40:21.392152 2429 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 24 23:40:21.704093 systemd-logind[1995]: Power key pressed short. Apr 24 23:40:21.704142 systemd-logind[1995]: Powering off...