Nov 5 15:03:06.381197 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 5 15:03:06.381245 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Wed Nov 5 13:42:06 -00 2025 Nov 5 15:03:06.381270 kernel: KASLR disabled due to lack of seed Nov 5 15:03:06.381287 kernel: efi: EFI v2.7 by EDK II Nov 5 15:03:06.381303 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a731a98 MEMRESERVE=0x78551598 Nov 5 15:03:06.381319 kernel: secureboot: Secure boot disabled Nov 5 15:03:06.381336 kernel: ACPI: Early table checksum verification disabled Nov 5 15:03:06.381352 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 5 15:03:06.381368 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 5 15:03:06.381387 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 5 15:03:06.381403 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 5 15:03:06.381418 kernel: ACPI: FACS 0x0000000078630000 000040 Nov 5 15:03:06.381434 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 5 15:03:06.381450 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 5 15:03:06.381472 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 5 15:03:06.381489 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 5 15:03:06.381506 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 5 15:03:06.381522 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 5 15:03:06.381539 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 5 15:03:06.381556 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 5 15:03:06.381572 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 5 15:03:06.381589 kernel: printk: legacy bootconsole [uart0] enabled Nov 5 15:03:06.381605 kernel: ACPI: Use ACPI SPCR as default console: No Nov 5 15:03:06.381622 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 5 15:03:06.381642 kernel: NODE_DATA(0) allocated [mem 0x4b584da00-0x4b5854fff] Nov 5 15:03:06.381659 kernel: Zone ranges: Nov 5 15:03:06.381676 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 5 15:03:06.381692 kernel: DMA32 empty Nov 5 15:03:06.381708 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 5 15:03:06.381725 kernel: Device empty Nov 5 15:03:06.381741 kernel: Movable zone start for each node Nov 5 15:03:06.381757 kernel: Early memory node ranges Nov 5 15:03:06.381774 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 5 15:03:06.381790 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 5 15:03:06.381807 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 5 15:03:06.381823 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 5 15:03:06.381843 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 5 15:03:06.381860 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 5 15:03:06.381876 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 5 15:03:06.381893 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 5 15:03:06.381916 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 5 15:03:06.381937 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 5 15:03:06.381954 kernel: cma: Reserved 16 MiB at 0x000000007f000000 on node -1 Nov 5 15:03:06.381972 kernel: psci: probing for conduit method from ACPI. Nov 5 15:03:06.381989 kernel: psci: PSCIv1.0 detected in firmware. Nov 5 15:03:06.382006 kernel: psci: Using standard PSCI v0.2 function IDs Nov 5 15:03:06.382023 kernel: psci: Trusted OS migration not required Nov 5 15:03:06.382040 kernel: psci: SMC Calling Convention v1.1 Nov 5 15:03:06.382058 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Nov 5 15:03:06.382075 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 5 15:03:06.382118 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 5 15:03:06.382137 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 5 15:03:06.382155 kernel: Detected PIPT I-cache on CPU0 Nov 5 15:03:06.382173 kernel: CPU features: detected: GIC system register CPU interface Nov 5 15:03:06.382191 kernel: CPU features: detected: Spectre-v2 Nov 5 15:03:06.382237 kernel: CPU features: detected: Spectre-v3a Nov 5 15:03:06.382255 kernel: CPU features: detected: Spectre-BHB Nov 5 15:03:06.382273 kernel: CPU features: detected: ARM erratum 1742098 Nov 5 15:03:06.382290 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 5 15:03:06.382308 kernel: alternatives: applying boot alternatives Nov 5 15:03:06.382328 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:03:06.382352 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 15:03:06.382370 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 15:03:06.382387 kernel: Fallback order for Node 0: 0 Nov 5 15:03:06.382405 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1007616 Nov 5 15:03:06.382422 kernel: Policy zone: Normal Nov 5 15:03:06.382440 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 15:03:06.382457 kernel: software IO TLB: area num 2. Nov 5 15:03:06.382474 kernel: software IO TLB: mapped [mem 0x000000006f800000-0x0000000073800000] (64MB) Nov 5 15:03:06.382492 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 5 15:03:06.382509 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 15:03:06.382531 kernel: rcu: RCU event tracing is enabled. Nov 5 15:03:06.382549 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 5 15:03:06.382567 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 15:03:06.382585 kernel: Tracing variant of Tasks RCU enabled. Nov 5 15:03:06.382603 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 15:03:06.382620 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 5 15:03:06.382654 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 5 15:03:06.382674 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 5 15:03:06.382692 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 5 15:03:06.382709 kernel: GICv3: 96 SPIs implemented Nov 5 15:03:06.382727 kernel: GICv3: 0 Extended SPIs implemented Nov 5 15:03:06.382749 kernel: Root IRQ handler: gic_handle_irq Nov 5 15:03:06.382766 kernel: GICv3: GICv3 features: 16 PPIs Nov 5 15:03:06.382783 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Nov 5 15:03:06.382801 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 5 15:03:06.382818 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 5 15:03:06.382835 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000f0000 (indirect, esz 8, psz 64K, shr 1) Nov 5 15:03:06.382853 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @400100000 (flat, esz 8, psz 64K, shr 1) Nov 5 15:03:06.382870 kernel: GICv3: using LPI property table @0x0000000400110000 Nov 5 15:03:06.382888 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 5 15:03:06.382905 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000400120000 Nov 5 15:03:06.382923 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 15:03:06.382944 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 5 15:03:06.382961 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 5 15:03:06.382979 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 5 15:03:06.382996 kernel: Console: colour dummy device 80x25 Nov 5 15:03:06.383015 kernel: printk: legacy console [tty1] enabled Nov 5 15:03:06.383033 kernel: ACPI: Core revision 20240827 Nov 5 15:03:06.383052 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 5 15:03:06.383070 kernel: pid_max: default: 32768 minimum: 301 Nov 5 15:03:06.383120 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 5 15:03:06.383139 kernel: landlock: Up and running. Nov 5 15:03:06.383158 kernel: SELinux: Initializing. Nov 5 15:03:06.383176 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:03:06.383194 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:03:06.383212 kernel: rcu: Hierarchical SRCU implementation. Nov 5 15:03:06.383230 kernel: rcu: Max phase no-delay instances is 400. Nov 5 15:03:06.383248 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 5 15:03:06.383270 kernel: Remapping and enabling EFI services. Nov 5 15:03:06.383288 kernel: smp: Bringing up secondary CPUs ... Nov 5 15:03:06.383306 kernel: Detected PIPT I-cache on CPU1 Nov 5 15:03:06.383324 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 5 15:03:06.383342 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000400130000 Nov 5 15:03:06.383360 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 5 15:03:06.383377 kernel: smp: Brought up 1 node, 2 CPUs Nov 5 15:03:06.383399 kernel: SMP: Total of 2 processors activated. Nov 5 15:03:06.383417 kernel: CPU: All CPU(s) started at EL1 Nov 5 15:03:06.383445 kernel: CPU features: detected: 32-bit EL0 Support Nov 5 15:03:06.383467 kernel: CPU features: detected: 32-bit EL1 Support Nov 5 15:03:06.383485 kernel: CPU features: detected: CRC32 instructions Nov 5 15:03:06.383504 kernel: alternatives: applying system-wide alternatives Nov 5 15:03:06.383523 kernel: Memory: 3822956K/4030464K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 186164K reserved, 16384K cma-reserved) Nov 5 15:03:06.383542 kernel: devtmpfs: initialized Nov 5 15:03:06.383565 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 15:03:06.383584 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 5 15:03:06.383602 kernel: 23536 pages in range for non-PLT usage Nov 5 15:03:06.383621 kernel: 515056 pages in range for PLT usage Nov 5 15:03:06.383639 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 15:03:06.383662 kernel: SMBIOS 3.0.0 present. Nov 5 15:03:06.383680 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 5 15:03:06.383699 kernel: DMI: Memory slots populated: 0/0 Nov 5 15:03:06.383717 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 15:03:06.383736 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 5 15:03:06.383755 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 5 15:03:06.383774 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 5 15:03:06.383796 kernel: audit: initializing netlink subsys (disabled) Nov 5 15:03:06.383815 kernel: audit: type=2000 audit(0.225:1): state=initialized audit_enabled=0 res=1 Nov 5 15:03:06.383834 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 15:03:06.383852 kernel: cpuidle: using governor menu Nov 5 15:03:06.383871 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 5 15:03:06.383889 kernel: ASID allocator initialised with 65536 entries Nov 5 15:03:06.383908 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 15:03:06.383930 kernel: Serial: AMBA PL011 UART driver Nov 5 15:03:06.383949 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 15:03:06.383968 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 15:03:06.383986 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 5 15:03:06.384005 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 5 15:03:06.384023 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 15:03:06.384042 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 15:03:06.384065 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 5 15:03:06.384120 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 5 15:03:06.384143 kernel: ACPI: Added _OSI(Module Device) Nov 5 15:03:06.384162 kernel: ACPI: Added _OSI(Processor Device) Nov 5 15:03:06.384181 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 15:03:06.384200 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 15:03:06.384218 kernel: ACPI: Interpreter enabled Nov 5 15:03:06.384242 kernel: ACPI: Using GIC for interrupt routing Nov 5 15:03:06.384261 kernel: ACPI: MCFG table detected, 1 entries Nov 5 15:03:06.384279 kernel: ACPI: CPU0 has been hot-added Nov 5 15:03:06.384298 kernel: ACPI: CPU1 has been hot-added Nov 5 15:03:06.384317 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 5 15:03:06.384669 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 5 15:03:06.384919 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 5 15:03:06.385257 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 5 15:03:06.385509 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 5 15:03:06.385753 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 5 15:03:06.385779 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 5 15:03:06.385798 kernel: acpiphp: Slot [1] registered Nov 5 15:03:06.385817 kernel: acpiphp: Slot [2] registered Nov 5 15:03:06.385842 kernel: acpiphp: Slot [3] registered Nov 5 15:03:06.385861 kernel: acpiphp: Slot [4] registered Nov 5 15:03:06.385879 kernel: acpiphp: Slot [5] registered Nov 5 15:03:06.385897 kernel: acpiphp: Slot [6] registered Nov 5 15:03:06.385916 kernel: acpiphp: Slot [7] registered Nov 5 15:03:06.385934 kernel: acpiphp: Slot [8] registered Nov 5 15:03:06.385952 kernel: acpiphp: Slot [9] registered Nov 5 15:03:06.385971 kernel: acpiphp: Slot [10] registered Nov 5 15:03:06.385993 kernel: acpiphp: Slot [11] registered Nov 5 15:03:06.386011 kernel: acpiphp: Slot [12] registered Nov 5 15:03:06.386030 kernel: acpiphp: Slot [13] registered Nov 5 15:03:06.386048 kernel: acpiphp: Slot [14] registered Nov 5 15:03:06.386067 kernel: acpiphp: Slot [15] registered Nov 5 15:03:06.386106 kernel: acpiphp: Slot [16] registered Nov 5 15:03:06.386132 kernel: acpiphp: Slot [17] registered Nov 5 15:03:06.386157 kernel: acpiphp: Slot [18] registered Nov 5 15:03:06.386176 kernel: acpiphp: Slot [19] registered Nov 5 15:03:06.386194 kernel: acpiphp: Slot [20] registered Nov 5 15:03:06.386213 kernel: acpiphp: Slot [21] registered Nov 5 15:03:06.386231 kernel: acpiphp: Slot [22] registered Nov 5 15:03:06.388552 kernel: acpiphp: Slot [23] registered Nov 5 15:03:06.388587 kernel: acpiphp: Slot [24] registered Nov 5 15:03:06.388615 kernel: acpiphp: Slot [25] registered Nov 5 15:03:06.388635 kernel: acpiphp: Slot [26] registered Nov 5 15:03:06.388653 kernel: acpiphp: Slot [27] registered Nov 5 15:03:06.388672 kernel: acpiphp: Slot [28] registered Nov 5 15:03:06.388691 kernel: acpiphp: Slot [29] registered Nov 5 15:03:06.388709 kernel: acpiphp: Slot [30] registered Nov 5 15:03:06.388728 kernel: acpiphp: Slot [31] registered Nov 5 15:03:06.388746 kernel: PCI host bridge to bus 0000:00 Nov 5 15:03:06.389046 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 5 15:03:06.389305 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 5 15:03:06.389532 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 5 15:03:06.389753 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 5 15:03:06.390033 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 conventional PCI endpoint Nov 5 15:03:06.390356 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 conventional PCI endpoint Nov 5 15:03:06.390607 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80118000-0x80118fff] Nov 5 15:03:06.390893 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 PCIe Root Complex Integrated Endpoint Nov 5 15:03:06.391415 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80114000-0x80117fff] Nov 5 15:03:06.391677 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 5 15:03:06.391949 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 PCIe Root Complex Integrated Endpoint Nov 5 15:03:06.392224 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80110000-0x80113fff] Nov 5 15:03:06.392472 kernel: pci 0000:00:05.0: BAR 2 [mem 0x80000000-0x800fffff pref] Nov 5 15:03:06.392718 kernel: pci 0000:00:05.0: BAR 4 [mem 0x80100000-0x8010ffff] Nov 5 15:03:06.392965 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 5 15:03:06.393248 kernel: pci 0000:00:05.0: BAR 2 [mem 0x80000000-0x800fffff pref]: assigned Nov 5 15:03:06.393501 kernel: pci 0000:00:05.0: BAR 4 [mem 0x80100000-0x8010ffff]: assigned Nov 5 15:03:06.393748 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80110000-0x80113fff]: assigned Nov 5 15:03:06.393992 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80114000-0x80117fff]: assigned Nov 5 15:03:06.394276 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80118000-0x80118fff]: assigned Nov 5 15:03:06.394504 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 5 15:03:06.394751 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 5 15:03:06.394981 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 5 15:03:06.395008 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 5 15:03:06.395027 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 5 15:03:06.395046 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 5 15:03:06.395066 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 5 15:03:06.395128 kernel: iommu: Default domain type: Translated Nov 5 15:03:06.395150 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 5 15:03:06.395175 kernel: efivars: Registered efivars operations Nov 5 15:03:06.395194 kernel: vgaarb: loaded Nov 5 15:03:06.395212 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 5 15:03:06.395231 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 15:03:06.395250 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 15:03:06.395269 kernel: pnp: PnP ACPI init Nov 5 15:03:06.395543 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 5 15:03:06.395578 kernel: pnp: PnP ACPI: found 1 devices Nov 5 15:03:06.395598 kernel: NET: Registered PF_INET protocol family Nov 5 15:03:06.395617 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 15:03:06.395637 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 15:03:06.395656 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 15:03:06.395676 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 15:03:06.395695 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 5 15:03:06.395718 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 15:03:06.395737 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:03:06.395756 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:03:06.395775 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 15:03:06.395794 kernel: PCI: CLS 0 bytes, default 64 Nov 5 15:03:06.395814 kernel: kvm [1]: HYP mode not available Nov 5 15:03:06.395833 kernel: Initialise system trusted keyrings Nov 5 15:03:06.395856 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 15:03:06.395877 kernel: Key type asymmetric registered Nov 5 15:03:06.395897 kernel: Asymmetric key parser 'x509' registered Nov 5 15:03:06.395917 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 5 15:03:06.395936 kernel: io scheduler mq-deadline registered Nov 5 15:03:06.395955 kernel: io scheduler kyber registered Nov 5 15:03:06.395974 kernel: io scheduler bfq registered Nov 5 15:03:06.397427 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 5 15:03:06.397475 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 5 15:03:06.397495 kernel: ACPI: button: Power Button [PWRB] Nov 5 15:03:06.397515 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 5 15:03:06.397534 kernel: ACPI: button: Sleep Button [SLPB] Nov 5 15:03:06.397553 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 15:03:06.397582 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 5 15:03:06.397837 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 5 15:03:06.397864 kernel: printk: legacy console [ttyS0] disabled Nov 5 15:03:06.397883 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 5 15:03:06.397902 kernel: printk: legacy console [ttyS0] enabled Nov 5 15:03:06.397921 kernel: printk: legacy bootconsole [uart0] disabled Nov 5 15:03:06.397940 kernel: thunder_xcv, ver 1.0 Nov 5 15:03:06.397964 kernel: thunder_bgx, ver 1.0 Nov 5 15:03:06.397983 kernel: nicpf, ver 1.0 Nov 5 15:03:06.398001 kernel: nicvf, ver 1.0 Nov 5 15:03:06.400418 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 5 15:03:06.400706 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-05T15:03:02 UTC (1762354982) Nov 5 15:03:06.400737 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 15:03:06.400759 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 (0,80000003) counters available Nov 5 15:03:06.400790 kernel: NET: Registered PF_INET6 protocol family Nov 5 15:03:06.400810 kernel: watchdog: NMI not fully supported Nov 5 15:03:06.400829 kernel: watchdog: Hard watchdog permanently disabled Nov 5 15:03:06.400849 kernel: Segment Routing with IPv6 Nov 5 15:03:06.400868 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 15:03:06.400887 kernel: NET: Registered PF_PACKET protocol family Nov 5 15:03:06.400906 kernel: Key type dns_resolver registered Nov 5 15:03:06.400930 kernel: registered taskstats version 1 Nov 5 15:03:06.400949 kernel: Loading compiled-in X.509 certificates Nov 5 15:03:06.400968 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 4b3babb46eb583bd8b0310732885d24e60ea58c5' Nov 5 15:03:06.400987 kernel: Demotion targets for Node 0: null Nov 5 15:03:06.401006 kernel: Key type .fscrypt registered Nov 5 15:03:06.401024 kernel: Key type fscrypt-provisioning registered Nov 5 15:03:06.401043 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 15:03:06.401066 kernel: ima: Allocated hash algorithm: sha1 Nov 5 15:03:06.402975 kernel: ima: No architecture policies found Nov 5 15:03:06.403006 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 5 15:03:06.403026 kernel: clk: Disabling unused clocks Nov 5 15:03:06.403048 kernel: PM: genpd: Disabling unused power domains Nov 5 15:03:06.403069 kernel: Freeing unused kernel memory: 12992K Nov 5 15:03:06.403136 kernel: Run /init as init process Nov 5 15:03:06.403168 kernel: with arguments: Nov 5 15:03:06.403187 kernel: /init Nov 5 15:03:06.403206 kernel: with environment: Nov 5 15:03:06.403225 kernel: HOME=/ Nov 5 15:03:06.403244 kernel: TERM=linux Nov 5 15:03:06.403264 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 5 15:03:06.403551 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 5 15:03:06.403750 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 5 15:03:06.403778 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 5 15:03:06.403802 kernel: GPT:25804799 != 33554431 Nov 5 15:03:06.403821 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 5 15:03:06.403839 kernel: GPT:25804799 != 33554431 Nov 5 15:03:06.403858 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 5 15:03:06.403877 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 5 15:03:06.403900 kernel: SCSI subsystem initialized Nov 5 15:03:06.403920 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 15:03:06.403939 kernel: device-mapper: uevent: version 1.0.3 Nov 5 15:03:06.403958 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 5 15:03:06.403978 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 5 15:03:06.403997 kernel: raid6: neonx8 gen() 6495 MB/s Nov 5 15:03:06.404016 kernel: raid6: neonx4 gen() 6452 MB/s Nov 5 15:03:06.404039 kernel: raid6: neonx2 gen() 5405 MB/s Nov 5 15:03:06.404058 kernel: raid6: neonx1 gen() 3940 MB/s Nov 5 15:03:06.404107 kernel: raid6: int64x8 gen() 3637 MB/s Nov 5 15:03:06.404134 kernel: raid6: int64x4 gen() 3682 MB/s Nov 5 15:03:06.404154 kernel: raid6: int64x2 gen() 3565 MB/s Nov 5 15:03:06.404173 kernel: raid6: int64x1 gen() 2775 MB/s Nov 5 15:03:06.404192 kernel: raid6: using algorithm neonx8 gen() 6495 MB/s Nov 5 15:03:06.404217 kernel: raid6: .... xor() 4727 MB/s, rmw enabled Nov 5 15:03:06.404237 kernel: raid6: using neon recovery algorithm Nov 5 15:03:06.404255 kernel: xor: measuring software checksum speed Nov 5 15:03:06.404274 kernel: 8regs : 12349 MB/sec Nov 5 15:03:06.404293 kernel: 32regs : 12245 MB/sec Nov 5 15:03:06.404311 kernel: arm64_neon : 9069 MB/sec Nov 5 15:03:06.404330 kernel: xor: using function: 8regs (12349 MB/sec) Nov 5 15:03:06.404353 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 15:03:06.404372 kernel: BTRFS: device fsid d8f84a83-fd8b-4c0e-831a-0d7c5ff234be devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (222) Nov 5 15:03:06.404391 kernel: BTRFS info (device dm-0): first mount of filesystem d8f84a83-fd8b-4c0e-831a-0d7c5ff234be Nov 5 15:03:06.404411 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:03:06.404430 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 5 15:03:06.404449 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 15:03:06.404468 kernel: BTRFS info (device dm-0): enabling free space tree Nov 5 15:03:06.404491 kernel: loop: module loaded Nov 5 15:03:06.404510 kernel: loop0: detected capacity change from 0 to 91464 Nov 5 15:03:06.404529 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 15:03:06.404550 systemd[1]: Successfully made /usr/ read-only. Nov 5 15:03:06.404576 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:03:06.404598 systemd[1]: Detected virtualization amazon. Nov 5 15:03:06.404623 systemd[1]: Detected architecture arm64. Nov 5 15:03:06.404643 systemd[1]: Running in initrd. Nov 5 15:03:06.404664 systemd[1]: No hostname configured, using default hostname. Nov 5 15:03:06.404685 systemd[1]: Hostname set to . Nov 5 15:03:06.404706 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 15:03:06.404727 systemd[1]: Queued start job for default target initrd.target. Nov 5 15:03:06.404760 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:03:06.404786 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:03:06.404807 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:06.404830 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 15:03:06.404852 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:03:06.404878 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 15:03:06.404901 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 15:03:06.404923 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:03:06.404944 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:03:06.404966 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:03:06.404987 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:03:06.405011 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:03:06.405033 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:03:06.405054 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:03:06.405111 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:03:06.405143 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:03:06.405167 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 15:03:06.405189 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 15:03:06.405217 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:03:06.405240 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:03:06.405262 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:03:06.405284 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:03:06.405306 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 15:03:06.405329 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 15:03:06.405350 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:03:06.405376 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 15:03:06.405398 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 5 15:03:06.405420 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 15:03:06.405446 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:03:06.405472 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:03:06.405494 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:03:06.405517 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 15:03:06.405543 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:03:06.405566 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:03:06.405588 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 15:03:06.405611 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:03:06.405689 systemd-journald[359]: Collecting audit messages is disabled. Nov 5 15:03:06.405739 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:03:06.405760 systemd-journald[359]: Journal started Nov 5 15:03:06.405797 systemd-journald[359]: Runtime Journal (/run/log/journal/ec25ca77a0ad8f762182f0d78bf8b2cc) is 8M, max 75.3M, 67.3M free. Nov 5 15:03:06.409119 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:03:06.409173 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 5 15:03:06.418171 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:03:06.450259 kernel: Bridge firewalling registered Nov 5 15:03:06.446418 systemd-tmpfiles[376]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 5 15:03:06.451402 systemd-modules-load[361]: Inserted module 'br_netfilter' Nov 5 15:03:06.461307 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:03:06.467999 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:03:06.477269 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:03:06.487350 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:03:06.494233 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:03:06.508307 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 15:03:06.524998 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:03:06.534074 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:03:06.566196 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:03:06.574855 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 15:03:06.619887 dracut-cmdline[402]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:03:06.730179 systemd-resolved[390]: Positive Trust Anchors: Nov 5 15:03:06.730211 systemd-resolved[390]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:03:06.730220 systemd-resolved[390]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:03:06.730280 systemd-resolved[390]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:03:06.924129 kernel: Loading iSCSI transport class v2.0-870. Nov 5 15:03:06.977136 kernel: iscsi: registered transport (tcp) Nov 5 15:03:07.010145 kernel: random: crng init done Nov 5 15:03:07.010447 systemd-resolved[390]: Defaulting to hostname 'linux'. Nov 5 15:03:07.026768 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:03:07.030558 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:03:07.054321 kernel: iscsi: registered transport (qla4xxx) Nov 5 15:03:07.054369 kernel: QLogic iSCSI HBA Driver Nov 5 15:03:07.090444 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:03:07.123436 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:03:07.131395 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:03:07.213163 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 15:03:07.220926 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 15:03:07.231791 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 15:03:07.296884 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:03:07.304384 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:03:07.363468 systemd-udevd[645]: Using default interface naming scheme 'v257'. Nov 5 15:03:07.386942 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:03:07.394295 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 15:03:07.445400 dracut-pre-trigger[712]: rd.md=0: removing MD RAID activation Nov 5 15:03:07.455187 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:03:07.466813 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:03:07.517677 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:03:07.527334 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:03:07.566270 systemd-networkd[759]: lo: Link UP Nov 5 15:03:07.566289 systemd-networkd[759]: lo: Gained carrier Nov 5 15:03:07.567297 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:03:07.571424 systemd[1]: Reached target network.target - Network. Nov 5 15:03:07.685620 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:03:07.697605 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 15:03:07.899284 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:03:07.920174 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 5 15:03:07.920224 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 5 15:03:07.899537 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:03:07.928295 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 5 15:03:07.928653 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 5 15:03:07.906300 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:03:07.910404 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:03:07.940120 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:d1:72:5b:f8:fb Nov 5 15:03:07.942465 (udev-worker)[813]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:03:07.960474 systemd-networkd[759]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:03:07.960498 systemd-networkd[759]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:03:07.974526 systemd-networkd[759]: eth0: Link UP Nov 5 15:03:07.974850 systemd-networkd[759]: eth0: Gained carrier Nov 5 15:03:07.974872 systemd-networkd[759]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:03:07.992673 kernel: nvme nvme0: using unchecked data buffer Nov 5 15:03:08.001223 systemd-networkd[759]: eth0: DHCPv4 address 172.31.27.235/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 5 15:03:08.023665 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:03:08.171743 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 5 15:03:08.210799 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 5 15:03:08.242470 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 15:03:08.273434 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 5 15:03:08.318148 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 5 15:03:08.322019 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:03:08.328817 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:03:08.332188 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:03:08.344605 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 15:03:08.349687 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 15:03:08.384495 disk-uuid[920]: Primary Header is updated. Nov 5 15:03:08.384495 disk-uuid[920]: Secondary Entries is updated. Nov 5 15:03:08.384495 disk-uuid[920]: Secondary Header is updated. Nov 5 15:03:08.441072 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:03:09.509251 disk-uuid[923]: Warning: The kernel is still using the old partition table. Nov 5 15:03:09.509251 disk-uuid[923]: The new table will be used at the next reboot or after you Nov 5 15:03:09.509251 disk-uuid[923]: run partprobe(8) or kpartx(8) Nov 5 15:03:09.509251 disk-uuid[923]: The operation has completed successfully. Nov 5 15:03:09.527977 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 15:03:09.528237 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 15:03:09.537216 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 15:03:09.606116 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1105) Nov 5 15:03:09.610714 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:03:09.610874 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:03:09.652956 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 5 15:03:09.653037 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 5 15:03:09.663261 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:03:09.663721 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 15:03:09.670739 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 15:03:09.787254 systemd-networkd[759]: eth0: Gained IPv6LL Nov 5 15:03:11.079252 ignition[1124]: Ignition 2.22.0 Nov 5 15:03:11.079282 ignition[1124]: Stage: fetch-offline Nov 5 15:03:11.083538 ignition[1124]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:11.083579 ignition[1124]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:11.088916 ignition[1124]: Ignition finished successfully Nov 5 15:03:11.093657 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:03:11.098469 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 5 15:03:11.145847 ignition[1131]: Ignition 2.22.0 Nov 5 15:03:11.145877 ignition[1131]: Stage: fetch Nov 5 15:03:11.146472 ignition[1131]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:11.146493 ignition[1131]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:11.146652 ignition[1131]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:11.162822 ignition[1131]: PUT result: OK Nov 5 15:03:11.166290 ignition[1131]: parsed url from cmdline: "" Nov 5 15:03:11.166312 ignition[1131]: no config URL provided Nov 5 15:03:11.166328 ignition[1131]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:03:11.166388 ignition[1131]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:03:11.166421 ignition[1131]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:11.171193 ignition[1131]: PUT result: OK Nov 5 15:03:11.171268 ignition[1131]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 5 15:03:11.177951 ignition[1131]: GET result: OK Nov 5 15:03:11.183435 unknown[1131]: fetched base config from "system" Nov 5 15:03:11.178054 ignition[1131]: parsing config with SHA512: 878700af1d7351ab47fa2123b415b8a7fc1a5fb9fed237dbea0c1d83d3f39c6dfc2341a4cbf7406f0da25cbd6e36e46cf1df46d3428f0444a8860ca13265c117 Nov 5 15:03:11.183450 unknown[1131]: fetched base config from "system" Nov 5 15:03:11.183824 ignition[1131]: fetch: fetch complete Nov 5 15:03:11.183462 unknown[1131]: fetched user config from "aws" Nov 5 15:03:11.183838 ignition[1131]: fetch: fetch passed Nov 5 15:03:11.183918 ignition[1131]: Ignition finished successfully Nov 5 15:03:11.201838 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 5 15:03:11.206835 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 15:03:11.270688 ignition[1138]: Ignition 2.22.0 Nov 5 15:03:11.271217 ignition[1138]: Stage: kargs Nov 5 15:03:11.271761 ignition[1138]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:11.271782 ignition[1138]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:11.271912 ignition[1138]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:11.282783 ignition[1138]: PUT result: OK Nov 5 15:03:11.286857 ignition[1138]: kargs: kargs passed Nov 5 15:03:11.286947 ignition[1138]: Ignition finished successfully Nov 5 15:03:11.292362 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 15:03:11.300691 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 15:03:11.356052 ignition[1144]: Ignition 2.22.0 Nov 5 15:03:11.356578 ignition[1144]: Stage: disks Nov 5 15:03:11.357143 ignition[1144]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:11.357164 ignition[1144]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:11.357292 ignition[1144]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:11.367336 ignition[1144]: PUT result: OK Nov 5 15:03:11.372063 ignition[1144]: disks: disks passed Nov 5 15:03:11.372188 ignition[1144]: Ignition finished successfully Nov 5 15:03:11.379407 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 15:03:11.386533 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 15:03:11.389870 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 15:03:11.393330 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:03:11.399556 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:03:11.400019 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:03:11.402321 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 15:03:11.489777 systemd-fsck[1152]: ROOT: clean, 15/1631200 files, 112378/1617920 blocks Nov 5 15:03:11.499980 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 15:03:11.508037 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 15:03:11.772111 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 67ab558f-e1dc-496b-b18a-e9709809a3c4 r/w with ordered data mode. Quota mode: none. Nov 5 15:03:11.773500 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 15:03:11.774543 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 15:03:11.778801 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:03:11.783359 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 15:03:11.799438 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 5 15:03:11.799508 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 15:03:11.799562 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:03:11.833355 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1171) Nov 5 15:03:11.837210 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 15:03:11.840655 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:03:11.841627 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:03:11.846050 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 15:03:11.861909 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 5 15:03:11.861978 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 5 15:03:11.864174 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:03:12.892989 initrd-setup-root[1195]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 15:03:12.904039 initrd-setup-root[1202]: cut: /sysroot/etc/group: No such file or directory Nov 5 15:03:12.913450 initrd-setup-root[1209]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 15:03:12.925379 initrd-setup-root[1216]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 15:03:13.551038 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 15:03:13.558241 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 15:03:13.569339 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 15:03:13.605278 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 15:03:13.609833 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:03:13.644065 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 15:03:13.662464 ignition[1285]: INFO : Ignition 2.22.0 Nov 5 15:03:13.665449 ignition[1285]: INFO : Stage: mount Nov 5 15:03:13.665449 ignition[1285]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:13.665449 ignition[1285]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:13.665449 ignition[1285]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:13.677909 ignition[1285]: INFO : PUT result: OK Nov 5 15:03:13.683593 ignition[1285]: INFO : mount: mount passed Nov 5 15:03:13.685977 ignition[1285]: INFO : Ignition finished successfully Nov 5 15:03:13.689495 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 15:03:13.698213 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 15:03:13.725225 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:03:13.775457 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1296) Nov 5 15:03:13.775519 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:03:13.779370 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:03:13.786858 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 5 15:03:13.786933 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 5 15:03:13.790018 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:03:13.839637 ignition[1313]: INFO : Ignition 2.22.0 Nov 5 15:03:13.839637 ignition[1313]: INFO : Stage: files Nov 5 15:03:13.844950 ignition[1313]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:13.844950 ignition[1313]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:13.844950 ignition[1313]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:13.844950 ignition[1313]: INFO : PUT result: OK Nov 5 15:03:13.857839 ignition[1313]: DEBUG : files: compiled without relabeling support, skipping Nov 5 15:03:13.862746 ignition[1313]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 15:03:13.862746 ignition[1313]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 15:03:13.906262 ignition[1313]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 15:03:13.910846 ignition[1313]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 15:03:13.910846 ignition[1313]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 15:03:13.910846 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 5 15:03:13.910846 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 5 15:03:13.907616 unknown[1313]: wrote ssh authorized keys file for user: core Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:03:13.928950 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.34.1-arm64.raw: attempt #1 Nov 5 15:03:14.258908 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 5 15:03:14.646952 ignition[1313]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.1-arm64.raw" Nov 5 15:03:14.654961 ignition[1313]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:03:14.654961 ignition[1313]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:03:14.654961 ignition[1313]: INFO : files: files passed Nov 5 15:03:14.654961 ignition[1313]: INFO : Ignition finished successfully Nov 5 15:03:14.670480 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 15:03:14.676224 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 15:03:14.685382 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 15:03:14.703733 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 15:03:14.704019 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 15:03:14.724997 initrd-setup-root-after-ignition[1344]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:03:14.724997 initrd-setup-root-after-ignition[1344]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:03:14.735786 initrd-setup-root-after-ignition[1348]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:03:14.743271 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:03:14.750163 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 15:03:14.754570 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 15:03:14.855295 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 15:03:14.855704 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 15:03:14.866586 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 15:03:14.870194 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 15:03:14.870758 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 15:03:14.872257 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 15:03:14.930791 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:03:14.934536 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 15:03:14.973611 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:03:14.973911 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:03:14.980276 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:03:14.984630 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 15:03:14.988970 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 15:03:14.989333 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:03:14.995709 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 15:03:14.999274 systemd[1]: Stopped target basic.target - Basic System. Nov 5 15:03:15.003839 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 15:03:15.012490 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:03:15.020413 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 15:03:15.026484 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:03:15.030242 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 15:03:15.038877 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:03:15.042927 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 15:03:15.047675 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 15:03:15.050630 systemd[1]: Stopped target swap.target - Swaps. Nov 5 15:03:15.065222 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 15:03:15.067808 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:03:15.073733 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:03:15.076959 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:03:15.082682 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 15:03:15.085398 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:03:15.088737 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 15:03:15.088972 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 15:03:15.099062 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 15:03:15.099399 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:03:15.105028 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 15:03:15.105645 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 15:03:15.111988 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 15:03:15.115129 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 15:03:15.118863 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:03:15.143471 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 15:03:15.154931 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 15:03:15.155305 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:03:15.159279 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 15:03:15.159545 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:03:15.183222 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 15:03:15.186497 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:03:15.204116 ignition[1368]: INFO : Ignition 2.22.0 Nov 5 15:03:15.204116 ignition[1368]: INFO : Stage: umount Nov 5 15:03:15.209950 ignition[1368]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:03:15.209950 ignition[1368]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 5 15:03:15.209950 ignition[1368]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 5 15:03:15.209950 ignition[1368]: INFO : PUT result: OK Nov 5 15:03:15.224642 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 15:03:15.226435 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 15:03:15.237957 ignition[1368]: INFO : umount: umount passed Nov 5 15:03:15.240498 ignition[1368]: INFO : Ignition finished successfully Nov 5 15:03:15.247489 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 15:03:15.250481 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 15:03:15.260268 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 15:03:15.267836 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 15:03:15.270847 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 15:03:15.276926 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 15:03:15.277058 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 15:03:15.286197 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 5 15:03:15.286318 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 5 15:03:15.289348 systemd[1]: Stopped target network.target - Network. Nov 5 15:03:15.296696 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 15:03:15.296798 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:03:15.300298 systemd[1]: Stopped target paths.target - Path Units. Nov 5 15:03:15.307314 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 15:03:15.315053 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:15.322224 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 15:03:15.324733 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 15:03:15.331877 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 15:03:15.332069 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:03:15.339262 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 15:03:15.339337 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:03:15.342267 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 15:03:15.342368 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 15:03:15.349771 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 15:03:15.349854 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 15:03:15.353427 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 15:03:15.360714 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 15:03:15.364237 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 15:03:15.364419 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 15:03:15.376050 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 15:03:15.376670 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 15:03:15.396673 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 15:03:15.396953 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 15:03:15.408830 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 15:03:15.409284 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 15:03:15.423576 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 5 15:03:15.426432 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 15:03:15.426505 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:03:15.430755 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 15:03:15.441590 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 15:03:15.441724 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:03:15.450169 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 15:03:15.450265 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:03:15.453395 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 15:03:15.453475 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 15:03:15.457210 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:03:15.497604 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 15:03:15.502169 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:03:15.509626 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 15:03:15.509717 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 15:03:15.512817 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 15:03:15.512887 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:03:15.528597 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 15:03:15.528712 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:03:15.537014 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 15:03:15.537146 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 15:03:15.544812 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 15:03:15.545008 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:03:15.556194 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 15:03:15.559737 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 5 15:03:15.559855 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:03:15.569480 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 15:03:15.569588 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:03:15.573929 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:03:15.574019 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:03:15.630459 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 15:03:15.634968 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 15:03:15.642001 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 15:03:15.642361 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 15:03:15.652135 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 15:03:15.658778 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 15:03:15.697806 systemd[1]: Switching root. Nov 5 15:03:15.799898 systemd-journald[359]: Journal stopped Nov 5 15:03:19.848539 systemd-journald[359]: Received SIGTERM from PID 1 (systemd). Nov 5 15:03:19.848663 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 15:03:19.848708 kernel: SELinux: policy capability open_perms=1 Nov 5 15:03:19.848741 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 15:03:19.848780 kernel: SELinux: policy capability always_check_network=0 Nov 5 15:03:19.848813 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 15:03:19.848849 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 15:03:19.848884 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 15:03:19.848922 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 15:03:19.848952 kernel: SELinux: policy capability userspace_initial_context=0 Nov 5 15:03:19.848983 kernel: audit: type=1403 audit(1762354996.746:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 15:03:19.849026 systemd[1]: Successfully loaded SELinux policy in 144.096ms. Nov 5 15:03:19.849122 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.925ms. Nov 5 15:03:19.849171 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:03:19.849211 systemd[1]: Detected virtualization amazon. Nov 5 15:03:19.849244 systemd[1]: Detected architecture arm64. Nov 5 15:03:19.849279 systemd[1]: Detected first boot. Nov 5 15:03:19.849323 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 15:03:19.849353 zram_generator::config[1413]: No configuration found. Nov 5 15:03:19.849389 kernel: NET: Registered PF_VSOCK protocol family Nov 5 15:03:19.849419 systemd[1]: Populated /etc with preset unit settings. Nov 5 15:03:19.849452 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 15:03:19.849485 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 15:03:19.849524 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 15:03:19.849564 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 15:03:19.849614 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 15:03:19.849651 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 15:03:19.849687 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 15:03:19.849722 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 15:03:19.849755 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 15:03:19.849792 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 15:03:19.849828 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 15:03:19.849866 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:03:19.849900 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:19.849931 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 15:03:19.849961 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 15:03:19.849995 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 15:03:19.850038 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:03:19.850068 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 5 15:03:19.852216 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:03:19.852265 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:03:19.852299 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 15:03:19.852331 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 15:03:19.852368 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 15:03:19.852398 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 15:03:19.852427 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:03:19.852461 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:03:19.852490 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:03:19.852519 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:03:19.852551 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 15:03:19.852581 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 15:03:19.852617 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 15:03:19.852649 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:03:19.852681 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:03:19.852713 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:03:19.852746 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 15:03:19.856279 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 15:03:19.856324 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 15:03:19.856362 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 15:03:19.856392 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 15:03:19.856422 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 15:03:19.856455 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 15:03:19.856486 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 5 15:03:19.856515 systemd[1]: Reached target machines.target - Containers. Nov 5 15:03:19.856545 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 15:03:19.856581 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:03:19.856611 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:03:19.856642 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 15:03:19.856672 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:03:19.856704 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:03:19.856734 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:03:19.856766 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 15:03:19.856799 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:03:19.856829 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 15:03:19.856860 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 15:03:19.856890 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 15:03:19.856922 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 15:03:19.856952 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 15:03:19.856988 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:03:19.857019 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:03:19.857050 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:03:19.859106 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:03:19.859158 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 15:03:19.859190 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 15:03:19.859224 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:03:19.859262 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 15:03:19.859296 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 15:03:19.859326 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 15:03:19.859358 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 15:03:19.859391 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 15:03:19.859421 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 15:03:19.859450 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:03:19.859482 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 15:03:19.859514 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 15:03:19.859543 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:03:19.859577 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:03:19.859610 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:03:19.859644 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:03:19.859674 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:03:19.859702 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:03:19.859732 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:03:19.859761 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:03:19.859791 kernel: fuse: init (API version 7.41) Nov 5 15:03:19.859824 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 15:03:19.859853 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 15:03:19.859888 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 15:03:19.859917 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:03:19.859946 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 5 15:03:19.859979 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 15:03:19.860008 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 15:03:19.860038 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 15:03:19.860068 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:03:19.860177 systemd-journald[1492]: Collecting audit messages is disabled. Nov 5 15:03:19.860233 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 15:03:19.860264 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:03:19.860293 systemd-journald[1492]: Journal started Nov 5 15:03:19.860340 systemd-journald[1492]: Runtime Journal (/run/log/journal/ec25ca77a0ad8f762182f0d78bf8b2cc) is 8M, max 75.3M, 67.3M free. Nov 5 15:03:19.133276 systemd[1]: Queued start job for default target multi-user.target. Nov 5 15:03:19.155928 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 5 15:03:19.156870 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 15:03:19.878692 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 15:03:19.878783 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:03:19.889124 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 15:03:19.889241 kernel: ACPI: bus type drm_connector registered Nov 5 15:03:19.898213 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:03:19.907826 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:03:19.914124 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 15:03:19.932678 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:03:19.932348 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:03:19.933214 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:03:19.937426 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 15:03:19.945126 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 15:03:19.948987 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 15:03:19.976153 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 15:03:20.000901 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 15:03:20.008719 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 15:03:20.018526 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 15:03:20.051173 kernel: loop1: detected capacity change from 0 to 200800 Nov 5 15:03:20.056898 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 15:03:20.065442 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 15:03:20.071384 systemd-journald[1492]: Time spent on flushing to /var/log/journal/ec25ca77a0ad8f762182f0d78bf8b2cc is 79.093ms for 900 entries. Nov 5 15:03:20.071384 systemd-journald[1492]: System Journal (/var/log/journal/ec25ca77a0ad8f762182f0d78bf8b2cc) is 8M, max 588.1M, 580.1M free. Nov 5 15:03:20.186365 systemd-journald[1492]: Received client request to flush runtime journal. Nov 5 15:03:20.112206 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 15:03:20.157431 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:03:20.165126 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 15:03:20.190671 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 15:03:20.226233 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:03:20.265328 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 15:03:20.277352 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:03:20.286474 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:03:20.337247 kernel: loop2: detected capacity change from 0 to 61264 Nov 5 15:03:20.344340 systemd-tmpfiles[1563]: ACLs are not supported, ignoring. Nov 5 15:03:20.344890 systemd-tmpfiles[1563]: ACLs are not supported, ignoring. Nov 5 15:03:20.353359 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:03:20.363801 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 15:03:20.442099 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 15:03:20.590046 systemd-resolved[1562]: Positive Trust Anchors: Nov 5 15:03:20.590575 systemd-resolved[1562]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:03:20.590590 systemd-resolved[1562]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:03:20.590675 systemd-resolved[1562]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 15:03:20.604290 systemd-resolved[1562]: Defaulting to hostname 'linux'. Nov 5 15:03:20.606743 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:03:20.612111 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:03:20.691131 kernel: loop3: detected capacity change from 0 to 100624 Nov 5 15:03:20.963259 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 15:03:20.972312 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:03:21.040827 systemd-udevd[1576]: Using default interface naming scheme 'v257'. Nov 5 15:03:21.115137 kernel: loop4: detected capacity change from 0 to 119344 Nov 5 15:03:21.141142 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:03:21.153345 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:03:21.308302 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 5 15:03:21.319999 (udev-worker)[1585]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:03:21.371890 systemd-networkd[1580]: lo: Link UP Nov 5 15:03:21.372655 systemd-networkd[1580]: lo: Gained carrier Nov 5 15:03:21.376494 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:03:21.379020 systemd-networkd[1580]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:03:21.379174 systemd-networkd[1580]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:03:21.381954 systemd[1]: Reached target network.target - Network. Nov 5 15:03:21.388293 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 15:03:21.397965 systemd-networkd[1580]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:03:21.399690 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 15:03:21.400803 systemd-networkd[1580]: eth0: Link UP Nov 5 15:03:21.402885 systemd-networkd[1580]: eth0: Gained carrier Nov 5 15:03:21.404378 systemd-networkd[1580]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:03:21.432630 kernel: loop5: detected capacity change from 0 to 200800 Nov 5 15:03:21.433586 systemd-networkd[1580]: eth0: DHCPv4 address 172.31.27.235/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 5 15:03:21.469124 kernel: loop6: detected capacity change from 0 to 61264 Nov 5 15:03:21.489166 kernel: loop7: detected capacity change from 0 to 100624 Nov 5 15:03:21.501045 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 15:03:21.516192 kernel: loop1: detected capacity change from 0 to 119344 Nov 5 15:03:21.529298 (sd-merge)[1611]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw', 'oem-ami.raw'. Nov 5 15:03:21.539299 (sd-merge)[1611]: Merged extensions into '/usr'. Nov 5 15:03:21.550471 systemd[1]: Reload requested from client PID 1521 ('systemd-sysext') (unit systemd-sysext.service)... Nov 5 15:03:21.550686 systemd[1]: Reloading... Nov 5 15:03:21.877001 zram_generator::config[1666]: No configuration found. Nov 5 15:03:22.501036 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 5 15:03:22.505949 systemd[1]: Reloading finished in 954 ms. Nov 5 15:03:22.524255 systemd-networkd[1580]: eth0: Gained IPv6LL Nov 5 15:03:22.545250 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 15:03:22.549857 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 15:03:22.610728 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 15:03:22.633310 systemd[1]: Starting ensure-sysext.service... Nov 5 15:03:22.637216 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 15:03:22.645554 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:03:22.655061 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:03:22.707370 systemd[1]: Reload requested from client PID 1795 ('systemctl') (unit ensure-sysext.service)... Nov 5 15:03:22.707403 systemd[1]: Reloading... Nov 5 15:03:22.759152 systemd-tmpfiles[1797]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 5 15:03:22.759224 systemd-tmpfiles[1797]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 5 15:03:22.759797 systemd-tmpfiles[1797]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 15:03:22.761349 systemd-tmpfiles[1797]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 15:03:22.763557 systemd-tmpfiles[1797]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 15:03:22.764107 systemd-tmpfiles[1797]: ACLs are not supported, ignoring. Nov 5 15:03:22.764253 systemd-tmpfiles[1797]: ACLs are not supported, ignoring. Nov 5 15:03:22.787342 systemd-tmpfiles[1797]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:03:22.787632 systemd-tmpfiles[1797]: Skipping /boot Nov 5 15:03:22.823636 systemd-tmpfiles[1797]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:03:22.823912 systemd-tmpfiles[1797]: Skipping /boot Nov 5 15:03:22.898192 zram_generator::config[1834]: No configuration found. Nov 5 15:03:23.328785 systemd[1]: Reloading finished in 620 ms. Nov 5 15:03:23.376173 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 15:03:23.380905 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:03:23.388256 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:03:23.408337 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:03:23.413050 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 15:03:23.423733 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 15:03:23.437709 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 15:03:23.447068 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 15:03:23.461481 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:03:23.466321 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:03:23.472831 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:03:23.481661 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:03:23.485438 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:03:23.485795 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:03:23.494855 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:03:23.496032 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:03:23.496735 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:03:23.506095 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:03:23.513289 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:03:23.518113 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:03:23.518361 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 15:03:23.518686 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 15:03:23.539380 systemd[1]: Finished ensure-sysext.service. Nov 5 15:03:23.556429 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 15:03:23.562887 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:03:23.563246 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:03:23.566905 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:03:23.574477 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:03:23.587768 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 15:03:23.591578 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:03:23.591954 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:03:23.601997 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:03:23.602750 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:03:23.607584 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:03:23.608572 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:03:23.673228 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 15:03:23.676700 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 5 15:03:23.716124 augenrules[1928]: No rules Nov 5 15:03:23.718376 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:03:23.718941 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:03:26.529307 ldconfig[1893]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 15:03:26.544256 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 15:03:26.551141 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 15:03:26.591231 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 15:03:26.594970 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:03:26.597770 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 15:03:26.601107 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 5 15:03:26.604740 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 15:03:26.608168 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 15:03:26.611748 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 15:03:26.615384 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 15:03:26.615445 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:03:26.617815 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:03:26.621993 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 15:03:26.627450 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 15:03:26.634532 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 15:03:26.638368 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 15:03:26.641779 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 15:03:26.649032 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 15:03:26.652538 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 15:03:26.656758 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 15:03:26.660027 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:03:26.662630 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:03:26.665136 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:03:26.665183 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:03:26.666934 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 15:03:26.673383 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 5 15:03:26.680582 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 15:03:26.692733 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 15:03:26.698952 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 15:03:26.713145 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 15:03:26.716151 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 5 15:03:26.730926 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:03:26.739532 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 15:03:26.749984 jq[1943]: false Nov 5 15:03:26.750530 systemd[1]: Started ntpd.service - Network Time Service. Nov 5 15:03:26.766406 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 15:03:26.780363 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 5 15:03:26.786983 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 15:03:26.796605 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 15:03:26.813380 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 15:03:26.819283 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 15:03:26.820274 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 15:03:26.823057 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 15:03:26.835006 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 15:03:26.846169 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 15:03:26.850389 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 15:03:26.851312 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 15:03:26.885986 extend-filesystems[1944]: Found /dev/nvme0n1p6 Nov 5 15:03:26.928214 extend-filesystems[1944]: Found /dev/nvme0n1p9 Nov 5 15:03:26.930298 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 15:03:26.933633 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 15:03:26.963178 extend-filesystems[1944]: Checking size of /dev/nvme0n1p9 Nov 5 15:03:26.976752 jq[1957]: true Nov 5 15:03:26.976232 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 15:03:26.991820 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 15:03:27.013008 dbus-daemon[1941]: [system] SELinux support is enabled Nov 5 15:03:27.018977 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 15:03:27.028300 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 5 15:03:27.028365 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 15:03:27.033370 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 5 15:03:27.033409 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 15:03:27.048474 extend-filesystems[1944]: Resized partition /dev/nvme0n1p9 Nov 5 15:03:27.055375 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 15:03:27.066779 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 5 15:03:27.076539 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 5 15:03:27.078460 dbus-daemon[1941]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1580 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 5 15:03:27.088569 (ntainerd)[1974]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 5 15:03:27.090425 ntpd[1947]: ntpd 4.2.8p18@1.4062-o Wed Nov 5 13:12:54 UTC 2025 (1): Starting Nov 5 15:03:27.095497 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: ntpd 4.2.8p18@1.4062-o Wed Nov 5 13:12:54 UTC 2025 (1): Starting Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: ---------------------------------------------------- Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: ntp-4 is maintained by Network Time Foundation, Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: corporation. Support and training for ntp-4 are Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: available at https://www.nwtime.org/support Nov 5 15:03:27.097887 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: ---------------------------------------------------- Nov 5 15:03:27.117518 jq[1999]: true Nov 5 15:03:27.090540 ntpd[1947]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 5 15:03:27.118012 extend-filesystems[2009]: resize2fs 1.47.3 (8-Jul-2025) Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: proto: precision = 0.096 usec (-23) Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: basedate set to 2025-10-24 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: gps base set to 2025-10-26 (week 2390) Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen and drop on 0 v6wildcard [::]:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen normally on 2 lo 127.0.0.1:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen normally on 3 eth0 172.31.27.235:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen normally on 4 lo [::1]:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listen normally on 5 eth0 [fe80::4d1:72ff:fe5b:f8fb%2]:123 Nov 5 15:03:27.127355 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: Listening on routing socket on fd #22 for interface updates Nov 5 15:03:27.090558 ntpd[1947]: ---------------------------------------------------- Nov 5 15:03:27.090593 ntpd[1947]: ntp-4 is maintained by Network Time Foundation, Nov 5 15:03:27.090615 ntpd[1947]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 5 15:03:27.090632 ntpd[1947]: corporation. Support and training for ntp-4 are Nov 5 15:03:27.090648 ntpd[1947]: available at https://www.nwtime.org/support Nov 5 15:03:27.090663 ntpd[1947]: ---------------------------------------------------- Nov 5 15:03:27.112055 ntpd[1947]: proto: precision = 0.096 usec (-23) Nov 5 15:03:27.112473 ntpd[1947]: basedate set to 2025-10-24 Nov 5 15:03:27.112497 ntpd[1947]: gps base set to 2025-10-26 (week 2390) Nov 5 15:03:27.112659 ntpd[1947]: Listen and drop on 0 v6wildcard [::]:123 Nov 5 15:03:27.112703 ntpd[1947]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 5 15:03:27.121541 ntpd[1947]: Listen normally on 2 lo 127.0.0.1:123 Nov 5 15:03:27.121619 ntpd[1947]: Listen normally on 3 eth0 172.31.27.235:123 Nov 5 15:03:27.121702 ntpd[1947]: Listen normally on 4 lo [::1]:123 Nov 5 15:03:27.121775 ntpd[1947]: Listen normally on 5 eth0 [fe80::4d1:72ff:fe5b:f8fb%2]:123 Nov 5 15:03:27.121850 ntpd[1947]: Listening on routing socket on fd #22 for interface updates Nov 5 15:03:27.148471 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 1617920 to 2604027 blocks Nov 5 15:03:27.161692 update_engine[1956]: I20251105 15:03:27.161294 1956 main.cc:92] Flatcar Update Engine starting Nov 5 15:03:27.164201 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 5 15:03:27.165632 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 5 15:03:27.165632 ntpd[1947]: 5 Nov 15:03:27 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 5 15:03:27.164258 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 5 15:03:27.166536 systemd[1]: Started update-engine.service - Update Engine. Nov 5 15:03:27.167282 update_engine[1956]: I20251105 15:03:27.167219 1956 update_check_scheduler.cc:74] Next update check in 8m50s Nov 5 15:03:27.208126 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 2604027 Nov 5 15:03:27.215587 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 15:03:27.231680 extend-filesystems[2009]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 5 15:03:27.231680 extend-filesystems[2009]: old_desc_blocks = 1, new_desc_blocks = 2 Nov 5 15:03:27.231680 extend-filesystems[2009]: The filesystem on /dev/nvme0n1p9 is now 2604027 (4k) blocks long. Nov 5 15:03:27.243850 extend-filesystems[1944]: Resized filesystem in /dev/nvme0n1p9 Nov 5 15:03:27.243396 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 15:03:27.249215 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 15:03:27.354173 coreos-metadata[1940]: Nov 05 15:03:27.353 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 5 15:03:27.355333 coreos-metadata[1940]: Nov 05 15:03:27.355 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 5 15:03:27.362917 coreos-metadata[1940]: Nov 05 15:03:27.362 INFO Fetch successful Nov 5 15:03:27.362917 coreos-metadata[1940]: Nov 05 15:03:27.362 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 5 15:03:27.375257 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetch successful Nov 5 15:03:27.375257 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 5 15:03:27.375257 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetch successful Nov 5 15:03:27.375257 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 5 15:03:27.375679 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetch successful Nov 5 15:03:27.375679 coreos-metadata[1940]: Nov 05 15:03:27.375 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 5 15:03:27.380326 coreos-metadata[1940]: Nov 05 15:03:27.380 INFO Fetch failed with 404: resource not found Nov 5 15:03:27.380507 coreos-metadata[1940]: Nov 05 15:03:27.380 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 5 15:03:27.386393 coreos-metadata[1940]: Nov 05 15:03:27.386 INFO Fetch successful Nov 5 15:03:27.386393 coreos-metadata[1940]: Nov 05 15:03:27.386 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 5 15:03:27.390209 coreos-metadata[1940]: Nov 05 15:03:27.390 INFO Fetch successful Nov 5 15:03:27.390209 coreos-metadata[1940]: Nov 05 15:03:27.390 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 5 15:03:27.392893 coreos-metadata[1940]: Nov 05 15:03:27.392 INFO Fetch successful Nov 5 15:03:27.392893 coreos-metadata[1940]: Nov 05 15:03:27.392 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 5 15:03:27.396341 coreos-metadata[1940]: Nov 05 15:03:27.396 INFO Fetch successful Nov 5 15:03:27.396341 coreos-metadata[1940]: Nov 05 15:03:27.396 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 5 15:03:27.396341 coreos-metadata[1940]: Nov 05 15:03:27.396 INFO Fetch successful Nov 5 15:03:27.438828 bash[2033]: Updated "/home/core/.ssh/authorized_keys" Nov 5 15:03:27.442912 systemd-logind[1955]: Watching system buttons on /dev/input/event0 (Power Button) Nov 5 15:03:27.442977 systemd-logind[1955]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 5 15:03:27.451675 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 15:03:27.459864 systemd-logind[1955]: New seat seat0. Nov 5 15:03:27.475991 systemd[1]: Starting sshkeys.service... Nov 5 15:03:27.477972 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 15:03:27.552713 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 5 15:03:27.558127 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 15:03:27.612029 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 5 15:03:27.620905 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 5 15:03:27.740300 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 5 15:03:27.743622 dbus-daemon[1941]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 5 15:03:27.746363 dbus-daemon[1941]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=2010 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 5 15:03:27.762629 systemd[1]: Starting polkit.service - Authorization Manager... Nov 5 15:03:27.801474 amazon-ssm-agent[2007]: Initializing new seelog logger Nov 5 15:03:27.803579 amazon-ssm-agent[2007]: New Seelog Logger Creation Complete Nov 5 15:03:27.803579 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.803579 amazon-ssm-agent[2007]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.803579 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 processing appconfig overrides Nov 5 15:03:27.804338 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.804941 amazon-ssm-agent[2007]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.805214 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 processing appconfig overrides Nov 5 15:03:27.805569 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.805657 amazon-ssm-agent[2007]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.805860 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 processing appconfig overrides Nov 5 15:03:27.810502 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8042 INFO Proxy environment variables: Nov 5 15:03:27.820192 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.820192 amazon-ssm-agent[2007]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:27.820192 amazon-ssm-agent[2007]: 2025/11/05 15:03:27 processing appconfig overrides Nov 5 15:03:27.910484 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8042 INFO http_proxy: Nov 5 15:03:28.013104 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8042 INFO no_proxy: Nov 5 15:03:28.094515 coreos-metadata[2087]: Nov 05 15:03:28.094 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 5 15:03:28.101075 coreos-metadata[2087]: Nov 05 15:03:28.100 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 5 15:03:28.103143 coreos-metadata[2087]: Nov 05 15:03:28.102 INFO Fetch successful Nov 5 15:03:28.103143 coreos-metadata[2087]: Nov 05 15:03:28.102 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 5 15:03:28.106103 coreos-metadata[2087]: Nov 05 15:03:28.105 INFO Fetch successful Nov 5 15:03:28.111056 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8042 INFO https_proxy: Nov 5 15:03:28.111476 unknown[2087]: wrote ssh authorized keys file for user: core Nov 5 15:03:28.210453 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8052 INFO Checking if agent identity type OnPrem can be assumed Nov 5 15:03:28.229480 update-ssh-keys[2157]: Updated "/home/core/.ssh/authorized_keys" Nov 5 15:03:28.224364 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 5 15:03:28.266186 systemd[1]: Finished sshkeys.service. Nov 5 15:03:28.318107 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.8053 INFO Checking if agent identity type EC2 can be assumed Nov 5 15:03:28.418216 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9608 INFO Agent will take identity from EC2 Nov 5 15:03:28.432372 locksmithd[2015]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 15:03:28.517615 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9628 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.3.0.0 Nov 5 15:03:28.550445 polkitd[2116]: Started polkitd version 126 Nov 5 15:03:28.558107 containerd[1974]: time="2025-11-05T15:03:28Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 5 15:03:28.560777 containerd[1974]: time="2025-11-05T15:03:28.560714529Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 5 15:03:28.579228 sshd_keygen[2008]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 15:03:28.587911 polkitd[2116]: Loading rules from directory /etc/polkit-1/rules.d Nov 5 15:03:28.589764 polkitd[2116]: Loading rules from directory /run/polkit-1/rules.d Nov 5 15:03:28.593265 polkitd[2116]: Error opening rules directory: Error opening directory “/run/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Nov 5 15:03:28.593934 polkitd[2116]: Loading rules from directory /usr/local/share/polkit-1/rules.d Nov 5 15:03:28.593981 polkitd[2116]: Error opening rules directory: Error opening directory “/usr/local/share/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Nov 5 15:03:28.594052 polkitd[2116]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 5 15:03:28.598455 polkitd[2116]: Finished loading, compiling and executing 2 rules Nov 5 15:03:28.599641 systemd[1]: Started polkit.service - Authorization Manager. Nov 5 15:03:28.602209 dbus-daemon[1941]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 5 15:03:28.603607 polkitd[2116]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 5 15:03:28.614126 containerd[1974]: time="2025-11-05T15:03:28.614016129Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="15.888µs" Nov 5 15:03:28.616869 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9629 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617116293Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617194677Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617482521Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617514861Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617567325Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617673381Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.617698533Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.618046161Z" level=info msg="skip loading plugin" 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" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:03:28.618138 containerd[1974]: time="2025-11-05T15:03:28.618113769Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:03:28.618548 containerd[1974]: time="2025-11-05T15:03:28.618156189Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:03:28.620205 containerd[1974]: time="2025-11-05T15:03:28.620153973Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 5 15:03:28.620429 containerd[1974]: time="2025-11-05T15:03:28.620384961Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 5 15:03:28.621164 containerd[1974]: time="2025-11-05T15:03:28.620830329Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:03:28.621164 containerd[1974]: time="2025-11-05T15:03:28.620913597Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:03:28.621164 containerd[1974]: time="2025-11-05T15:03:28.620944749Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 5 15:03:28.621164 containerd[1974]: time="2025-11-05T15:03:28.621020709Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 5 15:03:28.623643 containerd[1974]: time="2025-11-05T15:03:28.623574849Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 5 15:03:28.623846 containerd[1974]: time="2025-11-05T15:03:28.623763069Z" level=info msg="metadata content store policy set" policy=shared Nov 5 15:03:28.633262 systemd-resolved[1562]: System hostname changed to 'ip-172-31-27-235'. Nov 5 15:03:28.633749 systemd-hostnamed[2010]: Hostname set to (transient) Nov 5 15:03:28.635571 containerd[1974]: time="2025-11-05T15:03:28.635410533Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 5 15:03:28.635571 containerd[1974]: time="2025-11-05T15:03:28.635553357Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635600421Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635629329Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635657589Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635692089Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635721621Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635749665Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 5 15:03:28.635793 containerd[1974]: time="2025-11-05T15:03:28.635784177Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.635811129Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.635834565Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.635864409Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636117069Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636163221Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636238821Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636267321Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636299313Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636326001Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636352665Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636380025Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636407301Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636432717Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636458061Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 5 15:03:28.636953 containerd[1974]: time="2025-11-05T15:03:28.636847677Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 5 15:03:28.637942 containerd[1974]: time="2025-11-05T15:03:28.636881937Z" level=info msg="Start snapshots syncer" Nov 5 15:03:28.637942 containerd[1974]: time="2025-11-05T15:03:28.636936357Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 5 15:03:28.638668 containerd[1974]: time="2025-11-05T15:03:28.638545809Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 5 15:03:28.640195 containerd[1974]: time="2025-11-05T15:03:28.638693061Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 5 15:03:28.640195 containerd[1974]: time="2025-11-05T15:03:28.638881857Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 5 15:03:28.641385 containerd[1974]: time="2025-11-05T15:03:28.641319501Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 5 15:03:28.641474 containerd[1974]: time="2025-11-05T15:03:28.641406933Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 5 15:03:28.641474 containerd[1974]: time="2025-11-05T15:03:28.641439369Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 5 15:03:28.641556 containerd[1974]: time="2025-11-05T15:03:28.641471301Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 5 15:03:28.641556 containerd[1974]: time="2025-11-05T15:03:28.641500917Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 5 15:03:28.641556 containerd[1974]: time="2025-11-05T15:03:28.641527209Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 5 15:03:28.641696 containerd[1974]: time="2025-11-05T15:03:28.641554437Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 5 15:03:28.641696 containerd[1974]: time="2025-11-05T15:03:28.641609529Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 5 15:03:28.641696 containerd[1974]: time="2025-11-05T15:03:28.641637405Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 5 15:03:28.641696 containerd[1974]: time="2025-11-05T15:03:28.641664249Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 5 15:03:28.641883 containerd[1974]: time="2025-11-05T15:03:28.641752449Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:03:28.641883 containerd[1974]: time="2025-11-05T15:03:28.641786601Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:03:28.641883 containerd[1974]: time="2025-11-05T15:03:28.641808165Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:03:28.641883 containerd[1974]: time="2025-11-05T15:03:28.641832117Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:03:28.641883 containerd[1974]: time="2025-11-05T15:03:28.641855757Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 5 15:03:28.642075 containerd[1974]: time="2025-11-05T15:03:28.641886237Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 5 15:03:28.642075 containerd[1974]: time="2025-11-05T15:03:28.641912913Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 5 15:03:28.644193 containerd[1974]: time="2025-11-05T15:03:28.644150757Z" level=info msg="runtime interface created" Nov 5 15:03:28.644193 containerd[1974]: time="2025-11-05T15:03:28.644185401Z" level=info msg="created NRI interface" Nov 5 15:03:28.644306 containerd[1974]: time="2025-11-05T15:03:28.644212869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 5 15:03:28.644306 containerd[1974]: time="2025-11-05T15:03:28.644245797Z" level=info msg="Connect containerd service" Nov 5 15:03:28.644404 containerd[1974]: time="2025-11-05T15:03:28.644309613Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 15:03:28.645698 containerd[1974]: time="2025-11-05T15:03:28.645628581Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 5 15:03:28.660736 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 15:03:28.670775 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 15:03:28.714670 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 15:03:28.717215 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9629 INFO [amazon-ssm-agent] Starting Core Agent Nov 5 15:03:28.717176 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 15:03:28.725515 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 15:03:28.752782 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 15:03:28.763000 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 15:03:28.771967 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 5 15:03:28.777593 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 15:03:28.817506 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9629 INFO [amazon-ssm-agent] Registrar detected. Attempting registration Nov 5 15:03:28.923422 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9629 INFO [Registrar] Starting registrar module Nov 5 15:03:29.023751 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9688 INFO [EC2Identity] Checking disk for registration info Nov 5 15:03:29.054823 containerd[1974]: time="2025-11-05T15:03:29.054525715Z" level=info msg="Start subscribing containerd event" Nov 5 15:03:29.055164 containerd[1974]: time="2025-11-05T15:03:29.055003279Z" level=info msg="Start recovering state" Nov 5 15:03:29.055767 containerd[1974]: time="2025-11-05T15:03:29.055708591Z" level=info msg="Start event monitor" Nov 5 15:03:29.055920 containerd[1974]: time="2025-11-05T15:03:29.055884967Z" level=info msg="Start cni network conf syncer for default" Nov 5 15:03:29.056109 containerd[1974]: time="2025-11-05T15:03:29.056014507Z" level=info msg="Start streaming server" Nov 5 15:03:29.056233 containerd[1974]: time="2025-11-05T15:03:29.056205151Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 5 15:03:29.056412 containerd[1974]: time="2025-11-05T15:03:29.056367175Z" level=info msg="runtime interface starting up..." Nov 5 15:03:29.056783 containerd[1974]: time="2025-11-05T15:03:29.056541559Z" level=info msg="starting plugins..." Nov 5 15:03:29.056883 containerd[1974]: time="2025-11-05T15:03:29.056757163Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 5 15:03:29.057446 containerd[1974]: time="2025-11-05T15:03:29.056404903Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 15:03:29.057627 containerd[1974]: time="2025-11-05T15:03:29.057572719Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 15:03:29.067281 containerd[1974]: time="2025-11-05T15:03:29.060360091Z" level=info msg="containerd successfully booted in 0.508868s" Nov 5 15:03:29.060549 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 15:03:29.124279 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9689 INFO [EC2Identity] No registration info found for ec2 instance, attempting registration Nov 5 15:03:29.224577 amazon-ssm-agent[2007]: 2025-11-05 15:03:27.9689 INFO [EC2Identity] Generating registration keypair Nov 5 15:03:29.812682 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8124 INFO [EC2Identity] Checking write access before registering Nov 5 15:03:29.860437 amazon-ssm-agent[2007]: 2025/11/05 15:03:29 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:29.860437 amazon-ssm-agent[2007]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 5 15:03:29.861549 amazon-ssm-agent[2007]: 2025/11/05 15:03:29 processing appconfig overrides Nov 5 15:03:29.890026 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8131 INFO [EC2Identity] Registering EC2 instance with Systems Manager Nov 5 15:03:29.890026 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8599 INFO [EC2Identity] EC2 registration was successful. Nov 5 15:03:29.890026 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8599 INFO [amazon-ssm-agent] Registration attempted. Resuming core agent startup. Nov 5 15:03:29.890275 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8601 INFO [CredentialRefresher] credentialRefresher has started Nov 5 15:03:29.890275 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8601 INFO [CredentialRefresher] Starting credentials refresher loop Nov 5 15:03:29.890275 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8896 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 5 15:03:29.890275 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8899 INFO [CredentialRefresher] Credentials ready Nov 5 15:03:29.913567 amazon-ssm-agent[2007]: 2025-11-05 15:03:29.8901 INFO [CredentialRefresher] Next credential rotation will be in 29.9999919722 minutes Nov 5 15:03:30.921167 amazon-ssm-agent[2007]: 2025-11-05 15:03:30.9169 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 5 15:03:31.021302 amazon-ssm-agent[2007]: 2025-11-05 15:03:30.9209 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2221) started Nov 5 15:03:31.121640 amazon-ssm-agent[2007]: 2025-11-05 15:03:30.9210 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 5 15:03:31.796316 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:03:31.800700 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 15:03:31.806715 systemd[1]: Startup finished in 3.869s (kernel) + 11.423s (initrd) + 15.205s (userspace) = 30.498s. Nov 5 15:03:31.820543 (kubelet)[2237]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 15:03:31.846529 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 15:03:31.849946 systemd[1]: Started sshd@0-172.31.27.235:22-139.178.89.65:57740.service - OpenSSH per-connection server daemon (139.178.89.65:57740). Nov 5 15:03:32.220967 sshd[2239]: Accepted publickey for core from 139.178.89.65 port 57740 ssh2: RSA SHA256:AXdl0qxEckaI43Z7wHyF3i9fg3UyK1i6tXgWUp7EPFc Nov 5 15:03:32.226752 sshd-session[2239]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:03:32.244322 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 15:03:32.246518 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 15:03:32.267544 systemd-logind[1955]: New session 1 of user core. Nov 5 15:03:32.284219 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 15:03:32.290245 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 15:03:32.313670 (systemd)[2249]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:32.318288 systemd-logind[1955]: New session c1 of user core. Nov 5 15:03:32.606842 systemd[2249]: Queued start job for default target default.target. Nov 5 15:03:32.619268 systemd[2249]: Created slice app.slice - User Application Slice. Nov 5 15:03:32.619332 systemd[2249]: Reached target paths.target - Paths. Nov 5 15:03:32.619415 systemd[2249]: Reached target timers.target - Timers. Nov 5 15:03:32.621932 systemd[2249]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 15:03:32.649998 systemd[2249]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:03:32.650261 systemd[2249]: Reached target sockets.target - Sockets. Nov 5 15:03:32.650357 systemd[2249]: Reached target basic.target - Basic System. Nov 5 15:03:32.650437 systemd[2249]: Reached target default.target - Main User Target. Nov 5 15:03:32.650511 systemd[2249]: Startup finished in 317ms. Nov 5 15:03:32.651622 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 15:03:32.661325 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 15:03:32.820018 systemd[1]: Started sshd@1-172.31.27.235:22-139.178.89.65:57744.service - OpenSSH per-connection server daemon (139.178.89.65:57744). Nov 5 15:03:33.016228 sshd[2263]: Accepted publickey for core from 139.178.89.65 port 57744 ssh2: RSA SHA256:AXdl0qxEckaI43Z7wHyF3i9fg3UyK1i6tXgWUp7EPFc Nov 5 15:03:33.020287 sshd-session[2263]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:03:33.033832 systemd-logind[1955]: New session 2 of user core. Nov 5 15:03:33.039376 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 15:03:33.165118 sshd[2266]: Connection closed by 139.178.89.65 port 57744 Nov 5 15:03:33.165862 sshd-session[2263]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:33.175979 systemd[1]: sshd@1-172.31.27.235:22-139.178.89.65:57744.service: Deactivated successfully. Nov 5 15:03:33.180390 systemd[1]: session-2.scope: Deactivated successfully. Nov 5 15:03:33.183215 systemd-logind[1955]: Session 2 logged out. Waiting for processes to exit. Nov 5 15:03:33.185721 systemd-logind[1955]: Removed session 2. Nov 5 15:03:33.200800 systemd[1]: Started sshd@2-172.31.27.235:22-139.178.89.65:57746.service - OpenSSH per-connection server daemon (139.178.89.65:57746). Nov 5 15:03:33.397033 kubelet[2237]: E1105 15:03:33.396310 2237 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 5 15:03:33.400985 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 15:03:33.401326 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 15:03:33.402236 systemd[1]: kubelet.service: Consumed 1.284s CPU time, 248.4M memory peak. Nov 5 15:03:33.418976 sshd[2272]: Accepted publickey for core from 139.178.89.65 port 57746 ssh2: RSA SHA256:AXdl0qxEckaI43Z7wHyF3i9fg3UyK1i6tXgWUp7EPFc Nov 5 15:03:33.421051 sshd-session[2272]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:03:33.431189 systemd-logind[1955]: New session 3 of user core. Nov 5 15:03:33.438344 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 15:03:33.558155 sshd[2277]: Connection closed by 139.178.89.65 port 57746 Nov 5 15:03:33.558938 sshd-session[2272]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:33.565991 systemd[1]: sshd@2-172.31.27.235:22-139.178.89.65:57746.service: Deactivated successfully. Nov 5 15:03:33.569163 systemd[1]: session-3.scope: Deactivated successfully. Nov 5 15:03:33.571386 systemd-logind[1955]: Session 3 logged out. Waiting for processes to exit. Nov 5 15:03:33.574271 systemd-logind[1955]: Removed session 3. Nov 5 15:03:33.591006 systemd[1]: Started sshd@3-172.31.27.235:22-139.178.89.65:57754.service - OpenSSH per-connection server daemon (139.178.89.65:57754). Nov 5 15:03:33.784254 sshd[2283]: Accepted publickey for core from 139.178.89.65 port 57754 ssh2: RSA SHA256:AXdl0qxEckaI43Z7wHyF3i9fg3UyK1i6tXgWUp7EPFc Nov 5 15:03:33.787050 sshd-session[2283]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:03:33.794971 systemd-logind[1955]: New session 4 of user core. Nov 5 15:03:33.807344 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 15:03:33.931029 sshd[2286]: Connection closed by 139.178.89.65 port 57754 Nov 5 15:03:33.931847 sshd-session[2283]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:33.938240 systemd-logind[1955]: Session 4 logged out. Waiting for processes to exit. Nov 5 15:03:33.938700 systemd[1]: sshd@3-172.31.27.235:22-139.178.89.65:57754.service: Deactivated successfully. Nov 5 15:03:33.942432 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 15:03:33.947374 systemd-logind[1955]: Removed session 4. Nov 5 15:03:33.966814 systemd[1]: Started sshd@4-172.31.27.235:22-139.178.89.65:57766.service - OpenSSH per-connection server daemon (139.178.89.65:57766). Nov 5 15:03:34.166296 sshd[2292]: Accepted publickey for core from 139.178.89.65 port 57766 ssh2: RSA SHA256:AXdl0qxEckaI43Z7wHyF3i9fg3UyK1i6tXgWUp7EPFc Nov 5 15:03:34.168929 sshd-session[2292]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:03:34.177174 systemd-logind[1955]: New session 5 of user core. Nov 5 15:03:34.198468 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 15:03:34.382369 sudo[2296]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 5 15:03:34.382976 sudo[2296]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:03:36.161250 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:03:36.161767 systemd[1]: kubelet.service: Consumed 1.284s CPU time, 248.4M memory peak. Nov 5 15:03:36.165666 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:03:36.221058 systemd[1]: Reload requested from client PID 2329 ('systemctl') (unit session-5.scope)... Nov 5 15:03:36.221117 systemd[1]: Reloading... Nov 5 15:03:36.466160 zram_generator::config[2377]: No configuration found. Nov 5 15:03:36.927134 systemd[1]: Reloading finished in 705 ms. Nov 5 15:03:37.030119 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 5 15:03:37.030297 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 5 15:03:37.030802 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:03:37.030883 systemd[1]: kubelet.service: Consumed 237ms CPU time, 95M memory peak. Nov 5 15:03:37.033926 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:03:37.385502 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:03:37.402645 (kubelet)[2437]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 5 15:03:37.478951 kubelet[2437]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 5 15:03:37.478951 kubelet[2437]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 5 15:03:37.480164 kubelet[2437]: I1105 15:03:37.480060 2437 server.go:213] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 5 15:03:38.330470 kubelet[2437]: I1105 15:03:38.330419 2437 server.go:529] "Kubelet version" kubeletVersion="v1.34.1" Nov 5 15:03:38.330759 kubelet[2437]: I1105 15:03:38.330615 2437 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 5 15:03:38.333113 kubelet[2437]: I1105 15:03:38.333038 2437 watchdog_linux.go:95] "Systemd watchdog is not enabled" Nov 5 15:03:38.333271 kubelet[2437]: I1105 15:03:38.333245 2437 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 5 15:03:38.333823 kubelet[2437]: I1105 15:03:38.333796 2437 server.go:956] "Client rotation is on, will bootstrap in background" Nov 5 15:03:38.345110 kubelet[2437]: I1105 15:03:38.344956 2437 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 5 15:03:38.355819 kubelet[2437]: I1105 15:03:38.355768 2437 server.go:1423] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Nov 5 15:03:38.362016 kubelet[2437]: I1105 15:03:38.361953 2437 server.go:781] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Nov 5 15:03:38.362646 kubelet[2437]: I1105 15:03:38.362578 2437 container_manager_linux.go:270] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 5 15:03:38.362904 kubelet[2437]: I1105 15:03:38.362637 2437 container_manager_linux.go:275] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.27.235","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":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 5 15:03:38.362904 kubelet[2437]: I1105 15:03:38.362893 2437 topology_manager.go:138] "Creating topology manager with none policy" Nov 5 15:03:38.363176 kubelet[2437]: I1105 15:03:38.362913 2437 container_manager_linux.go:306] "Creating device plugin manager" Nov 5 15:03:38.363989 kubelet[2437]: I1105 15:03:38.363922 2437 container_manager_linux.go:315] "Creating Dynamic Resource Allocation (DRA) manager" Nov 5 15:03:38.368263 kubelet[2437]: I1105 15:03:38.368182 2437 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:03:38.370689 kubelet[2437]: I1105 15:03:38.370633 2437 kubelet.go:475] "Attempting to sync node with API server" Nov 5 15:03:38.371250 kubelet[2437]: I1105 15:03:38.371196 2437 kubelet.go:376] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 5 15:03:38.372619 kubelet[2437]: I1105 15:03:38.372557 2437 kubelet.go:387] "Adding apiserver pod source" Nov 5 15:03:38.372827 kubelet[2437]: I1105 15:03:38.372784 2437 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 5 15:03:38.372932 kubelet[2437]: E1105 15:03:38.372662 2437 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:38.372932 kubelet[2437]: E1105 15:03:38.372727 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:38.375495 kubelet[2437]: I1105 15:03:38.375417 2437 kuberuntime_manager.go:291] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Nov 5 15:03:38.377116 kubelet[2437]: I1105 15:03:38.376753 2437 kubelet.go:940] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 5 15:03:38.377116 kubelet[2437]: I1105 15:03:38.376805 2437 kubelet.go:964] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Nov 5 15:03:38.377116 kubelet[2437]: W1105 15:03:38.376884 2437 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 5 15:03:38.381611 kubelet[2437]: I1105 15:03:38.381579 2437 server.go:1262] "Started kubelet" Nov 5 15:03:38.383008 kubelet[2437]: I1105 15:03:38.382973 2437 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 5 15:03:38.397376 kubelet[2437]: I1105 15:03:38.397302 2437 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 5 15:03:38.399384 kubelet[2437]: I1105 15:03:38.398746 2437 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 5 15:03:38.399384 kubelet[2437]: I1105 15:03:38.399226 2437 server_v1.go:49] "podresources" method="list" useActivePods=true Nov 5 15:03:38.400120 kubelet[2437]: I1105 15:03:38.400053 2437 server.go:249] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 5 15:03:38.400404 kubelet[2437]: I1105 15:03:38.400357 2437 volume_manager.go:313] "Starting Kubelet Volume Manager" Nov 5 15:03:38.401012 kubelet[2437]: E1105 15:03:38.400965 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:38.401600 kubelet[2437]: I1105 15:03:38.401550 2437 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Nov 5 15:03:38.401883 kubelet[2437]: I1105 15:03:38.401808 2437 reconciler.go:29] "Reconciler: start to sync state" Nov 5 15:03:38.402904 kubelet[2437]: I1105 15:03:38.402876 2437 server.go:310] "Adding debug handlers to kubelet server" Nov 5 15:03:38.404063 kubelet[2437]: I1105 15:03:38.404013 2437 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 5 15:03:38.413670 kubelet[2437]: I1105 15:03:38.413302 2437 factory.go:223] Registration of the systemd container factory successfully Nov 5 15:03:38.413670 kubelet[2437]: I1105 15:03:38.413483 2437 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 5 15:03:38.419480 kubelet[2437]: E1105 15:03:38.417339 2437 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.27.235.1875248d46c1ff09 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.27.235,UID:172.31.27.235,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.27.235,},FirstTimestamp:2025-11-05 15:03:38.381524745 +0000 UTC m=+0.968596679,LastTimestamp:2025-11-05 15:03:38.381524745 +0000 UTC m=+0.968596679,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.27.235,}" Nov 5 15:03:38.419936 kubelet[2437]: E1105 15:03:38.419895 2437 reflector.go:205] "Failed to watch" err="failed to list *v1.Node: nodes \"172.31.27.235\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Nov 5 15:03:38.420942 kubelet[2437]: E1105 15:03:38.420829 2437 reflector.go:205] "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:160" type="*v1.Service" Nov 5 15:03:38.421834 kubelet[2437]: E1105 15:03:38.421540 2437 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.27.235\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Nov 5 15:03:38.421834 kubelet[2437]: E1105 15:03:38.421606 2437 reflector.go:205] "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:160" type="*v1.CSIDriver" Nov 5 15:03:38.422411 kubelet[2437]: E1105 15:03:38.422336 2437 kubelet.go:1615] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 5 15:03:38.425694 kubelet[2437]: I1105 15:03:38.425658 2437 factory.go:223] Registration of the containerd container factory successfully Nov 5 15:03:38.449351 kubelet[2437]: I1105 15:03:38.449295 2437 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 5 15:03:38.449351 kubelet[2437]: I1105 15:03:38.449327 2437 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 5 15:03:38.449351 kubelet[2437]: I1105 15:03:38.449359 2437 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:03:38.452133 kubelet[2437]: I1105 15:03:38.451129 2437 policy_none.go:49] "None policy: Start" Nov 5 15:03:38.452133 kubelet[2437]: I1105 15:03:38.451167 2437 memory_manager.go:187] "Starting memorymanager" policy="None" Nov 5 15:03:38.452133 kubelet[2437]: I1105 15:03:38.451191 2437 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Nov 5 15:03:38.453543 kubelet[2437]: I1105 15:03:38.453517 2437 policy_none.go:47] "Start" Nov 5 15:03:38.464054 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 5 15:03:38.483620 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 5 15:03:38.497563 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 5 15:03:38.501323 kubelet[2437]: E1105 15:03:38.501275 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:38.510214 kubelet[2437]: E1105 15:03:38.509797 2437 manager.go:513] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 5 15:03:38.515422 kubelet[2437]: I1105 15:03:38.515034 2437 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 5 15:03:38.516471 kubelet[2437]: I1105 15:03:38.516418 2437 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 5 15:03:38.519539 kubelet[2437]: I1105 15:03:38.517203 2437 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 5 15:03:38.523329 kubelet[2437]: E1105 15:03:38.523291 2437 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 5 15:03:38.523548 kubelet[2437]: E1105 15:03:38.523524 2437 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.27.235\" not found" Nov 5 15:03:38.618734 kubelet[2437]: I1105 15:03:38.617985 2437 kubelet_node_status.go:75] "Attempting to register node" node="172.31.27.235" Nov 5 15:03:38.627444 kubelet[2437]: I1105 15:03:38.627409 2437 kubelet_node_status.go:78] "Successfully registered node" node="172.31.27.235" Nov 5 15:03:38.627622 kubelet[2437]: E1105 15:03:38.627602 2437 kubelet_node_status.go:486] "Error updating node status, will retry" err="error getting node \"172.31.27.235\": node \"172.31.27.235\" not found" Nov 5 15:03:38.666284 kubelet[2437]: E1105 15:03:38.666246 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:38.675570 kubelet[2437]: I1105 15:03:38.675521 2437 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Nov 5 15:03:38.677927 kubelet[2437]: I1105 15:03:38.677695 2437 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Nov 5 15:03:38.677927 kubelet[2437]: I1105 15:03:38.677742 2437 status_manager.go:244] "Starting to sync pod status with apiserver" Nov 5 15:03:38.677927 kubelet[2437]: I1105 15:03:38.677795 2437 kubelet.go:2427] "Starting kubelet main sync loop" Nov 5 15:03:38.677927 kubelet[2437]: E1105 15:03:38.677863 2437 kubelet.go:2451] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 5 15:03:38.767005 kubelet[2437]: E1105 15:03:38.766956 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:38.867986 kubelet[2437]: E1105 15:03:38.867926 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:38.968878 kubelet[2437]: E1105 15:03:38.968746 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.069527 kubelet[2437]: E1105 15:03:39.069462 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.170297 kubelet[2437]: E1105 15:03:39.170240 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.271022 kubelet[2437]: E1105 15:03:39.270905 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.342575 kubelet[2437]: I1105 15:03:39.342516 2437 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 5 15:03:39.342804 kubelet[2437]: I1105 15:03:39.342771 2437 reflector.go:568] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:03:39.371924 kubelet[2437]: E1105 15:03:39.371878 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.373059 kubelet[2437]: E1105 15:03:39.373028 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:39.419400 sudo[2296]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:39.442438 sshd[2295]: Connection closed by 139.178.89.65 port 57766 Nov 5 15:03:39.443199 sshd-session[2292]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:39.450262 systemd[1]: sshd@4-172.31.27.235:22-139.178.89.65:57766.service: Deactivated successfully. Nov 5 15:03:39.454425 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 15:03:39.454925 systemd[1]: session-5.scope: Consumed 816ms CPU time, 76.7M memory peak. Nov 5 15:03:39.458704 systemd-logind[1955]: Session 5 logged out. Waiting for processes to exit. Nov 5 15:03:39.461781 systemd-logind[1955]: Removed session 5. Nov 5 15:03:39.472797 kubelet[2437]: E1105 15:03:39.472758 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.573835 kubelet[2437]: E1105 15:03:39.573718 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.674371 kubelet[2437]: E1105 15:03:39.674321 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.774647 kubelet[2437]: E1105 15:03:39.774603 2437 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"172.31.27.235\" not found" Nov 5 15:03:39.876850 kubelet[2437]: I1105 15:03:39.876637 2437 kuberuntime_manager.go:1828] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 5 15:03:39.877475 containerd[1974]: time="2025-11-05T15:03:39.877406163Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 5 15:03:39.879115 kubelet[2437]: I1105 15:03:39.878385 2437 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 5 15:03:40.374018 kubelet[2437]: E1105 15:03:40.373832 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:40.374018 kubelet[2437]: I1105 15:03:40.373859 2437 apiserver.go:52] "Watching apiserver" Nov 5 15:03:40.398302 systemd[1]: Created slice kubepods-burstable-pod1d7267c0_170c_4403_81e2_2f644597622f.slice - libcontainer container kubepods-burstable-pod1d7267c0_170c_4403_81e2_2f644597622f.slice. Nov 5 15:03:40.402298 kubelet[2437]: I1105 15:03:40.402256 2437 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Nov 5 15:03:40.415796 kubelet[2437]: I1105 15:03:40.415644 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/1d7267c0-170c-4403-81e2-2f644597622f-run\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.415796 kubelet[2437]: I1105 15:03:40.415737 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/1d7267c0-170c-4403-81e2-2f644597622f-cni-plugin\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.415796 kubelet[2437]: I1105 15:03:40.415787 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/1d7267c0-170c-4403-81e2-2f644597622f-flannel-cfg\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.416004 kubelet[2437]: I1105 15:03:40.415853 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6f760d8a-48d4-47c8-882d-74c57ba04d7e-xtables-lock\") pod \"kube-proxy-rljtg\" (UID: \"6f760d8a-48d4-47c8-882d-74c57ba04d7e\") " pod="kube-system/kube-proxy-rljtg" Nov 5 15:03:40.416004 kubelet[2437]: I1105 15:03:40.415903 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/6f760d8a-48d4-47c8-882d-74c57ba04d7e-lib-modules\") pod \"kube-proxy-rljtg\" (UID: \"6f760d8a-48d4-47c8-882d-74c57ba04d7e\") " pod="kube-system/kube-proxy-rljtg" Nov 5 15:03:40.416004 kubelet[2437]: I1105 15:03:40.415949 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4cjp4\" (UniqueName: \"kubernetes.io/projected/6f760d8a-48d4-47c8-882d-74c57ba04d7e-kube-api-access-4cjp4\") pod \"kube-proxy-rljtg\" (UID: \"6f760d8a-48d4-47c8-882d-74c57ba04d7e\") " pod="kube-system/kube-proxy-rljtg" Nov 5 15:03:40.416004 kubelet[2437]: I1105 15:03:40.415995 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/1d7267c0-170c-4403-81e2-2f644597622f-cni\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.416235 kubelet[2437]: I1105 15:03:40.416039 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1d7267c0-170c-4403-81e2-2f644597622f-xtables-lock\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.417477 systemd[1]: Created slice kubepods-besteffort-pod6f760d8a_48d4_47c8_882d_74c57ba04d7e.slice - libcontainer container kubepods-besteffort-pod6f760d8a_48d4_47c8_882d_74c57ba04d7e.slice. Nov 5 15:03:40.418333 kubelet[2437]: I1105 15:03:40.418148 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lfkkz\" (UniqueName: \"kubernetes.io/projected/1d7267c0-170c-4403-81e2-2f644597622f-kube-api-access-lfkkz\") pod \"kube-flannel-ds-w9vhx\" (UID: \"1d7267c0-170c-4403-81e2-2f644597622f\") " pod="kube-flannel/kube-flannel-ds-w9vhx" Nov 5 15:03:40.418333 kubelet[2437]: I1105 15:03:40.418258 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/6f760d8a-48d4-47c8-882d-74c57ba04d7e-kube-proxy\") pod \"kube-proxy-rljtg\" (UID: \"6f760d8a-48d4-47c8-882d-74c57ba04d7e\") " pod="kube-system/kube-proxy-rljtg" Nov 5 15:03:40.717399 containerd[1974]: time="2025-11-05T15:03:40.715872235Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-w9vhx,Uid:1d7267c0-170c-4403-81e2-2f644597622f,Namespace:kube-flannel,Attempt:0,}" Nov 5 15:03:40.734293 containerd[1974]: time="2025-11-05T15:03:40.734231606Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rljtg,Uid:6f760d8a-48d4-47c8-882d-74c57ba04d7e,Namespace:kube-system,Attempt:0,}" Nov 5 15:03:41.264192 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3306579731.mount: Deactivated successfully. Nov 5 15:03:41.273678 containerd[1974]: time="2025-11-05T15:03:41.273594772Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 15:03:41.276496 containerd[1974]: time="2025-11-05T15:03:41.276437146Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Nov 5 15:03:41.278685 containerd[1974]: time="2025-11-05T15:03:41.278564533Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 15:03:41.282318 containerd[1974]: time="2025-11-05T15:03:41.282176802Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 15:03:41.286523 containerd[1974]: time="2025-11-05T15:03:41.286357131Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 5 15:03:41.293833 containerd[1974]: time="2025-11-05T15:03:41.293276446Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 15:03:41.294344 containerd[1974]: time="2025-11-05T15:03:41.294304895Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 569.097191ms" Nov 5 15:03:41.295918 containerd[1974]: time="2025-11-05T15:03:41.295853861Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 555.59359ms" Nov 5 15:03:41.335466 containerd[1974]: time="2025-11-05T15:03:41.335351746Z" level=info msg="connecting to shim 792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0" address="unix:///run/containerd/s/49fad4d7f339b9455b18457a57c87f146b69dd6ba279c973e1de8676cfdbd959" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:03:41.335740 containerd[1974]: time="2025-11-05T15:03:41.335389023Z" level=info msg="connecting to shim 127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3" address="unix:///run/containerd/s/07d3f53abe20d636d7222f10e68a7b200408000c275393410fd2eb49bdda3ee6" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:03:41.374488 kubelet[2437]: E1105 15:03:41.374442 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:41.392505 systemd[1]: Started cri-containerd-127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3.scope - libcontainer container 127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3. Nov 5 15:03:41.397244 systemd[1]: Started cri-containerd-792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0.scope - libcontainer container 792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0. Nov 5 15:03:41.471860 containerd[1974]: time="2025-11-05T15:03:41.471605769Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rljtg,Uid:6f760d8a-48d4-47c8-882d-74c57ba04d7e,Namespace:kube-system,Attempt:0,} returns sandbox id \"792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0\"" Nov 5 15:03:41.482003 containerd[1974]: time="2025-11-05T15:03:41.481945797Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\"" Nov 5 15:03:41.490910 containerd[1974]: time="2025-11-05T15:03:41.490751899Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-w9vhx,Uid:1d7267c0-170c-4403-81e2-2f644597622f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\"" Nov 5 15:03:42.375283 kubelet[2437]: E1105 15:03:42.375150 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:42.772436 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3132135929.mount: Deactivated successfully. Nov 5 15:03:43.180051 containerd[1974]: time="2025-11-05T15:03:43.179734067Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.34.1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:43.181616 containerd[1974]: time="2025-11-05T15:03:43.181572879Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.34.1: active requests=0, bytes read=22789028" Nov 5 15:03:43.182232 containerd[1974]: time="2025-11-05T15:03:43.182163284Z" level=info msg="ImageCreate event name:\"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:43.186253 containerd[1974]: time="2025-11-05T15:03:43.186174809Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:43.188014 containerd[1974]: time="2025-11-05T15:03:43.187364182Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.34.1\" with image id \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\", repo tag \"registry.k8s.io/kube-proxy:v1.34.1\", repo digest \"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\", size \"22788047\" in 1.705360431s" Nov 5 15:03:43.188014 containerd[1974]: time="2025-11-05T15:03:43.187422833Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.1\" returns image reference \"sha256:05baa95f5142d87797a2bc1d3d11edfb0bf0a9236d436243d15061fae8b58cb9\"" Nov 5 15:03:43.190356 containerd[1974]: time="2025-11-05T15:03:43.190286736Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 5 15:03:43.201813 containerd[1974]: time="2025-11-05T15:03:43.201746391Z" level=info msg="CreateContainer within sandbox \"792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 5 15:03:43.215060 containerd[1974]: time="2025-11-05T15:03:43.215009488Z" level=info msg="Container 385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:43.235609 containerd[1974]: time="2025-11-05T15:03:43.235408476Z" level=info msg="CreateContainer within sandbox \"792a52b4811f968b82301a523039b296aa4297e149e632f0f5efa52a08c7c2b0\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85\"" Nov 5 15:03:43.236739 containerd[1974]: time="2025-11-05T15:03:43.236696655Z" level=info msg="StartContainer for \"385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85\"" Nov 5 15:03:43.240343 containerd[1974]: time="2025-11-05T15:03:43.240237080Z" level=info msg="connecting to shim 385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85" address="unix:///run/containerd/s/49fad4d7f339b9455b18457a57c87f146b69dd6ba279c973e1de8676cfdbd959" protocol=ttrpc version=3 Nov 5 15:03:43.280402 systemd[1]: Started cri-containerd-385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85.scope - libcontainer container 385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85. Nov 5 15:03:43.355242 containerd[1974]: time="2025-11-05T15:03:43.355068980Z" level=info msg="StartContainer for \"385d1ddab474d216b519d35c89104e0bc9e470ae352286d74a1f83b612cf8e85\" returns successfully" Nov 5 15:03:43.376052 kubelet[2437]: E1105 15:03:43.376008 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:44.376886 kubelet[2437]: E1105 15:03:44.376827 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:45.377291 kubelet[2437]: E1105 15:03:45.377216 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:46.001920 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3963596301.mount: Deactivated successfully. Nov 5 15:03:46.063247 containerd[1974]: time="2025-11-05T15:03:46.063185636Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:46.065018 containerd[1974]: time="2025-11-05T15:03:46.064941342Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Nov 5 15:03:46.067101 containerd[1974]: time="2025-11-05T15:03:46.065803903Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:46.070726 containerd[1974]: time="2025-11-05T15:03:46.070676441Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:46.072852 containerd[1974]: time="2025-11-05T15:03:46.072785538Z" 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 2.882071504s" Nov 5 15:03:46.072852 containerd[1974]: time="2025-11-05T15:03:46.072844465Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Nov 5 15:03:46.079408 containerd[1974]: time="2025-11-05T15:03:46.079357578Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 5 15:03:46.094285 containerd[1974]: time="2025-11-05T15:03:46.092312874Z" level=info msg="Container 72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:46.110698 containerd[1974]: time="2025-11-05T15:03:46.110634944Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\"" Nov 5 15:03:46.111931 containerd[1974]: time="2025-11-05T15:03:46.111875459Z" level=info msg="StartContainer for \"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\"" Nov 5 15:03:46.114612 containerd[1974]: time="2025-11-05T15:03:46.114534805Z" level=info msg="connecting to shim 72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526" address="unix:///run/containerd/s/07d3f53abe20d636d7222f10e68a7b200408000c275393410fd2eb49bdda3ee6" protocol=ttrpc version=3 Nov 5 15:03:46.149374 systemd[1]: Started cri-containerd-72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526.scope - libcontainer container 72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526. Nov 5 15:03:46.201610 containerd[1974]: time="2025-11-05T15:03:46.201520055Z" level=info msg="StartContainer for \"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\" returns successfully" Nov 5 15:03:46.204758 systemd[1]: cri-containerd-72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526.scope: Deactivated successfully. Nov 5 15:03:46.212435 containerd[1974]: time="2025-11-05T15:03:46.212363998Z" level=info msg="TaskExit event in podsandbox handler container_id:\"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\" id:\"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\" pid:2773 exited_at:{seconds:1762355026 nanos:211510613}" Nov 5 15:03:46.212651 containerd[1974]: time="2025-11-05T15:03:46.212611387Z" level=info msg="received exit event container_id:\"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\" id:\"72686626331d6a08f30847c09bbabf342fb8fba0b5ebedc7297e8f0d10589526\" pid:2773 exited_at:{seconds:1762355026 nanos:211510613}" Nov 5 15:03:46.378285 kubelet[2437]: E1105 15:03:46.378134 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:46.715404 containerd[1974]: time="2025-11-05T15:03:46.715154171Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 5 15:03:46.730866 kubelet[2437]: I1105 15:03:46.730767 2437 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-rljtg" podStartSLOduration=7.021897174 podStartE2EDuration="8.730748626s" podCreationTimestamp="2025-11-05 15:03:38 +0000 UTC" firstStartedPulling="2025-11-05 15:03:41.47997956 +0000 UTC m=+4.067051482" lastFinishedPulling="2025-11-05 15:03:43.18883094 +0000 UTC m=+5.775902934" observedRunningTime="2025-11-05 15:03:43.720475906 +0000 UTC m=+6.307547828" watchObservedRunningTime="2025-11-05 15:03:46.730748626 +0000 UTC m=+9.317820536" Nov 5 15:03:47.378363 kubelet[2437]: E1105 15:03:47.378303 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:48.379502 kubelet[2437]: E1105 15:03:48.379349 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:49.295885 containerd[1974]: time="2025-11-05T15:03:49.295797084Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:49.298350 containerd[1974]: time="2025-11-05T15:03:49.298284759Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Nov 5 15:03:49.300135 containerd[1974]: time="2025-11-05T15:03:49.299284507Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:49.311116 containerd[1974]: time="2025-11-05T15:03:49.310454435Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:49.314577 containerd[1974]: time="2025-11-05T15:03:49.314510578Z" 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.599293847s" Nov 5 15:03:49.314796 containerd[1974]: time="2025-11-05T15:03:49.314766566Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Nov 5 15:03:49.324778 containerd[1974]: time="2025-11-05T15:03:49.324532777Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 5 15:03:49.337388 containerd[1974]: time="2025-11-05T15:03:49.336435706Z" level=info msg="Container 122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:49.349943 containerd[1974]: time="2025-11-05T15:03:49.349893598Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\"" Nov 5 15:03:49.351223 containerd[1974]: time="2025-11-05T15:03:49.351125226Z" level=info msg="StartContainer for \"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\"" Nov 5 15:03:49.352718 containerd[1974]: time="2025-11-05T15:03:49.352666923Z" level=info msg="connecting to shim 122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810" address="unix:///run/containerd/s/07d3f53abe20d636d7222f10e68a7b200408000c275393410fd2eb49bdda3ee6" protocol=ttrpc version=3 Nov 5 15:03:49.379903 kubelet[2437]: E1105 15:03:49.379843 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:49.394395 systemd[1]: Started cri-containerd-122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810.scope - libcontainer container 122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810. Nov 5 15:03:49.447861 systemd[1]: cri-containerd-122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810.scope: Deactivated successfully. Nov 5 15:03:49.451031 containerd[1974]: time="2025-11-05T15:03:49.450980734Z" level=info msg="received exit event container_id:\"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\" id:\"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\" pid:2848 exited_at:{seconds:1762355029 nanos:449862377}" Nov 5 15:03:49.451596 containerd[1974]: time="2025-11-05T15:03:49.451360116Z" level=info msg="TaskExit event in podsandbox handler container_id:\"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\" id:\"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\" pid:2848 exited_at:{seconds:1762355029 nanos:449862377}" Nov 5 15:03:49.453270 containerd[1974]: time="2025-11-05T15:03:49.453058298Z" level=info msg="StartContainer for \"122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810\" returns successfully" Nov 5 15:03:49.488511 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-122103003bde15d9ee46ee8f181fe1c058fdf30b3f14cdcb55507c879a1c0810-rootfs.mount: Deactivated successfully. Nov 5 15:03:49.510906 kubelet[2437]: I1105 15:03:49.510762 2437 kubelet_node_status.go:439] "Fast updating node status as it just became ready" Nov 5 15:03:49.730717 containerd[1974]: time="2025-11-05T15:03:49.730661624Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 5 15:03:49.739813 containerd[1974]: time="2025-11-05T15:03:49.739736656Z" level=info msg="Container 16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:49.748296 containerd[1974]: time="2025-11-05T15:03:49.748225158Z" level=info msg="CreateContainer within sandbox \"127b2778171c9637dce5f74ebdf5fff452edc7807dd8f42b0b9fb134060b36f3\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f\"" Nov 5 15:03:49.749265 containerd[1974]: time="2025-11-05T15:03:49.749211173Z" level=info msg="StartContainer for \"16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f\"" Nov 5 15:03:49.750904 containerd[1974]: time="2025-11-05T15:03:49.750827808Z" level=info msg="connecting to shim 16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f" address="unix:///run/containerd/s/07d3f53abe20d636d7222f10e68a7b200408000c275393410fd2eb49bdda3ee6" protocol=ttrpc version=3 Nov 5 15:03:49.783618 systemd[1]: Started cri-containerd-16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f.scope - libcontainer container 16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f. Nov 5 15:03:49.841250 containerd[1974]: time="2025-11-05T15:03:49.840937567Z" level=info msg="StartContainer for \"16f09cb489dd53cfe4936de92fd0bb3bf5eb75be19549413f7c82e1517ce1d6f\" returns successfully" Nov 5 15:03:50.380551 kubelet[2437]: E1105 15:03:50.380488 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:51.024041 (udev-worker)[2906]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:03:51.040748 systemd-networkd[1580]: flannel.1: Link UP Nov 5 15:03:51.040769 systemd-networkd[1580]: flannel.1: Gained carrier Nov 5 15:03:51.381526 kubelet[2437]: E1105 15:03:51.381454 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:51.564578 kubelet[2437]: I1105 15:03:51.564439 2437 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-w9vhx" podStartSLOduration=5.738792928 podStartE2EDuration="13.564417597s" podCreationTimestamp="2025-11-05 15:03:38 +0000 UTC" firstStartedPulling="2025-11-05 15:03:41.49360544 +0000 UTC m=+4.080677350" lastFinishedPulling="2025-11-05 15:03:49.319230109 +0000 UTC m=+11.906302019" observedRunningTime="2025-11-05 15:03:50.753439314 +0000 UTC m=+13.340511260" watchObservedRunningTime="2025-11-05 15:03:51.564417597 +0000 UTC m=+14.151489519" Nov 5 15:03:51.578260 systemd[1]: Created slice kubepods-besteffort-podf040abae_0630_4204_817e_d341c1925b76.slice - libcontainer container kubepods-besteffort-podf040abae_0630_4204_817e_d341c1925b76.slice. Nov 5 15:03:51.587428 kubelet[2437]: I1105 15:03:51.587387 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-shzs8\" (UniqueName: \"kubernetes.io/projected/f040abae-0630-4204-817e-d341c1925b76-kube-api-access-shzs8\") pod \"nginx-deployment-bb8f74bfb-db6c2\" (UID: \"f040abae-0630-4204-817e-d341c1925b76\") " pod="default/nginx-deployment-bb8f74bfb-db6c2" Nov 5 15:03:51.886239 containerd[1974]: time="2025-11-05T15:03:51.886178897Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-db6c2,Uid:f040abae-0630-4204-817e-d341c1925b76,Namespace:default,Attempt:0,}" Nov 5 15:03:51.914927 systemd-networkd[1580]: cni0: Link UP Nov 5 15:03:51.914939 systemd-networkd[1580]: cni0: Gained carrier Nov 5 15:03:51.917754 systemd-networkd[1580]: cni0: Lost carrier Nov 5 15:03:51.926165 (udev-worker)[2905]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:03:51.927248 systemd-networkd[1580]: veth36f92a30: Link UP Nov 5 15:03:51.932543 kernel: cni0: port 1(veth36f92a30) entered blocking state Nov 5 15:03:51.932693 kernel: cni0: port 1(veth36f92a30) entered disabled state Nov 5 15:03:51.932768 kernel: veth36f92a30: entered allmulticast mode Nov 5 15:03:51.938289 kernel: veth36f92a30: entered promiscuous mode Nov 5 15:03:51.961447 kernel: cni0: port 1(veth36f92a30) entered blocking state Nov 5 15:03:51.961581 kernel: cni0: port 1(veth36f92a30) entered forwarding state Nov 5 15:03:51.961697 systemd-networkd[1580]: veth36f92a30: Gained carrier Nov 5 15:03:51.963052 systemd-networkd[1580]: cni0: Gained carrier Nov 5 15:03:51.967764 containerd[1974]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000082950), "name":"cbr0", "type":"bridge"} Nov 5 15:03:51.967764 containerd[1974]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:03:52.012000 containerd[1974]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-05T15:03:52.011907525Z" level=info msg="connecting to shim de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f" address="unix:///run/containerd/s/a4d4521f73f7ed3a398f6d5bc38a16fecaec813eaf4521688277c8113f351b1d" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:03:52.074396 systemd[1]: Started cri-containerd-de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f.scope - libcontainer container de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f. Nov 5 15:03:52.144712 containerd[1974]: time="2025-11-05T15:03:52.144025490Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-db6c2,Uid:f040abae-0630-4204-817e-d341c1925b76,Namespace:default,Attempt:0,} returns sandbox id \"de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f\"" Nov 5 15:03:52.147611 containerd[1974]: time="2025-11-05T15:03:52.147568445Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:03:52.219410 systemd-networkd[1580]: flannel.1: Gained IPv6LL Nov 5 15:03:52.382054 kubelet[2437]: E1105 15:03:52.381946 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:52.987367 systemd-networkd[1580]: cni0: Gained IPv6LL Nov 5 15:03:53.382458 kubelet[2437]: E1105 15:03:53.382381 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:53.755335 systemd-networkd[1580]: veth36f92a30: Gained IPv6LL Nov 5 15:03:54.383043 kubelet[2437]: E1105 15:03:54.382955 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:55.383256 kubelet[2437]: E1105 15:03:55.383181 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:55.828475 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2110392299.mount: Deactivated successfully. Nov 5 15:03:56.103285 ntpd[1947]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 5 15:03:56.104529 ntpd[1947]: 5 Nov 15:03:56 ntpd[1947]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 5 15:03:56.104529 ntpd[1947]: 5 Nov 15:03:56 ntpd[1947]: Listen normally on 7 cni0 192.168.1.1:123 Nov 5 15:03:56.104529 ntpd[1947]: 5 Nov 15:03:56 ntpd[1947]: Listen normally on 8 flannel.1 [fe80::8c9d:71ff:fe07:67a6%3]:123 Nov 5 15:03:56.104529 ntpd[1947]: 5 Nov 15:03:56 ntpd[1947]: Listen normally on 9 cni0 [fe80::41a:b6ff:fea0:e049%4]:123 Nov 5 15:03:56.104529 ntpd[1947]: 5 Nov 15:03:56 ntpd[1947]: Listen normally on 10 veth36f92a30 [fe80::9823:f3ff:fe37:2963%5]:123 Nov 5 15:03:56.103549 ntpd[1947]: Listen normally on 7 cni0 192.168.1.1:123 Nov 5 15:03:56.103601 ntpd[1947]: Listen normally on 8 flannel.1 [fe80::8c9d:71ff:fe07:67a6%3]:123 Nov 5 15:03:56.103651 ntpd[1947]: Listen normally on 9 cni0 [fe80::41a:b6ff:fea0:e049%4]:123 Nov 5 15:03:56.103697 ntpd[1947]: Listen normally on 10 veth36f92a30 [fe80::9823:f3ff:fe37:2963%5]:123 Nov 5 15:03:56.383583 kubelet[2437]: E1105 15:03:56.383529 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:57.266527 containerd[1974]: time="2025-11-05T15:03:57.266446185Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:57.268550 containerd[1974]: time="2025-11-05T15:03:57.268161543Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 5 15:03:57.269749 containerd[1974]: time="2025-11-05T15:03:57.269690334Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:57.274105 containerd[1974]: time="2025-11-05T15:03:57.274022171Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:57.276102 containerd[1974]: time="2025-11-05T15:03:57.276022783Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 5.128226332s" Nov 5 15:03:57.276267 containerd[1974]: time="2025-11-05T15:03:57.276238051Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:03:57.282778 containerd[1974]: time="2025-11-05T15:03:57.282713731Z" level=info msg="CreateContainer within sandbox \"de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 5 15:03:57.293194 containerd[1974]: time="2025-11-05T15:03:57.293143294Z" level=info msg="Container 4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:57.303366 containerd[1974]: time="2025-11-05T15:03:57.303304851Z" level=info msg="CreateContainer within sandbox \"de4826700d1e70ac47b4737b3a919a075ee03fb147349470ebc1c37a617a311f\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a\"" Nov 5 15:03:57.304905 containerd[1974]: time="2025-11-05T15:03:57.304855555Z" level=info msg="StartContainer for \"4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a\"" Nov 5 15:03:57.307017 containerd[1974]: time="2025-11-05T15:03:57.306950271Z" level=info msg="connecting to shim 4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a" address="unix:///run/containerd/s/a4d4521f73f7ed3a398f6d5bc38a16fecaec813eaf4521688277c8113f351b1d" protocol=ttrpc version=3 Nov 5 15:03:57.348392 systemd[1]: Started cri-containerd-4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a.scope - libcontainer container 4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a. Nov 5 15:03:57.384413 kubelet[2437]: E1105 15:03:57.384371 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:57.405765 containerd[1974]: time="2025-11-05T15:03:57.405705581Z" level=info msg="StartContainer for \"4d43bf8ed80fe2b5baf5355b15b8dfd69a17febf049129107dcb759e8267313a\" returns successfully" Nov 5 15:03:58.372745 kubelet[2437]: E1105 15:03:58.372675 2437 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:58.385369 kubelet[2437]: E1105 15:03:58.385320 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:58.643968 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 5 15:03:59.386497 kubelet[2437]: E1105 15:03:59.386425 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:00.386897 kubelet[2437]: E1105 15:04:00.386834 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:01.387759 kubelet[2437]: E1105 15:04:01.387695 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:02.388246 kubelet[2437]: E1105 15:04:02.388181 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:03.388864 kubelet[2437]: E1105 15:04:03.388804 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:04.389338 kubelet[2437]: E1105 15:04:04.389272 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:05.075262 kubelet[2437]: I1105 15:04:05.075117 2437 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-bb8f74bfb-db6c2" podStartSLOduration=8.943395462 podStartE2EDuration="14.075067645s" podCreationTimestamp="2025-11-05 15:03:51 +0000 UTC" firstStartedPulling="2025-11-05 15:03:52.146449921 +0000 UTC m=+14.733521831" lastFinishedPulling="2025-11-05 15:03:57.278122104 +0000 UTC m=+19.865194014" observedRunningTime="2025-11-05 15:03:57.769376088 +0000 UTC m=+20.356448010" watchObservedRunningTime="2025-11-05 15:04:05.075067645 +0000 UTC m=+27.662139567" Nov 5 15:04:05.088539 systemd[1]: Created slice kubepods-besteffort-podd46c8ff9_eb1e_443b_a103_e714833fc86a.slice - libcontainer container kubepods-besteffort-podd46c8ff9_eb1e_443b_a103_e714833fc86a.slice. Nov 5 15:04:05.162507 kubelet[2437]: I1105 15:04:05.162336 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d46c8ff9-eb1e-443b-a103-e714833fc86a-data\") pod \"nfs-server-provisioner-0\" (UID: \"d46c8ff9-eb1e-443b-a103-e714833fc86a\") " pod="default/nfs-server-provisioner-0" Nov 5 15:04:05.162507 kubelet[2437]: I1105 15:04:05.162403 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gdkcz\" (UniqueName: \"kubernetes.io/projected/d46c8ff9-eb1e-443b-a103-e714833fc86a-kube-api-access-gdkcz\") pod \"nfs-server-provisioner-0\" (UID: \"d46c8ff9-eb1e-443b-a103-e714833fc86a\") " pod="default/nfs-server-provisioner-0" Nov 5 15:04:05.390438 kubelet[2437]: E1105 15:04:05.390390 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:05.396811 containerd[1974]: time="2025-11-05T15:04:05.396470660Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d46c8ff9-eb1e-443b-a103-e714833fc86a,Namespace:default,Attempt:0,}" Nov 5 15:04:05.417211 (udev-worker)[3170]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:04:05.419608 systemd-networkd[1580]: vetha3059226: Link UP Nov 5 15:04:05.425476 kernel: cni0: port 2(vetha3059226) entered blocking state Nov 5 15:04:05.425609 kernel: cni0: port 2(vetha3059226) entered disabled state Nov 5 15:04:05.426821 kernel: vetha3059226: entered allmulticast mode Nov 5 15:04:05.430009 kernel: vetha3059226: entered promiscuous mode Nov 5 15:04:05.441882 kernel: cni0: port 2(vetha3059226) entered blocking state Nov 5 15:04:05.441975 kernel: cni0: port 2(vetha3059226) entered forwarding state Nov 5 15:04:05.442495 systemd-networkd[1580]: vetha3059226: Gained carrier Nov 5 15:04:05.447332 containerd[1974]: 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)(0x4000084950), "name":"cbr0", "type":"bridge"} Nov 5 15:04:05.447332 containerd[1974]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:04:05.487433 containerd[1974]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-05T15:04:05.487354535Z" level=info msg="connecting to shim edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea" address="unix:///run/containerd/s/aeecd0f4f08bfeb050928a1a1c076090dbd696bdf7bb77b9d9f6014e18beb52d" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:04:05.540418 systemd[1]: Started cri-containerd-edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea.scope - libcontainer container edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea. Nov 5 15:04:05.607573 containerd[1974]: time="2025-11-05T15:04:05.607495999Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d46c8ff9-eb1e-443b-a103-e714833fc86a,Namespace:default,Attempt:0,} returns sandbox id \"edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea\"" Nov 5 15:04:05.611149 containerd[1974]: time="2025-11-05T15:04:05.610985449Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 5 15:04:06.390986 kubelet[2437]: E1105 15:04:06.390929 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:07.067932 systemd-networkd[1580]: vetha3059226: Gained IPv6LL Nov 5 15:04:07.391249 kubelet[2437]: E1105 15:04:07.391175 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:08.391580 kubelet[2437]: E1105 15:04:08.391469 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:09.023805 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2230914706.mount: Deactivated successfully. Nov 5 15:04:09.392731 kubelet[2437]: E1105 15:04:09.392523 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:10.104256 ntpd[1947]: Listen normally on 11 vetha3059226 [fe80::8fe:30ff:fe30:c510%6]:123 Nov 5 15:04:10.105326 ntpd[1947]: 5 Nov 15:04:10 ntpd[1947]: Listen normally on 11 vetha3059226 [fe80::8fe:30ff:fe30:c510%6]:123 Nov 5 15:04:10.392823 kubelet[2437]: E1105 15:04:10.392748 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:11.394186 kubelet[2437]: E1105 15:04:11.393828 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:12.048636 containerd[1974]: time="2025-11-05T15:04:12.048579085Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:04:12.049714 containerd[1974]: time="2025-11-05T15:04:12.049674953Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 5 15:04:12.051035 containerd[1974]: time="2025-11-05T15:04:12.050989819Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:04:12.055502 containerd[1974]: time="2025-11-05T15:04:12.055431473Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:04:12.057563 containerd[1974]: time="2025-11-05T15:04:12.057515227Z" 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.446434497s" Nov 5 15:04:12.057746 containerd[1974]: time="2025-11-05T15:04:12.057714987Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 5 15:04:12.063839 containerd[1974]: time="2025-11-05T15:04:12.063783459Z" level=info msg="CreateContainer within sandbox \"edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 5 15:04:12.076009 containerd[1974]: time="2025-11-05T15:04:12.075523678Z" level=info msg="Container 93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:04:12.086608 containerd[1974]: time="2025-11-05T15:04:12.086529025Z" level=info msg="CreateContainer within sandbox \"edb399f829fbc9034a8d392f182b53119249009d18a096fb3fcbac91ebdc2cea\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078\"" Nov 5 15:04:12.087868 containerd[1974]: time="2025-11-05T15:04:12.087815873Z" level=info msg="StartContainer for \"93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078\"" Nov 5 15:04:12.089906 containerd[1974]: time="2025-11-05T15:04:12.089788826Z" level=info msg="connecting to shim 93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078" address="unix:///run/containerd/s/aeecd0f4f08bfeb050928a1a1c076090dbd696bdf7bb77b9d9f6014e18beb52d" protocol=ttrpc version=3 Nov 5 15:04:12.133385 systemd[1]: Started cri-containerd-93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078.scope - libcontainer container 93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078. Nov 5 15:04:12.186348 containerd[1974]: time="2025-11-05T15:04:12.186295393Z" level=info msg="StartContainer for \"93ea0d1e31dc96212ea27b9c1970a64c7a3aa5e2614827c2709868703326f078\" returns successfully" Nov 5 15:04:12.309002 update_engine[1956]: I20251105 15:04:12.308143 1956 update_attempter.cc:509] Updating boot flags... Nov 5 15:04:12.394421 kubelet[2437]: E1105 15:04:12.394026 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:12.819304 kubelet[2437]: I1105 15:04:12.818819 2437 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.369973543 podStartE2EDuration="8.818798289s" podCreationTimestamp="2025-11-05 15:04:04 +0000 UTC" firstStartedPulling="2025-11-05 15:04:05.610495542 +0000 UTC m=+28.197567440" lastFinishedPulling="2025-11-05 15:04:12.059320276 +0000 UTC m=+34.646392186" observedRunningTime="2025-11-05 15:04:12.818595603 +0000 UTC m=+35.405667537" watchObservedRunningTime="2025-11-05 15:04:12.818798289 +0000 UTC m=+35.405870199" Nov 5 15:04:13.394613 kubelet[2437]: E1105 15:04:13.394548 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:14.395433 kubelet[2437]: E1105 15:04:14.395358 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:15.395702 kubelet[2437]: E1105 15:04:15.395629 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:16.396675 kubelet[2437]: E1105 15:04:16.396610 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:17.396851 kubelet[2437]: E1105 15:04:17.396787 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:17.652821 systemd[1]: Created slice kubepods-besteffort-pod94fe6882_cfdb_4c6d_8110_151506fa9adc.slice - libcontainer container kubepods-besteffort-pod94fe6882_cfdb_4c6d_8110_151506fa9adc.slice. Nov 5 15:04:17.840341 kubelet[2437]: I1105 15:04:17.840304 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g8mx2\" (UniqueName: \"kubernetes.io/projected/94fe6882-cfdb-4c6d-8110-151506fa9adc-kube-api-access-g8mx2\") pod \"test-pod-1\" (UID: \"94fe6882-cfdb-4c6d-8110-151506fa9adc\") " pod="default/test-pod-1" Nov 5 15:04:17.840702 kubelet[2437]: I1105 15:04:17.840587 2437 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-d6e7f176-ae58-4524-89ce-d7f109ee5f13\" (UniqueName: \"kubernetes.io/nfs/94fe6882-cfdb-4c6d-8110-151506fa9adc-pvc-d6e7f176-ae58-4524-89ce-d7f109ee5f13\") pod \"test-pod-1\" (UID: \"94fe6882-cfdb-4c6d-8110-151506fa9adc\") " pod="default/test-pod-1" Nov 5 15:04:18.012202 kernel: netfs: FS-Cache loaded Nov 5 15:04:18.082095 kernel: RPC: Registered named UNIX socket transport module. Nov 5 15:04:18.082244 kernel: RPC: Registered udp transport module. Nov 5 15:04:18.082289 kernel: RPC: Registered tcp transport module. Nov 5 15:04:18.084309 kernel: RPC: Registered tcp-with-tls transport module. Nov 5 15:04:18.084369 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 5 15:04:18.373577 kubelet[2437]: E1105 15:04:18.373516 2437 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:18.396954 kubelet[2437]: E1105 15:04:18.396905 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:18.446777 kernel: NFS: Registering the id_resolver key type Nov 5 15:04:18.446908 kernel: Key type id_resolver registered Nov 5 15:04:18.446952 kernel: Key type id_legacy registered Nov 5 15:04:18.515221 nfsidmap[3620]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Nov 5 15:04:18.517022 nfsidmap[3620]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 15:04:18.520911 nfsidmap[3621]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Nov 5 15:04:18.521483 nfsidmap[3621]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 15:04:18.549611 nfsrahead[3623]: setting /var/lib/kubelet/pods/94fe6882-cfdb-4c6d-8110-151506fa9adc/volumes/kubernetes.io~nfs/pvc-d6e7f176-ae58-4524-89ce-d7f109ee5f13 readahead to 128 Nov 5 15:04:18.562118 containerd[1974]: time="2025-11-05T15:04:18.561961021Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:94fe6882-cfdb-4c6d-8110-151506fa9adc,Namespace:default,Attempt:0,}" Nov 5 15:04:18.590425 (udev-worker)[3604]: Network interface NamePolicy= disabled on kernel command line. Nov 5 15:04:18.590496 systemd-networkd[1580]: veth9f913b6e: Link UP Nov 5 15:04:18.597023 kernel: cni0: port 3(veth9f913b6e) entered blocking state Nov 5 15:04:18.597160 kernel: cni0: port 3(veth9f913b6e) entered disabled state Nov 5 15:04:18.598181 kernel: veth9f913b6e: entered allmulticast mode Nov 5 15:04:18.599718 kernel: veth9f913b6e: entered promiscuous mode Nov 5 15:04:18.614877 kernel: cni0: port 3(veth9f913b6e) entered blocking state Nov 5 15:04:18.615004 kernel: cni0: port 3(veth9f913b6e) entered forwarding state Nov 5 15:04:18.615333 systemd-networkd[1580]: veth9f913b6e: Gained carrier Nov 5 15:04:18.638547 containerd[1974]: 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)(0x400008c950), "name":"cbr0", "type":"bridge"} Nov 5 15:04:18.638547 containerd[1974]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:04:18.697908 containerd[1974]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-11-05T15:04:18.697844837Z" level=info msg="connecting to shim d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236" address="unix:///run/containerd/s/23841da71886f2df447a3897727489454145bd6bd54596f69176abab331b4bcb" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:04:18.741387 systemd[1]: Started cri-containerd-d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236.scope - libcontainer container d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236. Nov 5 15:04:18.807926 containerd[1974]: time="2025-11-05T15:04:18.807854441Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:94fe6882-cfdb-4c6d-8110-151506fa9adc,Namespace:default,Attempt:0,} returns sandbox id \"d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236\"" Nov 5 15:04:18.809584 containerd[1974]: time="2025-11-05T15:04:18.809522711Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:04:19.193138 containerd[1974]: time="2025-11-05T15:04:19.192454430Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:04:19.193690 containerd[1974]: time="2025-11-05T15:04:19.193654634Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 5 15:04:19.198981 containerd[1974]: time="2025-11-05T15:04:19.198918416Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 389.333373ms" Nov 5 15:04:19.199235 containerd[1974]: time="2025-11-05T15:04:19.199205517Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:04:19.205117 containerd[1974]: time="2025-11-05T15:04:19.204885312Z" level=info msg="CreateContainer within sandbox \"d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 5 15:04:19.242583 containerd[1974]: time="2025-11-05T15:04:19.241145189Z" level=info msg="Container 33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:04:19.255004 containerd[1974]: time="2025-11-05T15:04:19.254955008Z" level=info msg="CreateContainer within sandbox \"d8ecb438e3790b877786efdab5ce599230a5571e5a8c8bc0c88c40a2951cb236\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72\"" Nov 5 15:04:19.256442 containerd[1974]: time="2025-11-05T15:04:19.256376033Z" level=info msg="StartContainer for \"33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72\"" Nov 5 15:04:19.258182 containerd[1974]: time="2025-11-05T15:04:19.258057316Z" level=info msg="connecting to shim 33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72" address="unix:///run/containerd/s/23841da71886f2df447a3897727489454145bd6bd54596f69176abab331b4bcb" protocol=ttrpc version=3 Nov 5 15:04:19.299370 systemd[1]: Started cri-containerd-33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72.scope - libcontainer container 33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72. Nov 5 15:04:19.359097 containerd[1974]: time="2025-11-05T15:04:19.359013957Z" level=info msg="StartContainer for \"33ec27eaa22273c890b1c61ab04feb21c6f1ca327e297394d419013884b4ba72\" returns successfully" Nov 5 15:04:19.397836 kubelet[2437]: E1105 15:04:19.397743 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:19.931345 systemd-networkd[1580]: veth9f913b6e: Gained IPv6LL Nov 5 15:04:20.398686 kubelet[2437]: E1105 15:04:20.398616 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:21.399832 kubelet[2437]: E1105 15:04:21.399768 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:22.400748 kubelet[2437]: E1105 15:04:22.400684 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:23.103244 ntpd[1947]: Listen normally on 12 veth9f913b6e [fe80::742a:f1ff:fe67:c75d%7]:123 Nov 5 15:04:23.103817 ntpd[1947]: 5 Nov 15:04:23 ntpd[1947]: Listen normally on 12 veth9f913b6e [fe80::742a:f1ff:fe67:c75d%7]:123 Nov 5 15:04:23.401586 kubelet[2437]: E1105 15:04:23.401531 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:24.401725 kubelet[2437]: E1105 15:04:24.401637 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:25.402808 kubelet[2437]: E1105 15:04:25.402738 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:26.404304 kubelet[2437]: E1105 15:04:26.404238 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:27.404601 kubelet[2437]: E1105 15:04:27.404521 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:28.405687 kubelet[2437]: E1105 15:04:28.405626 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:29.406431 kubelet[2437]: E1105 15:04:29.406369 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:30.407453 kubelet[2437]: E1105 15:04:30.407387 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:31.408316 kubelet[2437]: E1105 15:04:31.408262 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:32.409164 kubelet[2437]: E1105 15:04:32.409101 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:33.409462 kubelet[2437]: E1105 15:04:33.409405 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:34.409907 kubelet[2437]: E1105 15:04:34.409846 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:35.410801 kubelet[2437]: E1105 15:04:35.410728 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:36.411246 kubelet[2437]: E1105 15:04:36.411187 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:37.412345 kubelet[2437]: E1105 15:04:37.412280 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:38.373040 kubelet[2437]: E1105 15:04:38.372986 2437 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:38.412757 kubelet[2437]: E1105 15:04:38.412700 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:39.413634 kubelet[2437]: E1105 15:04:39.413579 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:40.414024 kubelet[2437]: E1105 15:04:40.413959 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:41.415013 kubelet[2437]: E1105 15:04:41.414949 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:42.415640 kubelet[2437]: E1105 15:04:42.415569 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:43.416255 kubelet[2437]: E1105 15:04:43.416192 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:44.417352 kubelet[2437]: E1105 15:04:44.417298 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:45.417979 kubelet[2437]: E1105 15:04:45.417909 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:46.418833 kubelet[2437]: E1105 15:04:46.418752 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:47.419604 kubelet[2437]: E1105 15:04:47.419548 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:48.420521 kubelet[2437]: E1105 15:04:48.420446 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:49.402166 kubelet[2437]: E1105 15:04:49.402067 2437 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.218:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.235?timeout=10s\": context deadline exceeded" Nov 5 15:04:49.421381 kubelet[2437]: E1105 15:04:49.421338 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:50.422340 kubelet[2437]: E1105 15:04:50.422275 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:50.501167 kubelet[2437]: E1105 15:04:50.500900 2437 kubelet_node_status.go:486] "Error updating node status, will retry" err="failed to patch status \"{\\\"status\\\":{\\\"$setElementOrder/conditions\\\":[{\\\"type\\\":\\\"NetworkUnavailable\\\"},{\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"type\\\":\\\"DiskPressure\\\"},{\\\"type\\\":\\\"PIDPressure\\\"},{\\\"type\\\":\\\"Ready\\\"}],\\\"conditions\\\":[{\\\"lastHeartbeatTime\\\":\\\"2025-11-05T15:04:40Z\\\",\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2025-11-05T15:04:40Z\\\",\\\"type\\\":\\\"DiskPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2025-11-05T15:04:40Z\\\",\\\"type\\\":\\\"PIDPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2025-11-05T15:04:40Z\\\",\\\"type\\\":\\\"Ready\\\"}],\\\"images\\\":[{\\\"names\\\":[\\\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\\\",\\\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\\\"],\\\"sizeBytes\\\":87371201},{\\\"names\\\":[\\\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\\\",\\\"ghcr.io/flatcar/nginx:latest\\\"],\\\"sizeBytes\\\":70017022},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\\\",\\\"ghcr.io/flannel-io/flannel:v0.26.7\\\"],\\\"sizeBytes\\\":32412118},{\\\"names\\\":[\\\"registry.k8s.io/kube-proxy@sha256:913cc83ca0b5588a81d86ce8eedeb3ed1e9c1326e81852a1ea4f622b74ff749a\\\",\\\"registry.k8s.io/kube-proxy:v1.34.1\\\"],\\\"sizeBytes\\\":22788047},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\\\",\\\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\\\"],\\\"sizeBytes\\\":5125394},{\\\"names\\\":[\\\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\\\",\\\"registry.k8s.io/pause:3.10\\\"],\\\"sizeBytes\\\":267933}]}}\" for node \"172.31.27.235\": Patch \"https://172.31.29.218:6443/api/v1/nodes/172.31.27.235/status?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" Nov 5 15:04:51.423258 kubelet[2437]: E1105 15:04:51.423191 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:52.424276 kubelet[2437]: E1105 15:04:52.424220 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:53.424982 kubelet[2437]: E1105 15:04:53.424906 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:54.425608 kubelet[2437]: E1105 15:04:54.425545 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:55.425904 kubelet[2437]: E1105 15:04:55.425834 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:56.427030 kubelet[2437]: E1105 15:04:56.426958 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:04:57.427594 kubelet[2437]: E1105 15:04:57.427543 2437 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"