Oct 29 00:09:34.337831 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Oct 29 00:09:34.337879 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 Tue Oct 28 22:30:37 -00 2025 Oct 29 00:09:34.337903 kernel: KASLR disabled due to lack of seed Oct 29 00:09:34.337919 kernel: efi: EFI v2.7 by EDK II Oct 29 00:09:34.337935 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a731a98 MEMRESERVE=0x78557598 Oct 29 00:09:34.337950 kernel: secureboot: Secure boot disabled Oct 29 00:09:34.337967 kernel: ACPI: Early table checksum verification disabled Oct 29 00:09:34.337983 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Oct 29 00:09:34.337998 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Oct 29 00:09:34.338017 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Oct 29 00:09:34.338034 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Oct 29 00:09:34.338049 kernel: ACPI: FACS 0x0000000078630000 000040 Oct 29 00:09:34.338065 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Oct 29 00:09:34.338080 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Oct 29 00:09:34.338103 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Oct 29 00:09:34.338119 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Oct 29 00:09:34.338136 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Oct 29 00:09:34.338153 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Oct 29 00:09:34.338169 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Oct 29 00:09:34.338186 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Oct 29 00:09:34.338202 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Oct 29 00:09:34.338218 kernel: printk: legacy bootconsole [uart0] enabled Oct 29 00:09:34.338235 kernel: ACPI: Use ACPI SPCR as default console: No Oct 29 00:09:34.338251 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Oct 29 00:09:34.338273 kernel: NODE_DATA(0) allocated [mem 0x4b584da00-0x4b5854fff] Oct 29 00:09:34.338290 kernel: Zone ranges: Oct 29 00:09:34.338307 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Oct 29 00:09:34.338323 kernel: DMA32 empty Oct 29 00:09:34.338340 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Oct 29 00:09:34.338356 kernel: Device empty Oct 29 00:09:34.338372 kernel: Movable zone start for each node Oct 29 00:09:34.338389 kernel: Early memory node ranges Oct 29 00:09:34.338405 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Oct 29 00:09:34.338421 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Oct 29 00:09:34.338438 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Oct 29 00:09:34.338454 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Oct 29 00:09:34.338476 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Oct 29 00:09:34.338493 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Oct 29 00:09:34.338542 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Oct 29 00:09:34.338566 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Oct 29 00:09:34.338596 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Oct 29 00:09:34.338618 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Oct 29 00:09:34.338656 kernel: cma: Reserved 16 MiB at 0x000000007f000000 on node -1 Oct 29 00:09:34.338675 kernel: psci: probing for conduit method from ACPI. Oct 29 00:09:34.338693 kernel: psci: PSCIv1.0 detected in firmware. Oct 29 00:09:34.338710 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 00:09:34.338727 kernel: psci: Trusted OS migration not required Oct 29 00:09:34.338745 kernel: psci: SMC Calling Convention v1.1 Oct 29 00:09:34.338763 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Oct 29 00:09:34.338781 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 29 00:09:34.338805 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 29 00:09:34.338823 kernel: pcpu-alloc: [0] 0 [0] 1 Oct 29 00:09:34.338841 kernel: Detected PIPT I-cache on CPU0 Oct 29 00:09:34.338858 kernel: CPU features: detected: GIC system register CPU interface Oct 29 00:09:34.338876 kernel: CPU features: detected: Spectre-v2 Oct 29 00:09:34.338893 kernel: CPU features: detected: Spectre-v3a Oct 29 00:09:34.338911 kernel: CPU features: detected: Spectre-BHB Oct 29 00:09:34.338928 kernel: CPU features: detected: ARM erratum 1742098 Oct 29 00:09:34.338945 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 29 00:09:34.338963 kernel: alternatives: applying boot alternatives Oct 29 00:09:34.338982 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=1786621379ee1d54410a47cd8b94f912c2ba84533043e9c2ce11fc0d65ee89d8 Oct 29 00:09:34.339005 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 00:09:34.339023 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 00:09:34.339040 kernel: Fallback order for Node 0: 0 Oct 29 00:09:34.339058 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1007616 Oct 29 00:09:34.339076 kernel: Policy zone: Normal Oct 29 00:09:34.339093 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 00:09:34.339110 kernel: software IO TLB: area num 2. Oct 29 00:09:34.339128 kernel: software IO TLB: mapped [mem 0x000000006f800000-0x0000000073800000] (64MB) Oct 29 00:09:34.339145 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 29 00:09:34.339163 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 00:09:34.339185 kernel: rcu: RCU event tracing is enabled. Oct 29 00:09:34.339203 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 29 00:09:34.339221 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 00:09:34.339239 kernel: Tracing variant of Tasks RCU enabled. Oct 29 00:09:34.339256 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 00:09:34.339274 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 29 00:09:34.339292 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 29 00:09:34.339310 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 29 00:09:34.339327 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 00:09:34.339345 kernel: GICv3: 96 SPIs implemented Oct 29 00:09:34.339362 kernel: GICv3: 0 Extended SPIs implemented Oct 29 00:09:34.339383 kernel: Root IRQ handler: gic_handle_irq Oct 29 00:09:34.339401 kernel: GICv3: GICv3 features: 16 PPIs Oct 29 00:09:34.339418 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 29 00:09:34.339436 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Oct 29 00:09:34.339454 kernel: ITS [mem 0x10080000-0x1009ffff] Oct 29 00:09:34.339471 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000f0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 00:09:34.339489 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @400100000 (flat, esz 8, psz 64K, shr 1) Oct 29 00:09:34.339545 kernel: GICv3: using LPI property table @0x0000000400110000 Oct 29 00:09:34.339569 kernel: ITS: Using hypervisor restricted LPI range [128] Oct 29 00:09:34.339587 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000400120000 Oct 29 00:09:34.339605 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 29 00:09:34.339628 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Oct 29 00:09:34.339646 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Oct 29 00:09:34.339664 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Oct 29 00:09:34.339682 kernel: Console: colour dummy device 80x25 Oct 29 00:09:34.339700 kernel: printk: legacy console [tty1] enabled Oct 29 00:09:34.339718 kernel: ACPI: Core revision 20240827 Oct 29 00:09:34.339737 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Oct 29 00:09:34.339755 kernel: pid_max: default: 32768 minimum: 301 Oct 29 00:09:34.339777 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 29 00:09:34.339795 kernel: landlock: Up and running. Oct 29 00:09:34.339813 kernel: SELinux: Initializing. Oct 29 00:09:34.339831 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 00:09:34.339849 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 00:09:34.339867 kernel: rcu: Hierarchical SRCU implementation. Oct 29 00:09:34.339886 kernel: rcu: Max phase no-delay instances is 400. Oct 29 00:09:34.339908 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 29 00:09:34.339927 kernel: Remapping and enabling EFI services. Oct 29 00:09:34.339945 kernel: smp: Bringing up secondary CPUs ... Oct 29 00:09:34.339965 kernel: Detected PIPT I-cache on CPU1 Oct 29 00:09:34.339984 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Oct 29 00:09:34.340003 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000400130000 Oct 29 00:09:34.340021 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Oct 29 00:09:34.340164 kernel: smp: Brought up 1 node, 2 CPUs Oct 29 00:09:34.340186 kernel: SMP: Total of 2 processors activated. Oct 29 00:09:34.340205 kernel: CPU: All CPU(s) started at EL1 Oct 29 00:09:34.340233 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 00:09:34.340256 kernel: CPU features: detected: 32-bit EL1 Support Oct 29 00:09:34.340275 kernel: CPU features: detected: CRC32 instructions Oct 29 00:09:34.340295 kernel: alternatives: applying system-wide alternatives Oct 29 00:09:34.340317 kernel: Memory: 3822956K/4030464K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 186164K reserved, 16384K cma-reserved) Oct 29 00:09:34.340337 kernel: devtmpfs: initialized Oct 29 00:09:34.340361 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 00:09:34.340380 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 29 00:09:34.340399 kernel: 23536 pages in range for non-PLT usage Oct 29 00:09:34.340418 kernel: 515056 pages in range for PLT usage Oct 29 00:09:34.340441 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 00:09:34.340460 kernel: SMBIOS 3.0.0 present. Oct 29 00:09:34.340480 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Oct 29 00:09:34.340498 kernel: DMI: Memory slots populated: 0/0 Oct 29 00:09:34.340549 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 29 00:09:34.340574 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 00:09:34.340594 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 00:09:34.340613 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 00:09:34.340640 kernel: audit: initializing netlink subsys (disabled) Oct 29 00:09:34.340659 kernel: audit: type=2000 audit(0.245:1): state=initialized audit_enabled=0 res=1 Oct 29 00:09:34.340678 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 00:09:34.340697 kernel: cpuidle: using governor menu Oct 29 00:09:34.340716 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 00:09:34.340735 kernel: ASID allocator initialised with 65536 entries Oct 29 00:09:34.340754 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 00:09:34.340777 kernel: Serial: AMBA PL011 UART driver Oct 29 00:09:34.340796 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 00:09:34.340815 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 29 00:09:34.340834 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 00:09:34.340852 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 29 00:09:34.340872 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 00:09:34.340891 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 29 00:09:34.340914 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 00:09:34.340933 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 29 00:09:34.340952 kernel: ACPI: Added _OSI(Module Device) Oct 29 00:09:34.340971 kernel: ACPI: Added _OSI(Processor Device) Oct 29 00:09:34.340989 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 00:09:34.341008 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 00:09:34.341027 kernel: ACPI: Interpreter enabled Oct 29 00:09:34.341050 kernel: ACPI: Using GIC for interrupt routing Oct 29 00:09:34.341069 kernel: ACPI: MCFG table detected, 1 entries Oct 29 00:09:34.341087 kernel: ACPI: CPU0 has been hot-added Oct 29 00:09:34.341106 kernel: ACPI: CPU1 has been hot-added Oct 29 00:09:34.341125 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Oct 29 00:09:34.341492 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 00:09:34.341803 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 00:09:34.342060 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 00:09:34.342313 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Oct 29 00:09:34.342592 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Oct 29 00:09:34.342620 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Oct 29 00:09:34.342699 kernel: acpiphp: Slot [1] registered Oct 29 00:09:34.342719 kernel: acpiphp: Slot [2] registered Oct 29 00:09:34.342747 kernel: acpiphp: Slot [3] registered Oct 29 00:09:34.342765 kernel: acpiphp: Slot [4] registered Oct 29 00:09:34.342784 kernel: acpiphp: Slot [5] registered Oct 29 00:09:34.342803 kernel: acpiphp: Slot [6] registered Oct 29 00:09:34.342822 kernel: acpiphp: Slot [7] registered Oct 29 00:09:34.342840 kernel: acpiphp: Slot [8] registered Oct 29 00:09:34.342859 kernel: acpiphp: Slot [9] registered Oct 29 00:09:34.342882 kernel: acpiphp: Slot [10] registered Oct 29 00:09:34.342901 kernel: acpiphp: Slot [11] registered Oct 29 00:09:34.342919 kernel: acpiphp: Slot [12] registered Oct 29 00:09:34.342938 kernel: acpiphp: Slot [13] registered Oct 29 00:09:34.342957 kernel: acpiphp: Slot [14] registered Oct 29 00:09:34.342975 kernel: acpiphp: Slot [15] registered Oct 29 00:09:34.342994 kernel: acpiphp: Slot [16] registered Oct 29 00:09:34.343013 kernel: acpiphp: Slot [17] registered Oct 29 00:09:34.343035 kernel: acpiphp: Slot [18] registered Oct 29 00:09:34.343054 kernel: acpiphp: Slot [19] registered Oct 29 00:09:34.343073 kernel: acpiphp: Slot [20] registered Oct 29 00:09:34.343091 kernel: acpiphp: Slot [21] registered Oct 29 00:09:34.343110 kernel: acpiphp: Slot [22] registered Oct 29 00:09:34.343129 kernel: acpiphp: Slot [23] registered Oct 29 00:09:34.343148 kernel: acpiphp: Slot [24] registered Oct 29 00:09:34.343171 kernel: acpiphp: Slot [25] registered Oct 29 00:09:34.343189 kernel: acpiphp: Slot [26] registered Oct 29 00:09:34.343208 kernel: acpiphp: Slot [27] registered Oct 29 00:09:34.343226 kernel: acpiphp: Slot [28] registered Oct 29 00:09:34.343245 kernel: acpiphp: Slot [29] registered Oct 29 00:09:34.343264 kernel: acpiphp: Slot [30] registered Oct 29 00:09:34.343283 kernel: acpiphp: Slot [31] registered Oct 29 00:09:34.343306 kernel: PCI host bridge to bus 0000:00 Oct 29 00:09:34.343620 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Oct 29 00:09:34.343879 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 00:09:34.344109 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Oct 29 00:09:34.344340 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Oct 29 00:09:34.344684 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 conventional PCI endpoint Oct 29 00:09:34.345028 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 conventional PCI endpoint Oct 29 00:09:34.345318 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80118000-0x80118fff] Oct 29 00:09:34.345753 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 PCIe Root Complex Integrated Endpoint Oct 29 00:09:34.348250 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80114000-0x80117fff] Oct 29 00:09:34.348567 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 29 00:09:34.348922 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 PCIe Root Complex Integrated Endpoint Oct 29 00:09:34.349195 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80110000-0x80113fff] Oct 29 00:09:34.349448 kernel: pci 0000:00:05.0: BAR 2 [mem 0x80000000-0x800fffff pref] Oct 29 00:09:34.349769 kernel: pci 0000:00:05.0: BAR 4 [mem 0x80100000-0x8010ffff] Oct 29 00:09:34.350031 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 29 00:09:34.350293 kernel: pci 0000:00:05.0: BAR 2 [mem 0x80000000-0x800fffff pref]: assigned Oct 29 00:09:34.350620 kernel: pci 0000:00:05.0: BAR 4 [mem 0x80100000-0x8010ffff]: assigned Oct 29 00:09:34.350923 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80110000-0x80113fff]: assigned Oct 29 00:09:34.351202 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80114000-0x80117fff]: assigned Oct 29 00:09:34.351491 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80118000-0x80118fff]: assigned Oct 29 00:09:34.351785 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Oct 29 00:09:34.352024 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 00:09:34.352276 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Oct 29 00:09:34.352306 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 29 00:09:34.352326 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 29 00:09:34.352345 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 29 00:09:34.352365 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 29 00:09:34.352384 kernel: iommu: Default domain type: Translated Oct 29 00:09:34.352408 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 29 00:09:34.352428 kernel: efivars: Registered efivars operations Oct 29 00:09:34.352446 kernel: vgaarb: loaded Oct 29 00:09:34.352466 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 00:09:34.352485 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 00:09:34.352504 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 00:09:34.352558 kernel: pnp: PnP ACPI init Oct 29 00:09:34.352845 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Oct 29 00:09:34.352880 kernel: pnp: PnP ACPI: found 1 devices Oct 29 00:09:34.352900 kernel: NET: Registered PF_INET protocol family Oct 29 00:09:34.352920 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 00:09:34.352940 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 00:09:34.352960 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 29 00:09:34.352980 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 00:09:34.353004 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 29 00:09:34.353023 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 00:09:34.353042 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 00:09:34.353061 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 00:09:34.353080 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 29 00:09:34.353099 kernel: PCI: CLS 0 bytes, default 64 Oct 29 00:09:34.353117 kernel: kvm [1]: HYP mode not available Oct 29 00:09:34.353140 kernel: Initialise system trusted keyrings Oct 29 00:09:34.353158 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 00:09:34.353177 kernel: Key type asymmetric registered Oct 29 00:09:34.353196 kernel: Asymmetric key parser 'x509' registered Oct 29 00:09:34.353215 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 29 00:09:34.353234 kernel: io scheduler mq-deadline registered Oct 29 00:09:34.353253 kernel: io scheduler kyber registered Oct 29 00:09:34.353275 kernel: io scheduler bfq registered Oct 29 00:09:34.353594 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Oct 29 00:09:34.353625 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 00:09:34.353644 kernel: ACPI: button: Power Button [PWRB] Oct 29 00:09:34.353663 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Oct 29 00:09:34.353682 kernel: ACPI: button: Sleep Button [SLPB] Oct 29 00:09:34.353701 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 00:09:34.353727 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Oct 29 00:09:34.353985 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Oct 29 00:09:34.354011 kernel: printk: legacy console [ttyS0] disabled Oct 29 00:09:34.354031 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Oct 29 00:09:34.354050 kernel: printk: legacy console [ttyS0] enabled Oct 29 00:09:34.354069 kernel: printk: legacy bootconsole [uart0] disabled Oct 29 00:09:34.354088 kernel: thunder_xcv, ver 1.0 Oct 29 00:09:34.354111 kernel: thunder_bgx, ver 1.0 Oct 29 00:09:34.354130 kernel: nicpf, ver 1.0 Oct 29 00:09:34.354149 kernel: nicvf, ver 1.0 Oct 29 00:09:34.354411 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 00:09:34.354709 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T00:09:30 UTC (1761696570) Oct 29 00:09:34.354738 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 00:09:34.354764 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 (0,80000003) counters available Oct 29 00:09:34.354784 kernel: NET: Registered PF_INET6 protocol family Oct 29 00:09:34.354802 kernel: watchdog: NMI not fully supported Oct 29 00:09:34.354821 kernel: watchdog: Hard watchdog permanently disabled Oct 29 00:09:34.354840 kernel: Segment Routing with IPv6 Oct 29 00:09:34.354859 kernel: In-situ OAM (IOAM) with IPv6 Oct 29 00:09:34.354877 kernel: NET: Registered PF_PACKET protocol family Oct 29 00:09:34.354896 kernel: Key type dns_resolver registered Oct 29 00:09:34.354921 kernel: registered taskstats version 1 Oct 29 00:09:34.354940 kernel: Loading compiled-in X.509 certificates Oct 29 00:09:34.354959 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: bed0dde5d901830544599ae3d4004f72d9ff36a5' Oct 29 00:09:34.354978 kernel: Demotion targets for Node 0: null Oct 29 00:09:34.354996 kernel: Key type .fscrypt registered Oct 29 00:09:34.355014 kernel: Key type fscrypt-provisioning registered Oct 29 00:09:34.355033 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 00:09:34.355056 kernel: ima: Allocated hash algorithm: sha1 Oct 29 00:09:34.355074 kernel: ima: No architecture policies found Oct 29 00:09:34.355093 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 29 00:09:34.355112 kernel: clk: Disabling unused clocks Oct 29 00:09:34.355131 kernel: PM: genpd: Disabling unused power domains Oct 29 00:09:34.355150 kernel: Freeing unused kernel memory: 12992K Oct 29 00:09:34.355168 kernel: Run /init as init process Oct 29 00:09:34.355190 kernel: with arguments: Oct 29 00:09:34.355209 kernel: /init Oct 29 00:09:34.355227 kernel: with environment: Oct 29 00:09:34.355246 kernel: HOME=/ Oct 29 00:09:34.355265 kernel: TERM=linux Oct 29 00:09:34.355284 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Oct 29 00:09:34.355501 kernel: nvme nvme0: pci function 0000:00:04.0 Oct 29 00:09:34.355749 kernel: nvme nvme0: 2/0/0 default/read/poll queues Oct 29 00:09:34.355776 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 29 00:09:34.355796 kernel: GPT:25804799 != 33554431 Oct 29 00:09:34.355814 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 29 00:09:34.355833 kernel: GPT:25804799 != 33554431 Oct 29 00:09:34.355850 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 29 00:09:34.355874 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 29 00:09:34.355894 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.355913 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.355931 kernel: SCSI subsystem initialized Oct 29 00:09:34.355950 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.355969 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 29 00:09:34.355988 kernel: device-mapper: uevent: version 1.0.3 Oct 29 00:09:34.356010 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 29 00:09:34.356030 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 29 00:09:34.356049 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356067 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356085 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356104 kernel: raid6: neonx8 gen() 6382 MB/s Oct 29 00:09:34.356122 kernel: raid6: neonx4 gen() 6342 MB/s Oct 29 00:09:34.356141 kernel: raid6: neonx2 gen() 5384 MB/s Oct 29 00:09:34.356163 kernel: raid6: neonx1 gen() 3887 MB/s Oct 29 00:09:34.356182 kernel: raid6: int64x8 gen() 3597 MB/s Oct 29 00:09:34.356201 kernel: raid6: int64x4 gen() 3631 MB/s Oct 29 00:09:34.356220 kernel: raid6: int64x2 gen() 3520 MB/s Oct 29 00:09:34.356238 kernel: raid6: int64x1 gen() 2718 MB/s Oct 29 00:09:34.356257 kernel: raid6: using algorithm neonx8 gen() 6382 MB/s Oct 29 00:09:34.356275 kernel: raid6: .... xor() 4699 MB/s, rmw enabled Oct 29 00:09:34.356298 kernel: raid6: using neon recovery algorithm Oct 29 00:09:34.356317 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356335 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356354 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356372 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356390 kernel: xor: measuring software checksum speed Oct 29 00:09:34.356409 kernel: 8regs : 12954 MB/sec Oct 29 00:09:34.356428 kernel: 32regs : 13049 MB/sec Oct 29 00:09:34.356451 kernel: arm64_neon : 8652 MB/sec Oct 29 00:09:34.356470 kernel: xor: using function: 32regs (13049 MB/sec) Oct 29 00:09:34.356489 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356530 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 29 00:09:34.356556 kernel: BTRFS: device fsid f785b428-ed9b-4a9f-9ef7-34d3a0d5ba0e devid 1 transid 39 /dev/mapper/usr (254:0) scanned by mount (220) Oct 29 00:09:34.356576 kernel: BTRFS info (device dm-0): first mount of filesystem f785b428-ed9b-4a9f-9ef7-34d3a0d5ba0e Oct 29 00:09:34.356596 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:09:34.356615 kernel: BTRFS info (device dm-0): enabling ssd optimizations Oct 29 00:09:34.356641 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 29 00:09:34.356660 kernel: BTRFS info (device dm-0): enabling free space tree Oct 29 00:09:34.356680 kernel: Invalid ELF header magic: != \u007fELF Oct 29 00:09:34.356718 kernel: loop: module loaded Oct 29 00:09:34.356742 kernel: loop0: detected capacity change from 0 to 91464 Oct 29 00:09:34.356762 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 29 00:09:34.356785 systemd[1]: Successfully made /usr/ read-only. Oct 29 00:09:34.356816 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) Oct 29 00:09:34.356839 systemd[1]: Detected virtualization amazon. Oct 29 00:09:34.356860 systemd[1]: Detected architecture arm64. Oct 29 00:09:34.356880 systemd[1]: Running in initrd. Oct 29 00:09:34.356901 systemd[1]: No hostname configured, using default hostname. Oct 29 00:09:34.356927 systemd[1]: Hostname set to . Oct 29 00:09:34.356948 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 29 00:09:34.356969 systemd[1]: Queued start job for default target initrd.target. Oct 29 00:09:34.356991 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 00:09:34.357013 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:09:34.357035 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:09:34.357077 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 29 00:09:34.357107 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 00:09:34.357131 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 29 00:09:34.357154 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 29 00:09:34.357175 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:09:34.357197 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:09:34.357218 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 29 00:09:34.357243 systemd[1]: Reached target paths.target - Path Units. Oct 29 00:09:34.357265 systemd[1]: Reached target slices.target - Slice Units. Oct 29 00:09:34.357286 systemd[1]: Reached target swap.target - Swaps. Oct 29 00:09:34.357308 systemd[1]: Reached target timers.target - Timer Units. Oct 29 00:09:34.357329 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 00:09:34.357351 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 00:09:34.357378 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 29 00:09:34.357400 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 29 00:09:34.357442 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:09:34.357468 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 00:09:34.357490 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:09:34.357540 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 00:09:34.357565 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 29 00:09:34.357592 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 29 00:09:34.357614 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 00:09:34.357635 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 29 00:09:34.357657 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). Oct 29 00:09:34.357679 systemd[1]: Starting systemd-fsck-usr.service... Oct 29 00:09:34.357705 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 00:09:34.357727 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 00:09:34.357748 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:09:34.357771 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 29 00:09:34.357796 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:09:34.357818 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 29 00:09:34.357840 systemd[1]: Finished systemd-fsck-usr.service. Oct 29 00:09:34.357917 systemd-journald[358]: Collecting audit messages is disabled. Oct 29 00:09:34.357969 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 00:09:34.357992 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 29 00:09:34.358040 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 00:09:34.358064 systemd-journald[358]: Journal started Oct 29 00:09:34.358108 systemd-journald[358]: Runtime Journal (/run/log/journal/ec2adea5e1bb403e39ba7548a36c6c22) is 8M, max 75.3M, 67.3M free. Oct 29 00:09:34.362664 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 00:09:34.368251 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 00:09:34.379396 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:09:34.390560 kernel: Bridge firewalling registered Oct 29 00:09:34.390567 systemd-modules-load[360]: Inserted module 'br_netfilter' Oct 29 00:09:34.397393 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 00:09:34.405546 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 00:09:34.421773 systemd-tmpfiles[375]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 29 00:09:34.428380 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:09:34.436496 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 29 00:09:34.447578 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:09:34.466944 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:09:34.476804 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 00:09:34.514881 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 00:09:34.520418 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 29 00:09:34.569556 dracut-cmdline[400]: 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=1786621379ee1d54410a47cd8b94f912c2ba84533043e9c2ce11fc0d65ee89d8 Oct 29 00:09:34.703056 systemd-resolved[388]: Positive Trust Anchors: Oct 29 00:09:34.703090 systemd-resolved[388]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:09:34.703099 systemd-resolved[388]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 00:09:34.703159 systemd-resolved[388]: 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 Oct 29 00:09:34.906545 kernel: Loading iSCSI transport class v2.0-870. Oct 29 00:09:34.951577 kernel: iscsi: registered transport (tcp) Oct 29 00:09:34.974916 kernel: iscsi: registered transport (qla4xxx) Oct 29 00:09:34.975008 kernel: QLogic iSCSI HBA Driver Oct 29 00:09:34.989802 kernel: random: crng init done Oct 29 00:09:34.990195 systemd-resolved[388]: Defaulting to hostname 'linux'. Oct 29 00:09:34.996435 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 00:09:34.999117 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:09:35.031043 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 00:09:35.077824 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:09:35.096082 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 00:09:35.175872 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 29 00:09:35.182703 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 29 00:09:35.190430 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 29 00:09:35.257211 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 29 00:09:35.269960 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:09:35.333186 systemd-udevd[645]: Using default interface naming scheme 'v257'. Oct 29 00:09:35.355305 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:09:35.369735 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 29 00:09:35.412642 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 00:09:35.427856 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 00:09:35.439937 dracut-pre-trigger[717]: rd.md=0: removing MD RAID activation Oct 29 00:09:35.502063 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 00:09:35.510806 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 00:09:35.537737 systemd-networkd[749]: lo: Link UP Oct 29 00:09:35.537756 systemd-networkd[749]: lo: Gained carrier Oct 29 00:09:35.539091 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 00:09:35.542111 systemd[1]: Reached target network.target - Network. Oct 29 00:09:35.674844 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:09:35.686302 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 29 00:09:35.901243 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:09:35.903792 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:09:35.906737 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:09:35.915660 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:09:35.965269 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 29 00:09:35.965352 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Oct 29 00:09:35.970970 kernel: ena 0000:00:05.0: ENA device version: 0.10 Oct 29 00:09:35.971382 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Oct 29 00:09:35.971718 kernel: nvme nvme0: using unchecked data buffer Oct 29 00:09:35.985636 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:92:d0:bc:f5:99 Oct 29 00:09:35.989281 (udev-worker)[798]: Network interface NamePolicy= disabled on kernel command line. Oct 29 00:09:36.002938 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:09:36.012028 systemd-networkd[749]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:09:36.012056 systemd-networkd[749]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:09:36.023099 systemd-networkd[749]: eth0: Link UP Oct 29 00:09:36.023945 systemd-networkd[749]: eth0: Gained carrier Oct 29 00:09:36.023972 systemd-networkd[749]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:09:36.041637 systemd-networkd[749]: eth0: DHCPv4 address 172.31.28.41/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 29 00:09:36.120607 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Oct 29 00:09:36.148758 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 29 00:09:36.181972 disk-uuid[871]: Primary Header is updated. Oct 29 00:09:36.181972 disk-uuid[871]: Secondary Entries is updated. Oct 29 00:09:36.181972 disk-uuid[871]: Secondary Header is updated. Oct 29 00:09:36.243314 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Oct 29 00:09:36.293055 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 29 00:09:36.353904 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Oct 29 00:09:36.691715 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 29 00:09:36.698967 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 00:09:36.702028 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:09:36.705093 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 00:09:36.708778 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 29 00:09:36.765670 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 29 00:09:37.289593 disk-uuid[879]: Warning: The kernel is still using the old partition table. Oct 29 00:09:37.289593 disk-uuid[879]: The new table will be used at the next reboot or after you Oct 29 00:09:37.289593 disk-uuid[879]: run partprobe(8) or kpartx(8) Oct 29 00:09:37.289593 disk-uuid[879]: The operation has completed successfully. Oct 29 00:09:37.303983 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 00:09:37.304267 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 29 00:09:37.315465 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 29 00:09:37.389566 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1099) Oct 29 00:09:37.394335 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 255558c4-48b0-4843-b41f-5365c6f0c8ef Oct 29 00:09:37.394404 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:09:37.402676 systemd-networkd[749]: eth0: Gained IPv6LL Oct 29 00:09:37.429918 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 29 00:09:37.429999 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Oct 29 00:09:37.440573 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 255558c4-48b0-4843-b41f-5365c6f0c8ef Oct 29 00:09:37.442124 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 29 00:09:37.448739 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 29 00:09:38.775700 ignition[1118]: Ignition 2.22.0 Oct 29 00:09:38.776284 ignition[1118]: Stage: fetch-offline Oct 29 00:09:38.777201 ignition[1118]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:38.777228 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:38.777711 ignition[1118]: Ignition finished successfully Oct 29 00:09:38.788165 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 00:09:38.793029 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 29 00:09:38.853624 ignition[1126]: Ignition 2.22.0 Oct 29 00:09:38.854171 ignition[1126]: Stage: fetch Oct 29 00:09:38.855118 ignition[1126]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:38.855141 ignition[1126]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:38.855286 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:38.877615 ignition[1126]: PUT result: OK Oct 29 00:09:38.881821 ignition[1126]: parsed url from cmdline: "" Oct 29 00:09:38.881837 ignition[1126]: no config URL provided Oct 29 00:09:38.881851 ignition[1126]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 00:09:38.881883 ignition[1126]: no config at "/usr/lib/ignition/user.ign" Oct 29 00:09:38.881930 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:38.883898 ignition[1126]: PUT result: OK Oct 29 00:09:38.883987 ignition[1126]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Oct 29 00:09:38.886659 ignition[1126]: GET result: OK Oct 29 00:09:38.886760 ignition[1126]: parsing config with SHA512: ec06c3458994b6a89e9fddd559287d96e8874ae3ddfdb552d76125c8f9a437009ba550bda1bfaba72055f3ba0f72637052fb3d85b20791ef46a7c51b618d0234 Oct 29 00:09:38.905676 unknown[1126]: fetched base config from "system" Oct 29 00:09:38.906342 ignition[1126]: fetch: fetch complete Oct 29 00:09:38.905701 unknown[1126]: fetched base config from "system" Oct 29 00:09:38.906356 ignition[1126]: fetch: fetch passed Oct 29 00:09:38.905726 unknown[1126]: fetched user config from "aws" Oct 29 00:09:38.906455 ignition[1126]: Ignition finished successfully Oct 29 00:09:38.913027 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 29 00:09:38.920048 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 29 00:09:38.986133 ignition[1133]: Ignition 2.22.0 Oct 29 00:09:38.986170 ignition[1133]: Stage: kargs Oct 29 00:09:38.986775 ignition[1133]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:38.986798 ignition[1133]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:38.986949 ignition[1133]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:38.989645 ignition[1133]: PUT result: OK Oct 29 00:09:39.001930 ignition[1133]: kargs: kargs passed Oct 29 00:09:39.004018 ignition[1133]: Ignition finished successfully Oct 29 00:09:39.008700 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 29 00:09:39.015393 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 29 00:09:39.063377 ignition[1139]: Ignition 2.22.0 Oct 29 00:09:39.063906 ignition[1139]: Stage: disks Oct 29 00:09:39.064449 ignition[1139]: no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:39.064469 ignition[1139]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:39.064653 ignition[1139]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:39.073735 ignition[1139]: PUT result: OK Oct 29 00:09:39.081642 ignition[1139]: disks: disks passed Oct 29 00:09:39.081734 ignition[1139]: Ignition finished successfully Oct 29 00:09:39.087157 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 29 00:09:39.091227 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 29 00:09:39.091307 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 29 00:09:39.091371 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 00:09:39.091433 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 00:09:39.091599 systemd[1]: Reached target basic.target - Basic System. Oct 29 00:09:39.107697 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 29 00:09:39.239805 systemd-fsck[1147]: ROOT: clean, 15/1631200 files, 112378/1617920 blocks Oct 29 00:09:39.243835 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 29 00:09:39.251729 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 29 00:09:39.521578 kernel: EXT4-fs (nvme0n1p9): mounted filesystem dc197882-f620-4883-ae03-83da9873c421 r/w with ordered data mode. Quota mode: none. Oct 29 00:09:39.522103 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 29 00:09:39.526426 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 29 00:09:39.577661 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 00:09:39.583007 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 29 00:09:39.590055 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 29 00:09:39.591294 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 29 00:09:39.591356 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 00:09:39.624548 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1166) Oct 29 00:09:39.631344 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 255558c4-48b0-4843-b41f-5365c6f0c8ef Oct 29 00:09:39.631425 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:09:39.625288 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 29 00:09:39.634437 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 29 00:09:39.652341 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 29 00:09:39.652413 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Oct 29 00:09:39.655129 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 00:09:41.079554 initrd-setup-root[1191]: cut: /sysroot/etc/passwd: No such file or directory Oct 29 00:09:41.186081 initrd-setup-root[1198]: cut: /sysroot/etc/group: No such file or directory Oct 29 00:09:41.196690 initrd-setup-root[1205]: cut: /sysroot/etc/shadow: No such file or directory Oct 29 00:09:41.206071 initrd-setup-root[1212]: cut: /sysroot/etc/gshadow: No such file or directory Oct 29 00:09:41.856638 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 29 00:09:41.863999 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 29 00:09:41.881795 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 29 00:09:41.898930 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 29 00:09:41.902024 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 255558c4-48b0-4843-b41f-5365c6f0c8ef Oct 29 00:09:41.957470 ignition[1279]: INFO : Ignition 2.22.0 Oct 29 00:09:41.957470 ignition[1279]: INFO : Stage: mount Oct 29 00:09:41.957470 ignition[1279]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:41.957470 ignition[1279]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:41.957470 ignition[1279]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:41.971634 ignition[1279]: INFO : PUT result: OK Oct 29 00:09:41.958287 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 29 00:09:41.978308 ignition[1279]: INFO : mount: mount passed Oct 29 00:09:41.978308 ignition[1279]: INFO : Ignition finished successfully Oct 29 00:09:41.983416 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 29 00:09:41.989470 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 29 00:09:42.023867 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 00:09:42.065582 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 (259:5) scanned by mount (1291) Oct 29 00:09:42.070040 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 255558c4-48b0-4843-b41f-5365c6f0c8ef Oct 29 00:09:42.070118 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 29 00:09:42.077206 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 29 00:09:42.077283 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Oct 29 00:09:42.080808 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 00:09:42.137607 ignition[1308]: INFO : Ignition 2.22.0 Oct 29 00:09:42.137607 ignition[1308]: INFO : Stage: files Oct 29 00:09:42.141879 ignition[1308]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:42.144267 ignition[1308]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:42.147145 ignition[1308]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:42.151274 ignition[1308]: INFO : PUT result: OK Oct 29 00:09:42.155476 ignition[1308]: DEBUG : files: compiled without relabeling support, skipping Oct 29 00:09:42.158093 ignition[1308]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 29 00:09:42.158093 ignition[1308]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 29 00:09:42.197600 ignition[1308]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 29 00:09:42.200774 ignition[1308]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 29 00:09:42.204371 unknown[1308]: wrote ssh authorized keys file for user: core Oct 29 00:09:42.206941 ignition[1308]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 00:09:42.213955 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 29 00:09:42.217991 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 29 00:09:42.228392 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:09:42.232676 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 00:09:42.237677 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 29 00:09:42.237677 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 29 00:09:42.249312 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 29 00:09:42.249312 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 29 00:09:42.772172 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 29 00:09:43.191427 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 29 00:09:43.196154 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:09:43.196154 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 29 00:09:43.196154 ignition[1308]: INFO : files: files passed Oct 29 00:09:43.196154 ignition[1308]: INFO : Ignition finished successfully Oct 29 00:09:43.210914 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 29 00:09:43.215213 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 29 00:09:43.223684 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 29 00:09:43.248220 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 00:09:43.253014 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 29 00:09:43.270858 initrd-setup-root-after-ignition[1338]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 00:09:43.274617 initrd-setup-root-after-ignition[1338]: grep: Oct 29 00:09:43.276444 initrd-setup-root-after-ignition[1341]: grep: Oct 29 00:09:43.278560 initrd-setup-root-after-ignition[1338]: /sysroot/usr/share/flatcar/enabled-sysext.conf Oct 29 00:09:43.281342 initrd-setup-root-after-ignition[1341]: /sysroot/etc/flatcar/enabled-sysext.conf Oct 29 00:09:43.283974 initrd-setup-root-after-ignition[1338]: : No such file or directory Oct 29 00:09:43.286320 initrd-setup-root-after-ignition[1341]: : No such file or directory Oct 29 00:09:43.290674 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 00:09:43.296801 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 29 00:09:43.303768 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 29 00:09:43.384652 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 00:09:43.386140 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 29 00:09:43.392999 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 29 00:09:43.395407 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 29 00:09:43.399262 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 29 00:09:43.400993 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 29 00:09:43.449710 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 00:09:43.457481 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 29 00:09:43.498815 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 00:09:43.500330 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:09:43.508701 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:09:43.513168 systemd[1]: Stopped target timers.target - Timer Units. Oct 29 00:09:43.517317 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 00:09:43.517635 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 00:09:43.527671 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 29 00:09:43.533719 systemd[1]: Stopped target basic.target - Basic System. Oct 29 00:09:43.537376 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 29 00:09:43.544472 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 00:09:43.548328 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 29 00:09:43.553330 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 29 00:09:43.557811 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 29 00:09:43.561404 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 00:09:43.566118 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 29 00:09:43.573498 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 29 00:09:43.578440 systemd[1]: Stopped target swap.target - Swaps. Oct 29 00:09:43.585392 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 00:09:43.585862 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 29 00:09:43.597963 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:09:43.600972 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:09:43.606205 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 29 00:09:43.608440 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:09:43.608968 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 00:09:43.609359 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 29 00:09:43.619637 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 29 00:09:43.619967 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 00:09:43.625503 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 00:09:43.625828 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 29 00:09:43.639077 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 29 00:09:43.644623 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 00:09:43.644945 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:09:43.652944 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 29 00:09:43.658709 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 00:09:43.662760 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:09:43.671798 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 00:09:43.672850 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:09:43.684620 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 00:09:43.686053 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 00:09:43.707981 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 00:09:43.708253 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 29 00:09:43.744618 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 29 00:09:43.750101 ignition[1363]: INFO : Ignition 2.22.0 Oct 29 00:09:43.752645 ignition[1363]: INFO : Stage: umount Oct 29 00:09:43.752645 ignition[1363]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 00:09:43.752645 ignition[1363]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 29 00:09:43.760487 ignition[1363]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 29 00:09:43.763561 ignition[1363]: INFO : PUT result: OK Oct 29 00:09:43.770343 ignition[1363]: INFO : umount: umount passed Oct 29 00:09:43.772421 ignition[1363]: INFO : Ignition finished successfully Oct 29 00:09:43.777814 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 29 00:09:43.778407 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 29 00:09:43.791988 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 00:09:43.792133 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 29 00:09:43.807154 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 29 00:09:43.807292 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 29 00:09:43.812251 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 29 00:09:43.812408 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 29 00:09:43.812602 systemd[1]: Stopped target network.target - Network. Oct 29 00:09:43.813274 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 29 00:09:43.813379 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 00:09:43.816283 systemd[1]: Stopped target paths.target - Path Units. Oct 29 00:09:43.833673 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 00:09:43.836664 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:09:43.844691 systemd[1]: Stopped target slices.target - Slice Units. Oct 29 00:09:43.856425 systemd[1]: Stopped target sockets.target - Socket Units. Oct 29 00:09:43.861463 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 00:09:43.862656 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 00:09:43.867473 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 00:09:43.867582 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 00:09:43.870550 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 00:09:43.870708 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 29 00:09:43.878446 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 29 00:09:43.878646 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 29 00:09:43.888337 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 29 00:09:43.891048 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 29 00:09:43.902794 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 00:09:43.903021 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 29 00:09:43.906222 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 00:09:43.906331 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 29 00:09:43.921719 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 00:09:43.924377 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 29 00:09:43.934227 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 00:09:43.934489 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 29 00:09:43.942880 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 29 00:09:43.943858 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 00:09:43.943955 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:09:43.958213 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 29 00:09:43.967443 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 29 00:09:43.967823 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 00:09:43.979607 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 00:09:43.980345 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:09:43.985040 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 29 00:09:43.985167 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 29 00:09:43.988871 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:09:44.020582 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 00:09:44.024693 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:09:44.031450 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 00:09:44.033111 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 29 00:09:44.039267 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 00:09:44.039738 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:09:44.046494 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 00:09:44.046670 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 29 00:09:44.054531 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 00:09:44.055197 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 29 00:09:44.060023 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 00:09:44.060183 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 00:09:44.071069 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 29 00:09:44.076500 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 29 00:09:44.076672 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:09:44.096025 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 00:09:44.096787 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:09:44.106053 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 00:09:44.106177 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:09:44.135236 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 00:09:44.138347 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 29 00:09:44.148092 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 00:09:44.150434 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 29 00:09:44.155052 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 29 00:09:44.162548 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 29 00:09:44.231778 systemd[1]: Switching root. Oct 29 00:09:44.295603 systemd-journald[358]: Journal stopped Oct 29 00:09:49.028573 systemd-journald[358]: Received SIGTERM from PID 1 (systemd). Oct 29 00:09:49.028698 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 00:09:49.028737 kernel: SELinux: policy capability open_perms=1 Oct 29 00:09:49.028772 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 00:09:49.028804 kernel: SELinux: policy capability always_check_network=0 Oct 29 00:09:49.028840 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 00:09:49.028871 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 00:09:49.028902 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 00:09:49.028933 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 29 00:09:49.028965 kernel: SELinux: policy capability userspace_initial_context=0 Oct 29 00:09:49.028994 kernel: audit: type=1403 audit(1761696585.311:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 00:09:49.029035 systemd[1]: Successfully loaded SELinux policy in 123.619ms. Oct 29 00:09:49.029092 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 17.231ms. Oct 29 00:09:49.029126 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) Oct 29 00:09:49.029160 systemd[1]: Detected virtualization amazon. Oct 29 00:09:49.029201 systemd[1]: Detected architecture arm64. Oct 29 00:09:49.029229 systemd[1]: Detected first boot. Oct 29 00:09:49.029260 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 29 00:09:49.029294 zram_generator::config[1407]: No configuration found. Oct 29 00:09:49.029331 kernel: NET: Registered PF_VSOCK protocol family Oct 29 00:09:49.029364 systemd[1]: Populated /etc with preset unit settings. Oct 29 00:09:49.029396 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 00:09:49.029428 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 29 00:09:49.029465 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 00:09:49.029503 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 29 00:09:49.029646 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 29 00:09:49.029678 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 29 00:09:49.029713 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 29 00:09:49.029744 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 29 00:09:49.029778 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 29 00:09:49.029813 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 29 00:09:49.029843 systemd[1]: Created slice user.slice - User and Session Slice. Oct 29 00:09:49.029874 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 00:09:49.029905 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 00:09:49.029939 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 29 00:09:49.029972 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 29 00:09:49.030002 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 29 00:09:49.030039 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 00:09:49.030072 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 29 00:09:49.030106 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 00:09:49.030137 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 00:09:49.030168 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 29 00:09:49.030200 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 29 00:09:49.030233 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 29 00:09:49.030264 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 29 00:09:49.030293 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 00:09:49.030325 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 00:09:49.030358 systemd[1]: Reached target slices.target - Slice Units. Oct 29 00:09:49.030390 systemd[1]: Reached target swap.target - Swaps. Oct 29 00:09:49.030421 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 29 00:09:49.030457 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 29 00:09:49.030496 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 29 00:09:49.030579 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 00:09:49.030616 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 00:09:49.030648 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 00:09:49.030681 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 29 00:09:49.030716 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 29 00:09:49.030748 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 29 00:09:49.030786 systemd[1]: Mounting media.mount - External Media Directory... Oct 29 00:09:49.030825 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 29 00:09:49.030855 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 29 00:09:49.030886 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 29 00:09:49.030920 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). Oct 29 00:09:49.030953 systemd[1]: Reached target machines.target - Containers. Oct 29 00:09:49.030987 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 29 00:09:49.031019 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 00:09:49.031049 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 00:09:49.031082 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 29 00:09:49.031113 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 00:09:49.031145 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 00:09:49.031174 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 00:09:49.031208 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 29 00:09:49.031241 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 00:09:49.031273 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 29 00:09:49.031304 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 00:09:49.031335 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 29 00:09:49.031364 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 00:09:49.031393 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 00:09:49.031427 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). Oct 29 00:09:49.031456 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 00:09:49.031486 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 00:09:49.031543 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 00:09:49.031582 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 29 00:09:49.031613 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 29 00:09:49.031643 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 00:09:49.031678 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 29 00:09:49.031718 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 29 00:09:49.031750 systemd[1]: Mounted media.mount - External Media Directory. Oct 29 00:09:49.031779 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 29 00:09:49.031820 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 29 00:09:49.031850 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 29 00:09:49.031879 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 00:09:49.031908 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:09:49.031939 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 00:09:49.031969 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:09:49.032000 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 00:09:49.032033 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 00:09:49.032064 kernel: fuse: init (API version 7.41) Oct 29 00:09:49.032093 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 29 00:09:49.032124 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 00:09:49.032155 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 29 00:09:49.032184 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:09:49.032214 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 00:09:49.032248 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 00:09:49.032283 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 29 00:09:49.032313 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 29 00:09:49.032343 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 29 00:09:49.032376 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 29 00:09:49.032407 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 29 00:09:49.032482 systemd-journald[1486]: Collecting audit messages is disabled. Oct 29 00:09:49.032591 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 00:09:49.032636 systemd-journald[1486]: Journal started Oct 29 00:09:49.032691 systemd-journald[1486]: Runtime Journal (/run/log/journal/ec2adea5e1bb403e39ba7548a36c6c22) is 8M, max 75.3M, 67.3M free. Oct 29 00:09:49.043580 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 29 00:09:48.323014 systemd[1]: Queued start job for default target multi-user.target. Oct 29 00:09:48.337893 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Oct 29 00:09:48.338913 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 00:09:49.055637 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 00:09:49.071055 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 29 00:09:49.071162 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:09:49.087172 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 29 00:09:49.092570 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 00:09:49.102845 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 00:09:49.116626 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 29 00:09:49.124536 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 00:09:49.128945 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 00:09:49.136684 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 29 00:09:49.142347 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 29 00:09:49.145325 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 29 00:09:49.151887 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 29 00:09:49.205886 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 29 00:09:49.209780 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 00:09:49.232059 kernel: ACPI: bus type drm_connector registered Oct 29 00:09:49.232165 kernel: loop1: detected capacity change from 0 to 61264 Oct 29 00:09:49.219756 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 29 00:09:49.226452 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 29 00:09:49.229924 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:09:49.232134 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 00:09:49.251659 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 29 00:09:49.266969 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 29 00:09:49.299280 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 29 00:09:49.339223 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 00:09:49.345255 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 00:09:49.364859 systemd-journald[1486]: Time spent on flushing to /var/log/journal/ec2adea5e1bb403e39ba7548a36c6c22 is 34.849ms for 919 entries. Oct 29 00:09:49.364859 systemd-journald[1486]: System Journal (/var/log/journal/ec2adea5e1bb403e39ba7548a36c6c22) is 8M, max 588.1M, 580.1M free. Oct 29 00:09:49.422284 systemd-journald[1486]: Received client request to flush runtime journal. Oct 29 00:09:49.368781 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 00:09:49.425016 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 29 00:09:49.450803 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 29 00:09:49.468822 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 00:09:49.473911 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 00:09:49.532809 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 29 00:09:49.623808 kernel: loop2: detected capacity change from 0 to 211168 Oct 29 00:09:49.624764 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Oct 29 00:09:49.625331 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Oct 29 00:09:49.632019 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 29 00:09:49.646836 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 00:09:49.806154 systemd-resolved[1556]: Positive Trust Anchors: Oct 29 00:09:49.806198 systemd-resolved[1556]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 00:09:49.806209 systemd-resolved[1556]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 00:09:49.806274 systemd-resolved[1556]: 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 Oct 29 00:09:49.854244 systemd-resolved[1556]: Defaulting to hostname 'linux'. Oct 29 00:09:49.858703 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 00:09:49.861662 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 00:09:49.931624 kernel: loop3: detected capacity change from 0 to 119344 Oct 29 00:09:50.249674 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 29 00:09:50.255835 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 00:09:50.315726 systemd-udevd[1570]: Using default interface naming scheme 'v257'. Oct 29 00:09:50.329592 kernel: loop4: detected capacity change from 0 to 100624 Oct 29 00:09:50.400156 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 00:09:50.412019 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 00:09:50.504179 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 29 00:09:50.561216 (udev-worker)[1580]: Network interface NamePolicy= disabled on kernel command line. Oct 29 00:09:50.608723 systemd-networkd[1579]: lo: Link UP Oct 29 00:09:50.608738 systemd-networkd[1579]: lo: Gained carrier Oct 29 00:09:50.612831 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 00:09:50.615475 systemd[1]: Reached target network.target - Network. Oct 29 00:09:50.620765 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 29 00:09:50.630937 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 29 00:09:50.699471 systemd-networkd[1579]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:09:50.699486 systemd-networkd[1579]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 00:09:50.706472 systemd-networkd[1579]: eth0: Link UP Oct 29 00:09:50.708279 kernel: loop5: detected capacity change from 0 to 61264 Oct 29 00:09:50.710113 systemd-networkd[1579]: eth0: Gained carrier Oct 29 00:09:50.710159 systemd-networkd[1579]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 00:09:50.716683 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 29 00:09:50.726908 systemd-networkd[1579]: eth0: DHCPv4 address 172.31.28.41/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 29 00:09:50.738946 kernel: loop6: detected capacity change from 0 to 211168 Oct 29 00:09:50.769556 kernel: loop7: detected capacity change from 0 to 119344 Oct 29 00:09:50.795554 kernel: loop1: detected capacity change from 0 to 100624 Oct 29 00:09:50.827776 (sd-merge)[1606]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw', 'oem-ami.raw'. Oct 29 00:09:50.849929 (sd-merge)[1606]: Merged extensions into '/usr'. Oct 29 00:09:50.869748 systemd[1]: Reload requested from client PID 1515 ('systemd-sysext') (unit systemd-sysext.service)... Oct 29 00:09:50.869778 systemd[1]: Reloading... Oct 29 00:09:51.098570 zram_generator::config[1654]: No configuration found. Oct 29 00:09:51.643271 systemd[1]: Reloading finished in 771 ms. Oct 29 00:09:51.682279 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 29 00:09:51.751413 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 29 00:09:51.767084 systemd[1]: Starting ensure-sysext.service... Oct 29 00:09:51.772860 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 29 00:09:51.779056 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 00:09:51.786241 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 00:09:51.836574 systemd[1]: Reload requested from client PID 1788 ('systemctl') (unit ensure-sysext.service)... Oct 29 00:09:51.836860 systemd[1]: Reloading... Oct 29 00:09:51.846262 systemd-tmpfiles[1790]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 29 00:09:51.846353 systemd-tmpfiles[1790]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 29 00:09:51.847333 systemd-tmpfiles[1790]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 29 00:09:51.850143 systemd-tmpfiles[1790]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 29 00:09:51.853426 systemd-tmpfiles[1790]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 00:09:51.855269 systemd-tmpfiles[1790]: ACLs are not supported, ignoring. Oct 29 00:09:51.855447 systemd-tmpfiles[1790]: ACLs are not supported, ignoring. Oct 29 00:09:51.875266 systemd-tmpfiles[1790]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 00:09:51.875301 systemd-tmpfiles[1790]: Skipping /boot Oct 29 00:09:51.913890 systemd-tmpfiles[1790]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 00:09:51.913921 systemd-tmpfiles[1790]: Skipping /boot Oct 29 00:09:52.045601 zram_generator::config[1837]: No configuration found. Oct 29 00:09:52.186767 systemd-networkd[1579]: eth0: Gained IPv6LL Oct 29 00:09:52.514447 systemd[1]: Reloading finished in 676 ms. Oct 29 00:09:52.532746 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 29 00:09:52.562171 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 29 00:09:52.566213 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 00:09:52.573673 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 00:09:52.595471 systemd[1]: Reached target network-online.target - Network is Online. Oct 29 00:09:52.601175 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 29 00:09:52.606160 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 29 00:09:52.616125 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 29 00:09:52.630159 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 29 00:09:52.639242 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 29 00:09:52.649867 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 00:09:52.657089 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 00:09:52.670765 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 00:09:52.678219 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 00:09:52.680967 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 00:09:52.681236 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). Oct 29 00:09:52.702915 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 29 00:09:52.715330 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:09:52.721612 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 00:09:52.725925 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:09:52.729686 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 00:09:52.736001 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:09:52.737340 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 00:09:52.761212 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 00:09:52.764230 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 00:09:52.771541 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 00:09:52.780954 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 00:09:52.788617 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 00:09:52.791315 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 00:09:52.791697 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). Oct 29 00:09:52.792105 systemd[1]: Reached target time-set.target - System Time Set. Oct 29 00:09:52.800690 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 29 00:09:52.822188 systemd[1]: Finished ensure-sysext.service. Oct 29 00:09:52.829982 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 00:09:52.831798 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 00:09:52.836922 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 00:09:52.837424 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 00:09:52.847474 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 00:09:52.849419 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 00:09:52.850723 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 00:09:52.854478 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 00:09:52.854968 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 00:09:52.860812 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 00:09:52.903932 augenrules[1927]: No rules Oct 29 00:09:52.906464 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 00:09:52.907124 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 29 00:09:52.979675 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 29 00:09:52.987931 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). Oct 29 00:09:55.102559 ldconfig[1888]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 29 00:09:55.112691 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 29 00:09:55.118370 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 29 00:09:55.148242 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 29 00:09:55.151486 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 00:09:55.154377 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 29 00:09:55.157825 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 00:09:55.161260 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 29 00:09:55.164324 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 29 00:09:55.167158 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 29 00:09:55.170232 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 29 00:09:55.170585 systemd[1]: Reached target paths.target - Path Units. Oct 29 00:09:55.172683 systemd[1]: Reached target timers.target - Timer Units. Oct 29 00:09:55.176341 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 29 00:09:55.182143 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 29 00:09:55.189111 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 29 00:09:55.192652 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 29 00:09:55.196123 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 29 00:09:55.210078 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 29 00:09:55.214092 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 29 00:09:55.218480 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 29 00:09:55.221490 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 00:09:55.223918 systemd[1]: Reached target basic.target - Basic System. Oct 29 00:09:55.226216 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 29 00:09:55.226267 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 29 00:09:55.228469 systemd[1]: Starting containerd.service - containerd container runtime... Oct 29 00:09:55.235832 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 29 00:09:55.241825 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 29 00:09:55.250543 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 29 00:09:55.256955 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 29 00:09:55.265407 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 29 00:09:55.267730 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). Oct 29 00:09:55.275135 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 00:09:55.285914 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 29 00:09:55.295067 systemd[1]: Started ntpd.service - Network Time Service. Oct 29 00:09:55.303005 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 29 00:09:55.309445 systemd[1]: Starting setup-oem.service - Setup OEM... Oct 29 00:09:55.322928 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 29 00:09:55.331182 jq[1943]: false Oct 29 00:09:55.335797 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 29 00:09:55.353191 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 29 00:09:55.355566 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 29 00:09:55.356462 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 00:09:55.361909 systemd[1]: Starting update-engine.service - Update Engine... Oct 29 00:09:55.372665 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 29 00:09:55.389632 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 29 00:09:55.393070 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 29 00:09:55.393490 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 29 00:09:55.409696 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 00:09:55.411166 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 29 00:09:55.431700 extend-filesystems[1944]: Found /dev/nvme0n1p6 Oct 29 00:09:55.472033 jq[1957]: true Oct 29 00:09:55.472482 extend-filesystems[1944]: Found /dev/nvme0n1p9 Oct 29 00:09:55.499939 extend-filesystems[1944]: Checking size of /dev/nvme0n1p9 Oct 29 00:09:55.531164 dbus-daemon[1941]: [system] SELinux support is enabled Oct 29 00:09:55.544375 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 29 00:09:55.554249 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). Oct 29 00:09:55.554294 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 29 00:09:55.557311 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). Oct 29 00:09:55.557347 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 29 00:09:55.573190 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 00:09:55.582705 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 29 00:09:55.589311 (ntainerd)[1975]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 29 00:09:55.596088 extend-filesystems[1944]: Resized partition /dev/nvme0n1p9 Oct 29 00:09:55.591396 ntpd[1947]: ntpd 4.2.8p18@1.4062-o Tue Oct 28 21:43:43 UTC 2025 (1): Starting Oct 29 00:09:55.595562 dbus-daemon[1941]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1579 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Oct 29 00:09:55.601611 ntpd[1947]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: ntpd 4.2.8p18@1.4062-o Tue Oct 28 21:43:43 UTC 2025 (1): Starting Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: ---------------------------------------------------- Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: ntp-4 is maintained by Network Time Foundation, Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: corporation. Support and training for ntp-4 are Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: available at https://www.nwtime.org/support Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: ---------------------------------------------------- Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: proto: precision = 0.096 usec (-23) Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: basedate set to 2025-10-16 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: gps base set to 2025-10-19 (week 2389) Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen and drop on 0 v6wildcard [::]:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen normally on 2 lo 127.0.0.1:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen normally on 3 eth0 172.31.28.41:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen normally on 4 lo [::1]:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listen normally on 5 eth0 [fe80::492:d0ff:febc:f599%2]:123 Oct 29 00:09:55.614138 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: Listening on routing socket on fd #22 for interface updates Oct 29 00:09:55.601647 ntpd[1947]: ---------------------------------------------------- Oct 29 00:09:55.601671 ntpd[1947]: ntp-4 is maintained by Network Time Foundation, Oct 29 00:09:55.601690 ntpd[1947]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 29 00:09:55.601707 ntpd[1947]: corporation. Support and training for ntp-4 are Oct 29 00:09:55.632653 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 29 00:09:55.632946 extend-filesystems[2007]: resize2fs 1.47.3 (8-Jul-2025) Oct 29 00:09:55.668490 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 1617920 to 2604027 blocks Oct 29 00:09:55.670379 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 2604027 Oct 29 00:09:55.670439 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 29 00:09:55.670439 ntpd[1947]: 29 Oct 00:09:55 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 29 00:09:55.601723 ntpd[1947]: available at https://www.nwtime.org/support Oct 29 00:09:55.662431 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 29 00:09:55.683370 jq[1978]: true Oct 29 00:09:55.601739 ntpd[1947]: ---------------------------------------------------- Oct 29 00:09:55.606438 ntpd[1947]: proto: precision = 0.096 usec (-23) Oct 29 00:09:55.608587 ntpd[1947]: basedate set to 2025-10-16 Oct 29 00:09:55.608619 ntpd[1947]: gps base set to 2025-10-19 (week 2389) Oct 29 00:09:55.608815 ntpd[1947]: Listen and drop on 0 v6wildcard [::]:123 Oct 29 00:09:55.608862 ntpd[1947]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 29 00:09:55.609162 ntpd[1947]: Listen normally on 2 lo 127.0.0.1:123 Oct 29 00:09:55.609206 ntpd[1947]: Listen normally on 3 eth0 172.31.28.41:123 Oct 29 00:09:55.609278 ntpd[1947]: Listen normally on 4 lo [::1]:123 Oct 29 00:09:55.609326 ntpd[1947]: Listen normally on 5 eth0 [fe80::492:d0ff:febc:f599%2]:123 Oct 29 00:09:55.609367 ntpd[1947]: Listening on routing socket on fd #22 for interface updates Oct 29 00:09:55.618498 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 29 00:09:55.624965 ntpd[1947]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 29 00:09:55.696766 extend-filesystems[2007]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Oct 29 00:09:55.696766 extend-filesystems[2007]: old_desc_blocks = 1, new_desc_blocks = 2 Oct 29 00:09:55.696766 extend-filesystems[2007]: The filesystem on /dev/nvme0n1p9 is now 2604027 (4k) blocks long. Oct 29 00:09:55.631815 dbus-daemon[1941]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 29 00:09:55.723899 extend-filesystems[1944]: Resized filesystem in /dev/nvme0n1p9 Oct 29 00:09:55.732034 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.747 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.748 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.748 INFO Fetch successful Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.748 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.748 INFO Fetch successful Oct 29 00:09:55.748176 coreos-metadata[1940]: Oct 29 00:09:55.748 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Oct 29 00:09:55.751976 coreos-metadata[1940]: Oct 29 00:09:55.750 INFO Fetch successful Oct 29 00:09:55.751976 coreos-metadata[1940]: Oct 29 00:09:55.750 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Oct 29 00:09:55.751976 coreos-metadata[1940]: Oct 29 00:09:55.750 INFO Fetch successful Oct 29 00:09:55.751976 coreos-metadata[1940]: Oct 29 00:09:55.750 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Oct 29 00:09:55.756944 coreos-metadata[1940]: Oct 29 00:09:55.753 INFO Fetch failed with 404: resource not found Oct 29 00:09:55.756944 coreos-metadata[1940]: Oct 29 00:09:55.754 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Oct 29 00:09:55.759534 coreos-metadata[1940]: Oct 29 00:09:55.758 INFO Fetch successful Oct 29 00:09:55.759534 coreos-metadata[1940]: Oct 29 00:09:55.758 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Oct 29 00:09:55.759534 coreos-metadata[1940]: Oct 29 00:09:55.758 INFO Fetch successful Oct 29 00:09:55.759534 coreos-metadata[1940]: Oct 29 00:09:55.758 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Oct 29 00:09:55.760192 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 29 00:09:55.765904 coreos-metadata[1940]: Oct 29 00:09:55.760 INFO Fetch successful Oct 29 00:09:55.765904 coreos-metadata[1940]: Oct 29 00:09:55.761 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Oct 29 00:09:55.777230 update_engine[1956]: I20251029 00:09:55.770011 1956 main.cc:92] Flatcar Update Engine starting Oct 29 00:09:55.777835 coreos-metadata[1940]: Oct 29 00:09:55.773 INFO Fetch successful Oct 29 00:09:55.777835 coreos-metadata[1940]: Oct 29 00:09:55.773 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Oct 29 00:09:55.777835 coreos-metadata[1940]: Oct 29 00:09:55.773 INFO Fetch successful Oct 29 00:09:55.785693 update_engine[1956]: I20251029 00:09:55.784008 1956 update_check_scheduler.cc:74] Next update check in 3m4s Oct 29 00:09:55.797534 systemd[1]: Finished setup-oem.service - Setup OEM. Oct 29 00:09:55.800321 systemd[1]: Started update-engine.service - Update Engine. Oct 29 00:09:55.808974 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Oct 29 00:09:55.835079 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 29 00:09:55.983486 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 29 00:09:55.986356 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 29 00:09:56.024331 bash[2033]: Updated "/home/core/.ssh/authorized_keys" Oct 29 00:09:56.034637 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 29 00:09:56.045915 systemd[1]: Starting sshkeys.service... Oct 29 00:09:56.094237 systemd-logind[1955]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 00:09:56.094300 systemd-logind[1955]: Watching system buttons on /dev/input/event1 (Sleep Button) Oct 29 00:09:56.099613 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 29 00:09:56.101396 dbus-daemon[1941]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 29 00:09:56.106902 dbus-daemon[1941]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.4' (uid=0 pid=2012 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Oct 29 00:09:56.111390 systemd-logind[1955]: New seat seat0. Oct 29 00:09:56.132680 systemd[1]: Starting polkit.service - Authorization Manager... Oct 29 00:09:56.136988 systemd[1]: Started systemd-logind.service - User Login Management. Oct 29 00:09:56.165702 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 29 00:09:56.175406 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 29 00:09:56.419371 amazon-ssm-agent[2024]: Initializing new seelog logger Oct 29 00:09:56.440546 amazon-ssm-agent[2024]: New Seelog Logger Creation Complete Oct 29 00:09:56.440546 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.440546 amazon-ssm-agent[2024]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.442310 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 processing appconfig overrides Oct 29 00:09:56.446617 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.446617 amazon-ssm-agent[2024]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.446784 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 processing appconfig overrides Oct 29 00:09:56.448539 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.448539 amazon-ssm-agent[2024]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.448539 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 processing appconfig overrides Oct 29 00:09:56.454378 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4464 INFO Proxy environment variables: Oct 29 00:09:56.472155 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.472155 amazon-ssm-agent[2024]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:56.472155 amazon-ssm-agent[2024]: 2025/10/29 00:09:56 processing appconfig overrides Oct 29 00:09:56.493534 coreos-metadata[2067]: Oct 29 00:09:56.491 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 29 00:09:56.494738 coreos-metadata[2067]: Oct 29 00:09:56.494 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Oct 29 00:09:56.495548 coreos-metadata[2067]: Oct 29 00:09:56.495 INFO Fetch successful Oct 29 00:09:56.495631 coreos-metadata[2067]: Oct 29 00:09:56.495 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 29 00:09:56.503274 coreos-metadata[2067]: Oct 29 00:09:56.503 INFO Fetch successful Oct 29 00:09:56.557647 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4464 INFO https_proxy: Oct 29 00:09:56.583160 unknown[2067]: wrote ssh authorized keys file for user: core Oct 29 00:09:56.660120 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4465 INFO http_proxy: Oct 29 00:09:56.713706 update-ssh-keys[2149]: Updated "/home/core/.ssh/authorized_keys" Oct 29 00:09:56.717473 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 29 00:09:56.742748 systemd[1]: Finished sshkeys.service. Oct 29 00:09:56.782148 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4465 INFO no_proxy: Oct 29 00:09:56.865972 polkitd[2061]: Started polkitd version 126 Oct 29 00:09:56.875122 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4467 INFO Checking if agent identity type OnPrem can be assumed Oct 29 00:09:56.883573 locksmithd[2025]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 00:09:56.895314 polkitd[2061]: Loading rules from directory /etc/polkit-1/rules.d Oct 29 00:09:56.896152 polkitd[2061]: Loading rules from directory /run/polkit-1/rules.d Oct 29 00:09:56.897054 sshd_keygen[2001]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 29 00:09:56.898619 polkitd[2061]: Error opening rules directory: Error opening directory “/run/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Oct 29 00:09:56.899318 polkitd[2061]: Loading rules from directory /usr/local/share/polkit-1/rules.d Oct 29 00:09:56.899398 polkitd[2061]: Error opening rules directory: Error opening directory “/usr/local/share/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Oct 29 00:09:56.899497 polkitd[2061]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 29 00:09:56.906994 polkitd[2061]: Finished loading, compiling and executing 2 rules Oct 29 00:09:56.907497 systemd[1]: Started polkit.service - Authorization Manager. Oct 29 00:09:56.913084 dbus-daemon[1941]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 29 00:09:56.916034 polkitd[2061]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 29 00:09:56.974742 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.4468 INFO Checking if agent identity type EC2 can be assumed Oct 29 00:09:57.025951 systemd-hostnamed[2012]: Hostname set to (transient) Oct 29 00:09:57.025987 systemd-resolved[1556]: System hostname changed to 'ip-172-31-28-41'. Oct 29 00:09:57.066853 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 29 00:09:57.073750 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8212 INFO Agent will take identity from EC2 Oct 29 00:09:57.077096 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 29 00:09:57.127323 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 00:09:57.129645 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 29 00:09:57.139031 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 29 00:09:57.156550 containerd[1975]: time="2025-10-29T00:09:57Z" 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 Oct 29 00:09:57.158661 containerd[1975]: time="2025-10-29T00:09:57.158226968Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 29 00:09:57.173099 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8451 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.3.0.0 Oct 29 00:09:57.178981 containerd[1975]: time="2025-10-29T00:09:57.178919696Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="14.28µs" Oct 29 00:09:57.179167 containerd[1975]: time="2025-10-29T00:09:57.179136488Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 29 00:09:57.179334 containerd[1975]: time="2025-10-29T00:09:57.179305988Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 29 00:09:57.179722 containerd[1975]: time="2025-10-29T00:09:57.179688116Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 29 00:09:57.179852 containerd[1975]: time="2025-10-29T00:09:57.179821808Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 29 00:09:57.179993 containerd[1975]: time="2025-10-29T00:09:57.179964176Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 00:09:57.180312 containerd[1975]: time="2025-10-29T00:09:57.180270152Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 00:09:57.180429 containerd[1975]: time="2025-10-29T00:09:57.180400100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 29 00:09:57.180952 containerd[1975]: time="2025-10-29T00:09:57.180895364Z" 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 Oct 29 00:09:57.181543 containerd[1975]: time="2025-10-29T00:09:57.181065644Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 00:09:57.181543 containerd[1975]: time="2025-10-29T00:09:57.181107656Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 00:09:57.181543 containerd[1975]: time="2025-10-29T00:09:57.181130024Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 29 00:09:57.181543 containerd[1975]: time="2025-10-29T00:09:57.181341608Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 29 00:09:57.182097 containerd[1975]: time="2025-10-29T00:09:57.182053988Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 29 00:09:57.182265 containerd[1975]: time="2025-10-29T00:09:57.182234504Z" 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 Oct 29 00:09:57.182365 containerd[1975]: time="2025-10-29T00:09:57.182337104Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 29 00:09:57.182651 containerd[1975]: time="2025-10-29T00:09:57.182492384Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 29 00:09:57.183840 containerd[1975]: time="2025-10-29T00:09:57.183598676Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 29 00:09:57.183840 containerd[1975]: time="2025-10-29T00:09:57.183777476Z" level=info msg="metadata content store policy set" policy=shared Oct 29 00:09:57.189640 containerd[1975]: time="2025-10-29T00:09:57.189584960Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 29 00:09:57.189893 containerd[1975]: time="2025-10-29T00:09:57.189855848Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190095056Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190138760Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190171880Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190199708Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190230764Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190270388Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190301960Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190328576Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190353068Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 29 00:09:57.190549 containerd[1975]: time="2025-10-29T00:09:57.190388732Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191224700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191287220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191323160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191364176Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191394428Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191424032Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191451236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 29 00:09:57.191533 containerd[1975]: time="2025-10-29T00:09:57.191475704Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 29 00:09:57.192546 containerd[1975]: time="2025-10-29T00:09:57.191502716Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 29 00:09:57.192546 containerd[1975]: time="2025-10-29T00:09:57.192047768Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 29 00:09:57.192546 containerd[1975]: time="2025-10-29T00:09:57.192077012Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 29 00:09:57.192546 containerd[1975]: time="2025-10-29T00:09:57.192475352Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 29 00:09:57.192827 containerd[1975]: time="2025-10-29T00:09:57.192795308Z" level=info msg="Start snapshots syncer" Oct 29 00:09:57.192981 containerd[1975]: time="2025-10-29T00:09:57.192952964Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 29 00:09:57.197213 containerd[1975]: time="2025-10-29T00:09:57.197119976Z" 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\"}" Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.197544128Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.197732192Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198015284Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198060596Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198088844Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198125648Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198155516Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198191312Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198219704Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198272144Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198301256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198329900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198420128Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 29 00:09:57.198576 containerd[1975]: time="2025-10-29T00:09:57.198458456Z" 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 Oct 29 00:09:57.199205 containerd[1975]: time="2025-10-29T00:09:57.198489416Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 00:09:57.199955 containerd[1975]: time="2025-10-29T00:09:57.199907216Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 00:09:57.200072 containerd[1975]: time="2025-10-29T00:09:57.200045588Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 29 00:09:57.200174 containerd[1975]: time="2025-10-29T00:09:57.200148068Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 29 00:09:57.200289 containerd[1975]: time="2025-10-29T00:09:57.200251784Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 29 00:09:57.200578 containerd[1975]: time="2025-10-29T00:09:57.200547584Z" level=info msg="runtime interface created" Oct 29 00:09:57.200856 containerd[1975]: time="2025-10-29T00:09:57.200656676Z" level=info msg="created NRI interface" Oct 29 00:09:57.200856 containerd[1975]: time="2025-10-29T00:09:57.200688008Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 29 00:09:57.200856 containerd[1975]: time="2025-10-29T00:09:57.200721140Z" level=info msg="Connect containerd service" Oct 29 00:09:57.200856 containerd[1975]: time="2025-10-29T00:09:57.200795360Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 29 00:09:57.203554 containerd[1975]: time="2025-10-29T00:09:57.202731980Z" 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" Oct 29 00:09:57.211576 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 29 00:09:57.218277 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 29 00:09:57.228191 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 29 00:09:57.231968 systemd[1]: Reached target getty.target - Login Prompts. Oct 29 00:09:57.272446 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8451 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Oct 29 00:09:57.372625 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8451 INFO [amazon-ssm-agent] Starting Core Agent Oct 29 00:09:57.472012 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8452 INFO [amazon-ssm-agent] Registrar detected. Attempting registration Oct 29 00:09:57.572450 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8452 INFO [Registrar] Starting registrar module Oct 29 00:09:57.673990 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8536 INFO [EC2Identity] Checking disk for registration info Oct 29 00:09:57.697716 containerd[1975]: time="2025-10-29T00:09:57.697633067Z" level=info msg="Start subscribing containerd event" Oct 29 00:09:57.697881 containerd[1975]: time="2025-10-29T00:09:57.697729475Z" level=info msg="Start recovering state" Oct 29 00:09:57.697931 containerd[1975]: time="2025-10-29T00:09:57.697869887Z" level=info msg="Start event monitor" Oct 29 00:09:57.697931 containerd[1975]: time="2025-10-29T00:09:57.697910555Z" level=info msg="Start cni network conf syncer for default" Oct 29 00:09:57.698035 containerd[1975]: time="2025-10-29T00:09:57.697929503Z" level=info msg="Start streaming server" Oct 29 00:09:57.698035 containerd[1975]: time="2025-10-29T00:09:57.697948811Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 29 00:09:57.698035 containerd[1975]: time="2025-10-29T00:09:57.697965899Z" level=info msg="runtime interface starting up..." Oct 29 00:09:57.698035 containerd[1975]: time="2025-10-29T00:09:57.697980875Z" level=info msg="starting plugins..." Oct 29 00:09:57.698035 containerd[1975]: time="2025-10-29T00:09:57.698009567Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 29 00:09:57.699847 containerd[1975]: time="2025-10-29T00:09:57.699796235Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 29 00:09:57.702550 containerd[1975]: time="2025-10-29T00:09:57.701279939Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 29 00:09:57.702550 containerd[1975]: time="2025-10-29T00:09:57.701406635Z" level=info msg="containerd successfully booted in 0.545552s" Oct 29 00:09:57.701734 systemd[1]: Started containerd.service - containerd container runtime. Oct 29 00:09:57.773364 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8536 INFO [EC2Identity] No registration info found for ec2 instance, attempting registration Oct 29 00:09:57.874718 amazon-ssm-agent[2024]: 2025-10-29 00:09:56.8536 INFO [EC2Identity] Generating registration keypair Oct 29 00:09:58.205821 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 00:09:58.209973 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 29 00:09:58.215719 systemd[1]: Startup finished in 3.915s (kernel) + 12.029s (initrd) + 13.026s (userspace) = 28.972s. Oct 29 00:09:58.240246 (kubelet)[2218]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 29 00:09:58.674630 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.6744 INFO [EC2Identity] Checking write access before registering Oct 29 00:09:58.719197 amazon-ssm-agent[2024]: 2025/10/29 00:09:58 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:58.719313 amazon-ssm-agent[2024]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 29 00:09:58.719457 amazon-ssm-agent[2024]: 2025/10/29 00:09:58 processing appconfig overrides Oct 29 00:09:58.747369 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.6753 INFO [EC2Identity] Registering EC2 instance with Systems Manager Oct 29 00:09:58.747369 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7188 INFO [EC2Identity] EC2 registration was successful. Oct 29 00:09:58.747645 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7188 INFO [amazon-ssm-agent] Registration attempted. Resuming core agent startup. Oct 29 00:09:58.747645 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7189 INFO [CredentialRefresher] credentialRefresher has started Oct 29 00:09:58.747645 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7190 INFO [CredentialRefresher] Starting credentials refresher loop Oct 29 00:09:58.747645 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7469 INFO EC2RoleProvider Successfully connected with instance profile role credentials Oct 29 00:09:58.747645 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7472 INFO [CredentialRefresher] Credentials ready Oct 29 00:09:58.775207 amazon-ssm-agent[2024]: 2025-10-29 00:09:58.7474 INFO [CredentialRefresher] Next credential rotation will be in 29.9999920624 minutes Oct 29 00:09:59.449650 kubelet[2218]: E1029 00:09:59.449534 2218 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" Oct 29 00:09:59.454077 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 29 00:09:59.454408 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 29 00:09:59.455451 systemd[1]: kubelet.service: Consumed 1.460s CPU time, 259.5M memory peak. Oct 29 00:09:59.775741 amazon-ssm-agent[2024]: 2025-10-29 00:09:59.7751 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Oct 29 00:09:59.875933 amazon-ssm-agent[2024]: 2025-10-29 00:09:59.7783 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2230) started Oct 29 00:09:59.977662 amazon-ssm-agent[2024]: 2025-10-29 00:09:59.7783 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Oct 29 00:10:01.458559 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 29 00:10:01.461760 systemd[1]: Started sshd@0-172.31.28.41:22-147.75.109.163:53814.service - OpenSSH per-connection server daemon (147.75.109.163:53814). Oct 29 00:10:01.688796 sshd[2243]: Accepted publickey for core from 147.75.109.163 port 53814 ssh2: RSA SHA256:iLGXdrmRkxZWBl1ks97sUNbHOXfhbdEl67pxqLLgEhA Oct 29 00:10:01.693244 sshd-session[2243]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:10:01.708036 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 29 00:10:01.710792 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 29 00:10:01.730418 systemd-logind[1955]: New session 1 of user core. Oct 29 00:10:01.752108 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 29 00:10:01.759422 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 29 00:10:01.782902 (systemd)[2248]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 00:10:01.789106 systemd-logind[1955]: New session c1 of user core. Oct 29 00:10:02.102929 systemd[2248]: Queued start job for default target default.target. Oct 29 00:10:02.112322 systemd[2248]: Created slice app.slice - User Application Slice. Oct 29 00:10:02.112403 systemd[2248]: Reached target paths.target - Paths. Oct 29 00:10:02.112504 systemd[2248]: Reached target timers.target - Timers. Oct 29 00:10:02.115469 systemd[2248]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 29 00:10:02.151886 systemd[2248]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 29 00:10:02.152168 systemd[2248]: Reached target sockets.target - Sockets. Oct 29 00:10:02.152304 systemd[2248]: Reached target basic.target - Basic System. Oct 29 00:10:02.152407 systemd[2248]: Reached target default.target - Main User Target. Oct 29 00:10:02.152474 systemd[2248]: Startup finished in 349ms. Oct 29 00:10:02.153165 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 29 00:10:02.164384 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 29 00:10:02.320557 systemd[1]: Started sshd@1-172.31.28.41:22-147.75.109.163:53826.service - OpenSSH per-connection server daemon (147.75.109.163:53826). Oct 29 00:10:02.522351 sshd[2259]: Accepted publickey for core from 147.75.109.163 port 53826 ssh2: RSA SHA256:iLGXdrmRkxZWBl1ks97sUNbHOXfhbdEl67pxqLLgEhA Oct 29 00:10:02.525107 sshd-session[2259]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:10:02.534754 systemd-logind[1955]: New session 2 of user core. Oct 29 00:10:02.547865 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 29 00:10:02.157213 systemd-resolved[1556]: Clock change detected. Flushing caches. Oct 29 00:10:02.167287 systemd-journald[1486]: Time jumped backwards, rotating. Oct 29 00:10:02.228836 sshd[2262]: Connection closed by 147.75.109.163 port 53826 Oct 29 00:10:02.229708 sshd-session[2259]: pam_unix(sshd:session): session closed for user core Oct 29 00:10:02.237847 systemd[1]: sshd@1-172.31.28.41:22-147.75.109.163:53826.service: Deactivated successfully. Oct 29 00:10:02.241414 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 00:10:02.245608 systemd-logind[1955]: Session 2 logged out. Waiting for processes to exit. Oct 29 00:10:02.248861 systemd-logind[1955]: Removed session 2. Oct 29 00:10:02.267378 systemd[1]: Started sshd@2-172.31.28.41:22-147.75.109.163:53828.service - OpenSSH per-connection server daemon (147.75.109.163:53828). Oct 29 00:10:02.466508 sshd[2269]: Accepted publickey for core from 147.75.109.163 port 53828 ssh2: RSA SHA256:iLGXdrmRkxZWBl1ks97sUNbHOXfhbdEl67pxqLLgEhA Oct 29 00:10:02.468909 sshd-session[2269]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:10:02.477608 systemd-logind[1955]: New session 3 of user core. Oct 29 00:10:02.487074 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 29 00:10:02.605357 sshd[2272]: Connection closed by 147.75.109.163 port 53828 Oct 29 00:10:02.606048 sshd-session[2269]: pam_unix(sshd:session): session closed for user core Oct 29 00:10:02.613298 systemd-logind[1955]: Session 3 logged out. Waiting for processes to exit. Oct 29 00:10:02.613484 systemd[1]: sshd@2-172.31.28.41:22-147.75.109.163:53828.service: Deactivated successfully. Oct 29 00:10:02.617301 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 00:10:02.623337 systemd-logind[1955]: Removed session 3. Oct 29 00:10:02.645792 systemd[1]: Started sshd@3-172.31.28.41:22-147.75.109.163:53844.service - OpenSSH per-connection server daemon (147.75.109.163:53844). Oct 29 00:10:02.856254 sshd[2278]: Accepted publickey for core from 147.75.109.163 port 53844 ssh2: RSA SHA256:iLGXdrmRkxZWBl1ks97sUNbHOXfhbdEl67pxqLLgEhA Oct 29 00:10:02.858592 sshd-session[2278]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:10:02.867324 systemd-logind[1955]: New session 4 of user core. Oct 29 00:10:02.876053 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 29 00:10:03.005287 sshd[2281]: Connection closed by 147.75.109.163 port 53844 Oct 29 00:10:03.006076 sshd-session[2278]: pam_unix(sshd:session): session closed for user core Oct 29 00:10:03.013323 systemd[1]: sshd@3-172.31.28.41:22-147.75.109.163:53844.service: Deactivated successfully. Oct 29 00:10:03.016543 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 00:10:03.018776 systemd-logind[1955]: Session 4 logged out. Waiting for processes to exit. Oct 29 00:10:03.023195 systemd-logind[1955]: Removed session 4. Oct 29 00:10:03.039087 systemd[1]: Started sshd@4-172.31.28.41:22-147.75.109.163:53852.service - OpenSSH per-connection server daemon (147.75.109.163:53852). Oct 29 00:10:03.238535 sshd[2287]: Accepted publickey for core from 147.75.109.163 port 53852 ssh2: RSA SHA256:iLGXdrmRkxZWBl1ks97sUNbHOXfhbdEl67pxqLLgEhA Oct 29 00:10:03.241411 sshd-session[2287]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 00:10:03.250371 systemd-logind[1955]: New session 5 of user core. Oct 29 00:10:03.261068 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 29 00:10:03.384357 sudo[2291]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 29 00:10:03.385108 sudo[2291]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 29 00:10:05.164766 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 00:10:05.166438 systemd[1]: kubelet.service: Consumed 1.460s CPU time, 259.5M memory peak. Oct 29 00:10:05.170568 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 00:10:05.231884 systemd[1]: Reload requested from client PID 2326 ('systemctl') (unit session-5.scope)... Oct 29 00:10:05.231928 systemd[1]: Reloading... Oct 29 00:10:05.534827 zram_generator::config[2377]: No configuration found. Oct 29 00:10:06.007991 systemd[1]: Reloading finished in 775 ms. Oct 29 00:10:06.135133 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 00:10:06.143393 systemd[1]: kubelet.service: Deactivated successfully. Oct 29 00:10:06.143944 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 00:10:06.144051 systemd[1]: kubelet.service: Consumed 268ms CPU time, 95.1M memory peak. Oct 29 00:10:06.148346 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 00:10:06.493700 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 00:10:06.511662 (kubelet)[2436]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 29 00:10:06.584128 kubelet[2436]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 29 00:10:06.584128 kubelet[2436]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 29 00:10:06.584128 kubelet[2436]: 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. Oct 29 00:10:06.584629 kubelet[2436]: I1029 00:10:06.584197 2436 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 29 00:10:08.514126 kubelet[2436]: I1029 00:10:08.514030 2436 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 29 00:10:08.515198 kubelet[2436]: I1029 00:10:08.515144 2436 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 29 00:10:08.516511 kubelet[2436]: I1029 00:10:08.516298 2436 server.go:956] "Client rotation is on, will bootstrap in background" Oct 29 00:10:08.562661 kubelet[2436]: I1029 00:10:08.562592 2436 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 29 00:10:08.586028 kubelet[2436]: I1029 00:10:08.585961 2436 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 29 00:10:08.592341 kubelet[2436]: I1029 00:10:08.592294 2436 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 29 00:10:08.595843 kubelet[2436]: I1029 00:10:08.595256 2436 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 29 00:10:08.595843 kubelet[2436]: I1029 00:10:08.595345 2436 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.28.41","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 29 00:10:08.595843 kubelet[2436]: I1029 00:10:08.595824 2436 topology_manager.go:138] "Creating topology manager with none policy" Oct 29 00:10:08.595843 kubelet[2436]: I1029 00:10:08.595856 2436 container_manager_linux.go:303] "Creating device plugin manager" Oct 29 00:10:08.597769 kubelet[2436]: I1029 00:10:08.597632 2436 state_mem.go:36] "Initialized new in-memory state store" Oct 29 00:10:08.604319 kubelet[2436]: I1029 00:10:08.603873 2436 kubelet.go:480] "Attempting to sync node with API server" Oct 29 00:10:08.604319 kubelet[2436]: I1029 00:10:08.603924 2436 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 29 00:10:08.604319 kubelet[2436]: I1029 00:10:08.604003 2436 kubelet.go:386] "Adding apiserver pod source" Oct 29 00:10:08.606944 kubelet[2436]: I1029 00:10:08.606882 2436 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 29 00:10:08.607156 kubelet[2436]: E1029 00:10:08.607121 2436 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:08.608066 kubelet[2436]: E1029 00:10:08.608019 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:08.609791 kubelet[2436]: I1029 00:10:08.609238 2436 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 29 00:10:08.610766 kubelet[2436]: I1029 00:10:08.610701 2436 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 29 00:10:08.611230 kubelet[2436]: W1029 00:10:08.611199 2436 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 29 00:10:08.616166 kubelet[2436]: I1029 00:10:08.616127 2436 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 29 00:10:08.616431 kubelet[2436]: I1029 00:10:08.616403 2436 server.go:1289] "Started kubelet" Oct 29 00:10:08.620326 kubelet[2436]: I1029 00:10:08.620275 2436 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 29 00:10:08.634986 kubelet[2436]: I1029 00:10:08.634893 2436 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 29 00:10:08.646501 kubelet[2436]: I1029 00:10:08.646441 2436 server.go:317] "Adding debug handlers to kubelet server" Oct 29 00:10:08.653106 kubelet[2436]: I1029 00:10:08.653059 2436 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 29 00:10:08.656813 kubelet[2436]: I1029 00:10:08.654791 2436 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 29 00:10:08.656813 kubelet[2436]: I1029 00:10:08.655654 2436 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 29 00:10:08.658412 kubelet[2436]: I1029 00:10:08.658315 2436 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 29 00:10:08.667558 kubelet[2436]: E1029 00:10:08.666222 2436 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.28.41\" not found" Oct 29 00:10:08.669879 kubelet[2436]: I1029 00:10:08.669841 2436 reconciler.go:26] "Reconciler: start to sync state" Oct 29 00:10:08.672168 kubelet[2436]: I1029 00:10:08.670889 2436 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 29 00:10:08.675721 kubelet[2436]: I1029 00:10:08.675610 2436 factory.go:223] Registration of the systemd container factory successfully Oct 29 00:10:08.676433 kubelet[2436]: I1029 00:10:08.676079 2436 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 Oct 29 00:10:08.680468 kubelet[2436]: I1029 00:10:08.680035 2436 factory.go:223] Registration of the containerd container factory successfully Oct 29 00:10:08.711969 kubelet[2436]: E1029 00:10:08.711876 2436 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.28.41\" not found" node="172.31.28.41" Oct 29 00:10:08.716170 kubelet[2436]: E1029 00:10:08.716103 2436 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 29 00:10:08.743662 kubelet[2436]: I1029 00:10:08.743282 2436 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 29 00:10:08.743662 kubelet[2436]: I1029 00:10:08.743312 2436 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 29 00:10:08.743662 kubelet[2436]: I1029 00:10:08.743354 2436 state_mem.go:36] "Initialized new in-memory state store" Oct 29 00:10:08.748834 kubelet[2436]: I1029 00:10:08.748721 2436 policy_none.go:49] "None policy: Start" Oct 29 00:10:08.748834 kubelet[2436]: I1029 00:10:08.748819 2436 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 29 00:10:08.749019 kubelet[2436]: I1029 00:10:08.748856 2436 state_mem.go:35] "Initializing new in-memory state store" Oct 29 00:10:08.755130 kubelet[2436]: I1029 00:10:08.754944 2436 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 29 00:10:08.757783 kubelet[2436]: I1029 00:10:08.757623 2436 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 29 00:10:08.757783 kubelet[2436]: I1029 00:10:08.757677 2436 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 29 00:10:08.757783 kubelet[2436]: I1029 00:10:08.757712 2436 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 29 00:10:08.757783 kubelet[2436]: I1029 00:10:08.757726 2436 kubelet.go:2436] "Starting kubelet main sync loop" Oct 29 00:10:08.758002 kubelet[2436]: E1029 00:10:08.757863 2436 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 29 00:10:08.769354 kubelet[2436]: E1029 00:10:08.766459 2436 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.28.41\" not found" Oct 29 00:10:08.778548 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 29 00:10:08.795988 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 29 00:10:08.820597 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 29 00:10:08.825329 kubelet[2436]: E1029 00:10:08.824512 2436 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 29 00:10:08.825329 kubelet[2436]: I1029 00:10:08.824915 2436 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 29 00:10:08.825329 kubelet[2436]: I1029 00:10:08.824939 2436 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 29 00:10:08.827673 kubelet[2436]: I1029 00:10:08.827623 2436 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 29 00:10:08.829543 kubelet[2436]: E1029 00:10:08.828934 2436 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 29 00:10:08.829543 kubelet[2436]: E1029 00:10:08.829262 2436 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.28.41\" not found" Oct 29 00:10:08.926912 kubelet[2436]: I1029 00:10:08.926847 2436 kubelet_node_status.go:75] "Attempting to register node" node="172.31.28.41" Oct 29 00:10:08.935607 kubelet[2436]: I1029 00:10:08.935533 2436 kubelet_node_status.go:78] "Successfully registered node" node="172.31.28.41" Oct 29 00:10:09.050279 kubelet[2436]: I1029 00:10:09.050118 2436 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 29 00:10:09.051207 containerd[1975]: time="2025-10-29T00:10:09.050655386Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 29 00:10:09.052268 kubelet[2436]: I1029 00:10:09.051635 2436 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 29 00:10:09.058523 sudo[2291]: pam_unix(sudo:session): session closed for user root Oct 29 00:10:09.083585 sshd[2290]: Connection closed by 147.75.109.163 port 53852 Oct 29 00:10:09.084503 sshd-session[2287]: pam_unix(sshd:session): session closed for user core Oct 29 00:10:09.093333 systemd[1]: sshd@4-172.31.28.41:22-147.75.109.163:53852.service: Deactivated successfully. Oct 29 00:10:09.099176 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 00:10:09.101925 systemd[1]: session-5.scope: Consumed 864ms CPU time, 72.2M memory peak. Oct 29 00:10:09.104932 systemd-logind[1955]: Session 5 logged out. Waiting for processes to exit. Oct 29 00:10:09.108152 systemd-logind[1955]: Removed session 5. Oct 29 00:10:09.520367 kubelet[2436]: I1029 00:10:09.519433 2436 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 29 00:10:09.520367 kubelet[2436]: I1029 00:10:09.519679 2436 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 29 00:10:09.520367 kubelet[2436]: I1029 00:10:09.519784 2436 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 29 00:10:09.520367 kubelet[2436]: I1029 00:10:09.519855 2436 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 29 00:10:09.609000 kubelet[2436]: I1029 00:10:09.608957 2436 apiserver.go:52] "Watching apiserver" Oct 29 00:10:09.609315 kubelet[2436]: E1029 00:10:09.608973 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:09.641758 systemd[1]: Created slice kubepods-besteffort-pod50897a4f_578c_4b86_9018_403b3ea304b5.slice - libcontainer container kubepods-besteffort-pod50897a4f_578c_4b86_9018_403b3ea304b5.slice. Oct 29 00:10:09.662112 systemd[1]: Created slice kubepods-burstable-podcc3538bd_136f_461c_8e20_0ae28ecdc178.slice - libcontainer container kubepods-burstable-podcc3538bd_136f_461c_8e20_0ae28ecdc178.slice. Oct 29 00:10:09.673109 kubelet[2436]: I1029 00:10:09.673052 2436 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 29 00:10:09.677498 kubelet[2436]: I1029 00:10:09.677449 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hfmjf\" (UniqueName: \"kubernetes.io/projected/cc3538bd-136f-461c-8e20-0ae28ecdc178-kube-api-access-hfmjf\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.677609 kubelet[2436]: I1029 00:10:09.677521 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/50897a4f-578c-4b86-9018-403b3ea304b5-kube-proxy\") pod \"kube-proxy-fzxtc\" (UID: \"50897a4f-578c-4b86-9018-403b3ea304b5\") " pod="kube-system/kube-proxy-fzxtc" Oct 29 00:10:09.677609 kubelet[2436]: I1029 00:10:09.677562 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/cc3538bd-136f-461c-8e20-0ae28ecdc178-run\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.677609 kubelet[2436]: I1029 00:10:09.677598 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/cc3538bd-136f-461c-8e20-0ae28ecdc178-cni-plugin\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.677784 kubelet[2436]: I1029 00:10:09.677646 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/cc3538bd-136f-461c-8e20-0ae28ecdc178-cni\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.677784 kubelet[2436]: I1029 00:10:09.677679 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/cc3538bd-136f-461c-8e20-0ae28ecdc178-flannel-cfg\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.677784 kubelet[2436]: I1029 00:10:09.677714 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/50897a4f-578c-4b86-9018-403b3ea304b5-xtables-lock\") pod \"kube-proxy-fzxtc\" (UID: \"50897a4f-578c-4b86-9018-403b3ea304b5\") " pod="kube-system/kube-proxy-fzxtc" Oct 29 00:10:09.677926 kubelet[2436]: I1029 00:10:09.677851 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/50897a4f-578c-4b86-9018-403b3ea304b5-lib-modules\") pod \"kube-proxy-fzxtc\" (UID: \"50897a4f-578c-4b86-9018-403b3ea304b5\") " pod="kube-system/kube-proxy-fzxtc" Oct 29 00:10:09.678046 kubelet[2436]: I1029 00:10:09.678012 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8qf8c\" (UniqueName: \"kubernetes.io/projected/50897a4f-578c-4b86-9018-403b3ea304b5-kube-api-access-8qf8c\") pod \"kube-proxy-fzxtc\" (UID: \"50897a4f-578c-4b86-9018-403b3ea304b5\") " pod="kube-system/kube-proxy-fzxtc" Oct 29 00:10:09.678168 kubelet[2436]: I1029 00:10:09.678124 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cc3538bd-136f-461c-8e20-0ae28ecdc178-xtables-lock\") pod \"kube-flannel-ds-pzrqs\" (UID: \"cc3538bd-136f-461c-8e20-0ae28ecdc178\") " pod="kube-flannel/kube-flannel-ds-pzrqs" Oct 29 00:10:09.958207 containerd[1975]: time="2025-10-29T00:10:09.958044990Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-fzxtc,Uid:50897a4f-578c-4b86-9018-403b3ea304b5,Namespace:kube-system,Attempt:0,}" Oct 29 00:10:09.973869 containerd[1975]: time="2025-10-29T00:10:09.973511886Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pzrqs,Uid:cc3538bd-136f-461c-8e20-0ae28ecdc178,Namespace:kube-flannel,Attempt:0,}" Oct 29 00:10:10.549718 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2765068818.mount: Deactivated successfully. Oct 29 00:10:10.570868 containerd[1975]: time="2025-10-29T00:10:10.570354677Z" 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\"}" Oct 29 00:10:10.573044 containerd[1975]: time="2025-10-29T00:10:10.572972885Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268703" Oct 29 00:10:10.577691 containerd[1975]: time="2025-10-29T00:10:10.577578377Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 29 00:10:10.579357 containerd[1975]: time="2025-10-29T00:10:10.579280409Z" 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\"}" Oct 29 00:10:10.581584 containerd[1975]: time="2025-10-29T00:10:10.581497025Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 29 00:10:10.584643 containerd[1975]: time="2025-10-29T00:10:10.584565653Z" 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\"}" Oct 29 00:10:10.587771 containerd[1975]: time="2025-10-29T00:10:10.586442153Z" 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 614.647131ms" Oct 29 00:10:10.589906 containerd[1975]: time="2025-10-29T00:10:10.589855469Z" 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 602.813007ms" Oct 29 00:10:10.610312 kubelet[2436]: E1029 00:10:10.610247 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:10.652391 containerd[1975]: time="2025-10-29T00:10:10.651978114Z" level=info msg="connecting to shim ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0" address="unix:///run/containerd/s/3fd4b4496e21711f6cc72f23c6ad62ef2f28edca29d84cc33ee548e2b2351b45" namespace=k8s.io protocol=ttrpc version=3 Oct 29 00:10:10.654112 containerd[1975]: time="2025-10-29T00:10:10.654054138Z" level=info msg="connecting to shim 55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9" address="unix:///run/containerd/s/2bcb6ccbd8c9849d1a0fe225f7b9b15f807aa67df08a49b3ab4f622ae7a8498f" namespace=k8s.io protocol=ttrpc version=3 Oct 29 00:10:10.703148 systemd[1]: Started cri-containerd-ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0.scope - libcontainer container ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0. Oct 29 00:10:10.722104 systemd[1]: Started cri-containerd-55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9.scope - libcontainer container 55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9. Oct 29 00:10:10.796776 containerd[1975]: time="2025-10-29T00:10:10.795022866Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-fzxtc,Uid:50897a4f-578c-4b86-9018-403b3ea304b5,Namespace:kube-system,Attempt:0,} returns sandbox id \"ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0\"" Oct 29 00:10:10.806834 containerd[1975]: time="2025-10-29T00:10:10.805120866Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 29 00:10:10.851089 containerd[1975]: time="2025-10-29T00:10:10.850855807Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-pzrqs,Uid:cc3538bd-136f-461c-8e20-0ae28ecdc178,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\"" Oct 29 00:10:11.611334 kubelet[2436]: E1029 00:10:11.611269 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:12.098016 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1272135015.mount: Deactivated successfully. Oct 29 00:10:12.611924 kubelet[2436]: E1029 00:10:12.611846 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:12.701216 containerd[1975]: time="2025-10-29T00:10:12.701132936Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:12.703171 containerd[1975]: time="2025-10-29T00:10:12.703103072Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240106" Oct 29 00:10:12.704167 containerd[1975]: time="2025-10-29T00:10:12.704104820Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:12.708424 containerd[1975]: time="2025-10-29T00:10:12.708338708Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:12.709864 containerd[1975]: time="2025-10-29T00:10:12.709799072Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.904612122s" Oct 29 00:10:12.709864 containerd[1975]: time="2025-10-29T00:10:12.709858172Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 29 00:10:12.712976 containerd[1975]: time="2025-10-29T00:10:12.712336652Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 29 00:10:12.715873 containerd[1975]: time="2025-10-29T00:10:12.715796720Z" level=info msg="CreateContainer within sandbox \"ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 29 00:10:12.734790 containerd[1975]: time="2025-10-29T00:10:12.732885512Z" level=info msg="Container 9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:12.747525 containerd[1975]: time="2025-10-29T00:10:12.747472028Z" level=info msg="CreateContainer within sandbox \"ed85eb9f5fa924fb4b2424f5aa6050978a843c950dae9c279f987d4541dc24f0\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09\"" Oct 29 00:10:12.751276 containerd[1975]: time="2025-10-29T00:10:12.751193564Z" level=info msg="StartContainer for \"9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09\"" Oct 29 00:10:12.759368 containerd[1975]: time="2025-10-29T00:10:12.759258584Z" level=info msg="connecting to shim 9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09" address="unix:///run/containerd/s/3fd4b4496e21711f6cc72f23c6ad62ef2f28edca29d84cc33ee548e2b2351b45" protocol=ttrpc version=3 Oct 29 00:10:12.811053 systemd[1]: Started cri-containerd-9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09.scope - libcontainer container 9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09. Oct 29 00:10:12.886795 containerd[1975]: time="2025-10-29T00:10:12.885965469Z" level=info msg="StartContainer for \"9454e1b83cd5cccf5c1a037b9249fad2fcf76c0c88b0c81a6060eb9c2b47fb09\" returns successfully" Oct 29 00:10:13.612885 kubelet[2436]: E1029 00:10:13.612829 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:14.310641 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3866070003.mount: Deactivated successfully. Oct 29 00:10:14.381782 containerd[1975]: time="2025-10-29T00:10:14.381625208Z" 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\"}" Oct 29 00:10:14.384956 containerd[1975]: time="2025-10-29T00:10:14.384898280Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 29 00:10:14.387051 containerd[1975]: time="2025-10-29T00:10:14.386963012Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:14.394446 containerd[1975]: time="2025-10-29T00:10:14.394342364Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:14.396533 containerd[1975]: time="2025-10-29T00:10:14.396332216Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 1.683931028s" Oct 29 00:10:14.396533 containerd[1975]: time="2025-10-29T00:10:14.396391472Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 29 00:10:14.406790 containerd[1975]: time="2025-10-29T00:10:14.406591472Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 29 00:10:14.428235 containerd[1975]: time="2025-10-29T00:10:14.425877056Z" level=info msg="Container 1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:14.433966 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3463647069.mount: Deactivated successfully. Oct 29 00:10:14.446683 containerd[1975]: time="2025-10-29T00:10:14.446573505Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\"" Oct 29 00:10:14.447717 containerd[1975]: time="2025-10-29T00:10:14.447643173Z" level=info msg="StartContainer for \"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\"" Oct 29 00:10:14.450022 containerd[1975]: time="2025-10-29T00:10:14.449964993Z" level=info msg="connecting to shim 1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4" address="unix:///run/containerd/s/2bcb6ccbd8c9849d1a0fe225f7b9b15f807aa67df08a49b3ab4f622ae7a8498f" protocol=ttrpc version=3 Oct 29 00:10:14.494065 systemd[1]: Started cri-containerd-1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4.scope - libcontainer container 1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4. Oct 29 00:10:14.555017 systemd[1]: cri-containerd-1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4.scope: Deactivated successfully. Oct 29 00:10:14.556893 containerd[1975]: time="2025-10-29T00:10:14.555729777Z" level=info msg="StartContainer for \"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\" returns successfully" Oct 29 00:10:14.562280 containerd[1975]: time="2025-10-29T00:10:14.562125729Z" level=info msg="received exit event container_id:\"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\" id:\"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\" pid:2772 exited_at:{seconds:1761696614 nanos:561423153}" Oct 29 00:10:14.563064 containerd[1975]: time="2025-10-29T00:10:14.562921881Z" level=info msg="TaskExit event in podsandbox handler container_id:\"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\" id:\"1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4\" pid:2772 exited_at:{seconds:1761696614 nanos:561423153}" Oct 29 00:10:14.613325 kubelet[2436]: E1029 00:10:14.613260 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:14.812279 containerd[1975]: time="2025-10-29T00:10:14.812183038Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 29 00:10:14.828887 kubelet[2436]: I1029 00:10:14.828526 2436 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-fzxtc" podStartSLOduration=4.919257056 podStartE2EDuration="6.828507262s" podCreationTimestamp="2025-10-29 00:10:08 +0000 UTC" firstStartedPulling="2025-10-29 00:10:10.80254515 +0000 UTC m=+4.281359038" lastFinishedPulling="2025-10-29 00:10:12.711795272 +0000 UTC m=+6.190609244" observedRunningTime="2025-10-29 00:10:13.822924045 +0000 UTC m=+7.301737945" watchObservedRunningTime="2025-10-29 00:10:14.828507262 +0000 UTC m=+8.307321162" Oct 29 00:10:15.149851 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-1edda3519993813fa240aeda8cc5e2007e3d7d88f65e72f736eb37da0fe966f4-rootfs.mount: Deactivated successfully. Oct 29 00:10:15.614797 kubelet[2436]: E1029 00:10:15.614476 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:16.615620 kubelet[2436]: E1029 00:10:16.615556 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:17.176831 containerd[1975]: time="2025-10-29T00:10:17.176775742Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:17.180016 containerd[1975]: time="2025-10-29T00:10:17.179969962Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 29 00:10:17.182823 containerd[1975]: time="2025-10-29T00:10:17.182764330Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:17.187082 containerd[1975]: time="2025-10-29T00:10:17.186980014Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:17.190767 containerd[1975]: time="2025-10-29T00:10:17.189707242Z" 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.37742566s" Oct 29 00:10:17.190767 containerd[1975]: time="2025-10-29T00:10:17.189788410Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 29 00:10:17.196028 containerd[1975]: time="2025-10-29T00:10:17.195953662Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 29 00:10:17.207791 containerd[1975]: time="2025-10-29T00:10:17.205515886Z" level=info msg="Container a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:17.225494 containerd[1975]: time="2025-10-29T00:10:17.225403666Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\"" Oct 29 00:10:17.226459 containerd[1975]: time="2025-10-29T00:10:17.226399570Z" level=info msg="StartContainer for \"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\"" Oct 29 00:10:17.228815 containerd[1975]: time="2025-10-29T00:10:17.228697318Z" level=info msg="connecting to shim a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c" address="unix:///run/containerd/s/2bcb6ccbd8c9849d1a0fe225f7b9b15f807aa67df08a49b3ab4f622ae7a8498f" protocol=ttrpc version=3 Oct 29 00:10:17.266032 systemd[1]: Started cri-containerd-a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c.scope - libcontainer container a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c. Oct 29 00:10:17.316848 systemd[1]: cri-containerd-a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c.scope: Deactivated successfully. Oct 29 00:10:17.323008 containerd[1975]: time="2025-10-29T00:10:17.322909331Z" level=info msg="received exit event container_id:\"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\" id:\"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\" pid:2849 exited_at:{seconds:1761696617 nanos:321780491}" Oct 29 00:10:17.323608 containerd[1975]: time="2025-10-29T00:10:17.323501567Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\" id:\"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\" pid:2849 exited_at:{seconds:1761696617 nanos:321780491}" Oct 29 00:10:17.324418 kubelet[2436]: I1029 00:10:17.324377 2436 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 29 00:10:17.326959 containerd[1975]: time="2025-10-29T00:10:17.326805863Z" level=info msg="StartContainer for \"a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c\" returns successfully" Oct 29 00:10:17.367193 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a8c9e01e5a1b0b08f3e0b9a848ff76a236ce956b09f781217486884b9bbf4c3c-rootfs.mount: Deactivated successfully. Oct 29 00:10:17.617405 kubelet[2436]: E1029 00:10:17.617214 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:17.828510 containerd[1975]: time="2025-10-29T00:10:17.828441025Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 29 00:10:17.844348 containerd[1975]: time="2025-10-29T00:10:17.844270093Z" level=info msg="Container 8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:17.854844 containerd[1975]: time="2025-10-29T00:10:17.854731957Z" level=info msg="CreateContainer within sandbox \"55c4bb3db3a089cbdfab608d4be7e423dad306c68e5c004d0f62012d3fdeb8c9\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319\"" Oct 29 00:10:17.857416 containerd[1975]: time="2025-10-29T00:10:17.855790585Z" level=info msg="StartContainer for \"8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319\"" Oct 29 00:10:17.857416 containerd[1975]: time="2025-10-29T00:10:17.857299945Z" level=info msg="connecting to shim 8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319" address="unix:///run/containerd/s/2bcb6ccbd8c9849d1a0fe225f7b9b15f807aa67df08a49b3ab4f622ae7a8498f" protocol=ttrpc version=3 Oct 29 00:10:17.892069 systemd[1]: Started cri-containerd-8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319.scope - libcontainer container 8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319. Oct 29 00:10:17.951390 containerd[1975]: time="2025-10-29T00:10:17.951321902Z" level=info msg="StartContainer for \"8096d0cd1b2b5c6f84f9b90c3b64dff140de577a2a11a0b42eb96927a7f38319\" returns successfully" Oct 29 00:10:18.618160 kubelet[2436]: E1029 00:10:18.618084 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:19.055167 (udev-worker)[2911]: Network interface NamePolicy= disabled on kernel command line. Oct 29 00:10:19.072597 systemd-networkd[1579]: flannel.1: Link UP Oct 29 00:10:19.074720 systemd-networkd[1579]: flannel.1: Gained carrier Oct 29 00:10:19.618814 kubelet[2436]: E1029 00:10:19.618755 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:20.619332 kubelet[2436]: E1029 00:10:20.619248 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:20.861065 systemd-networkd[1579]: flannel.1: Gained IPv6LL Oct 29 00:10:21.249888 kubelet[2436]: I1029 00:10:21.249796 2436 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-pzrqs" podStartSLOduration=6.911553911 podStartE2EDuration="13.249723734s" podCreationTimestamp="2025-10-29 00:10:08 +0000 UTC" firstStartedPulling="2025-10-29 00:10:10.853630591 +0000 UTC m=+4.332444479" lastFinishedPulling="2025-10-29 00:10:17.191800426 +0000 UTC m=+10.670614302" observedRunningTime="2025-10-29 00:10:18.853382714 +0000 UTC m=+12.332196602" watchObservedRunningTime="2025-10-29 00:10:21.249723734 +0000 UTC m=+14.728537622" Oct 29 00:10:21.262502 systemd[1]: Created slice kubepods-besteffort-pod0260dcec_ae91_401e_a81e_aa9ee36fb71a.slice - libcontainer container kubepods-besteffort-pod0260dcec_ae91_401e_a81e_aa9ee36fb71a.slice. Oct 29 00:10:21.365646 kubelet[2436]: I1029 00:10:21.365568 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-q7ztp\" (UniqueName: \"kubernetes.io/projected/0260dcec-ae91-401e-a81e-aa9ee36fb71a-kube-api-access-q7ztp\") pod \"nginx-deployment-7fcdb87857-wvxjm\" (UID: \"0260dcec-ae91-401e-a81e-aa9ee36fb71a\") " pod="default/nginx-deployment-7fcdb87857-wvxjm" Oct 29 00:10:21.569494 containerd[1975]: time="2025-10-29T00:10:21.568954324Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-wvxjm,Uid:0260dcec-ae91-401e-a81e-aa9ee36fb71a,Namespace:default,Attempt:0,}" Oct 29 00:10:21.598885 systemd-networkd[1579]: cni0: Link UP Oct 29 00:10:21.598898 systemd-networkd[1579]: cni0: Gained carrier Oct 29 00:10:21.599377 systemd-networkd[1579]: cni0: Lost carrier Oct 29 00:10:21.620195 kubelet[2436]: E1029 00:10:21.620098 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:21.622130 kernel: cni0: port 1(vethc13615e5) entered blocking state Oct 29 00:10:21.623381 kernel: cni0: port 1(vethc13615e5) entered disabled state Oct 29 00:10:21.623428 kernel: vethc13615e5: entered allmulticast mode Oct 29 00:10:21.625781 kernel: vethc13615e5: entered promiscuous mode Oct 29 00:10:21.628831 systemd-networkd[1579]: vethc13615e5: Link UP Oct 29 00:10:21.642988 kernel: cni0: port 1(vethc13615e5) entered blocking state Oct 29 00:10:21.643302 kernel: cni0: port 1(vethc13615e5) entered forwarding state Oct 29 00:10:21.643733 systemd-networkd[1579]: vethc13615e5: Gained carrier Oct 29 00:10:21.645273 systemd-networkd[1579]: cni0: Gained carrier Oct 29 00:10:21.650577 containerd[1975]: 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"} Oct 29 00:10:21.650577 containerd[1975]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:10:21.691728 containerd[1975]: {"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-10-29T00:10:21.691636385Z" level=info msg="connecting to shim ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f" address="unix:///run/containerd/s/956016a0ec409678a079e05aae69e80acc1754d8f3539f5fdbf8e0e0de34b7ca" namespace=k8s.io protocol=ttrpc version=3 Oct 29 00:10:21.744064 systemd[1]: Started cri-containerd-ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f.scope - libcontainer container ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f. Oct 29 00:10:21.811422 containerd[1975]: time="2025-10-29T00:10:21.811303817Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-wvxjm,Uid:0260dcec-ae91-401e-a81e-aa9ee36fb71a,Namespace:default,Attempt:0,} returns sandbox id \"ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f\"" Oct 29 00:10:21.813917 containerd[1975]: time="2025-10-29T00:10:21.813783545Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 00:10:22.620537 kubelet[2436]: E1029 00:10:22.620472 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:22.781013 systemd-networkd[1579]: cni0: Gained IPv6LL Oct 29 00:10:22.845032 systemd-networkd[1579]: vethc13615e5: Gained IPv6LL Oct 29 00:10:23.620929 kubelet[2436]: E1029 00:10:23.620815 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:24.621259 kubelet[2436]: E1029 00:10:24.621201 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:24.955435 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2074314743.mount: Deactivated successfully. Oct 29 00:10:25.156969 ntpd[1947]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 29 00:10:25.157050 ntpd[1947]: Listen normally on 7 cni0 192.168.1.1:123 Oct 29 00:10:25.157535 ntpd[1947]: 29 Oct 00:10:25 ntpd[1947]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 29 00:10:25.157535 ntpd[1947]: 29 Oct 00:10:25 ntpd[1947]: Listen normally on 7 cni0 192.168.1.1:123 Oct 29 00:10:25.157535 ntpd[1947]: 29 Oct 00:10:25 ntpd[1947]: Listen normally on 8 flannel.1 [fe80::6815:75ff:fe72:be40%3]:123 Oct 29 00:10:25.157535 ntpd[1947]: 29 Oct 00:10:25 ntpd[1947]: Listen normally on 9 cni0 [fe80::601a:ceff:fedf:41e5%4]:123 Oct 29 00:10:25.157535 ntpd[1947]: 29 Oct 00:10:25 ntpd[1947]: Listen normally on 10 vethc13615e5 [fe80::f46c:93ff:fe2a:ad3%5]:123 Oct 29 00:10:25.157101 ntpd[1947]: Listen normally on 8 flannel.1 [fe80::6815:75ff:fe72:be40%3]:123 Oct 29 00:10:25.157148 ntpd[1947]: Listen normally on 9 cni0 [fe80::601a:ceff:fedf:41e5%4]:123 Oct 29 00:10:25.157206 ntpd[1947]: Listen normally on 10 vethc13615e5 [fe80::f46c:93ff:fe2a:ad3%5]:123 Oct 29 00:10:25.621853 kubelet[2436]: E1029 00:10:25.621786 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:26.424521 containerd[1975]: time="2025-10-29T00:10:26.424435208Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:26.426426 containerd[1975]: time="2025-10-29T00:10:26.426162440Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 29 00:10:26.427433 containerd[1975]: time="2025-10-29T00:10:26.427378460Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:26.433138 containerd[1975]: time="2025-10-29T00:10:26.433076864Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:26.434830 containerd[1975]: time="2025-10-29T00:10:26.434773640Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 4.620914375s" Oct 29 00:10:26.434936 containerd[1975]: time="2025-10-29T00:10:26.434829188Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 00:10:26.440520 containerd[1975]: time="2025-10-29T00:10:26.440449532Z" level=info msg="CreateContainer within sandbox \"ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 29 00:10:26.457914 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2708828197.mount: Deactivated successfully. Oct 29 00:10:26.462237 containerd[1975]: time="2025-10-29T00:10:26.458615312Z" level=info msg="Container d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:26.468379 containerd[1975]: time="2025-10-29T00:10:26.468315104Z" level=info msg="CreateContainer within sandbox \"ddb8431f9660075f8a3d2173f7960ec5dd39231d1ac2b72003b41b3970df2e4f\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54\"" Oct 29 00:10:26.469552 containerd[1975]: time="2025-10-29T00:10:26.469415756Z" level=info msg="StartContainer for \"d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54\"" Oct 29 00:10:26.471377 containerd[1975]: time="2025-10-29T00:10:26.471292460Z" level=info msg="connecting to shim d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54" address="unix:///run/containerd/s/956016a0ec409678a079e05aae69e80acc1754d8f3539f5fdbf8e0e0de34b7ca" protocol=ttrpc version=3 Oct 29 00:10:26.511055 systemd[1]: Started cri-containerd-d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54.scope - libcontainer container d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54. Oct 29 00:10:26.568499 containerd[1975]: time="2025-10-29T00:10:26.568441317Z" level=info msg="StartContainer for \"d66039e00541df3d9d9f22883ad69b0e05d7ac601ed50f67f445c2f35d32fe54\" returns successfully" Oct 29 00:10:26.614876 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 29 00:10:26.623781 kubelet[2436]: E1029 00:10:26.622940 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:27.624146 kubelet[2436]: E1029 00:10:27.624085 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:28.605085 kubelet[2436]: E1029 00:10:28.605016 2436 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:28.625029 kubelet[2436]: E1029 00:10:28.624981 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:29.171589 kubelet[2436]: I1029 00:10:29.171517 2436 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-wvxjm" podStartSLOduration=3.548101999 podStartE2EDuration="8.171490318s" podCreationTimestamp="2025-10-29 00:10:21 +0000 UTC" firstStartedPulling="2025-10-29 00:10:21.813015365 +0000 UTC m=+15.291829253" lastFinishedPulling="2025-10-29 00:10:26.436403684 +0000 UTC m=+19.915217572" observedRunningTime="2025-10-29 00:10:26.869520478 +0000 UTC m=+20.348334378" watchObservedRunningTime="2025-10-29 00:10:29.171490318 +0000 UTC m=+22.650304206" Oct 29 00:10:29.187483 systemd[1]: Created slice kubepods-besteffort-pod49481786_633c_4cdb_b61e_2cf5acb61144.slice - libcontainer container kubepods-besteffort-pod49481786_633c_4cdb_b61e_2cf5acb61144.slice. Oct 29 00:10:29.219384 kubelet[2436]: I1029 00:10:29.219231 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/49481786-633c-4cdb-b61e-2cf5acb61144-data\") pod \"nfs-server-provisioner-0\" (UID: \"49481786-633c-4cdb-b61e-2cf5acb61144\") " pod="default/nfs-server-provisioner-0" Oct 29 00:10:29.220785 kubelet[2436]: I1029 00:10:29.219892 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dlvwt\" (UniqueName: \"kubernetes.io/projected/49481786-633c-4cdb-b61e-2cf5acb61144-kube-api-access-dlvwt\") pod \"nfs-server-provisioner-0\" (UID: \"49481786-633c-4cdb-b61e-2cf5acb61144\") " pod="default/nfs-server-provisioner-0" Oct 29 00:10:29.497923 containerd[1975]: time="2025-10-29T00:10:29.497719583Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:49481786-633c-4cdb-b61e-2cf5acb61144,Namespace:default,Attempt:0,}" Oct 29 00:10:29.521845 (udev-worker)[3153]: Network interface NamePolicy= disabled on kernel command line. Oct 29 00:10:29.527285 systemd-networkd[1579]: veth7be1e07b: Link UP Oct 29 00:10:29.533423 kernel: cni0: port 2(veth7be1e07b) entered blocking state Oct 29 00:10:29.533527 kernel: cni0: port 2(veth7be1e07b) entered disabled state Oct 29 00:10:29.534789 kernel: veth7be1e07b: entered allmulticast mode Oct 29 00:10:29.536233 kernel: veth7be1e07b: entered promiscuous mode Oct 29 00:10:29.552404 kernel: cni0: port 2(veth7be1e07b) entered blocking state Oct 29 00:10:29.552485 kernel: cni0: port 2(veth7be1e07b) entered forwarding state Oct 29 00:10:29.552946 systemd-networkd[1579]: veth7be1e07b: Gained carrier Oct 29 00:10:29.557228 containerd[1975]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e840), "name":"cbr0", "type":"bridge"} Oct 29 00:10:29.557228 containerd[1975]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:10:29.600706 containerd[1975]: {"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-10-29T00:10:29.600646920Z" level=info msg="connecting to shim 5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7" address="unix:///run/containerd/s/713cc332fe815896bc1493d606b07d04f92ed8b2d327199669647f395087f28c" namespace=k8s.io protocol=ttrpc version=3 Oct 29 00:10:29.625770 kubelet[2436]: E1029 00:10:29.625675 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:29.649081 systemd[1]: Started cri-containerd-5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7.scope - libcontainer container 5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7. Oct 29 00:10:29.723675 containerd[1975]: time="2025-10-29T00:10:29.723614388Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:49481786-633c-4cdb-b61e-2cf5acb61144,Namespace:default,Attempt:0,} returns sandbox id \"5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7\"" Oct 29 00:10:29.726886 containerd[1975]: time="2025-10-29T00:10:29.726833412Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 29 00:10:30.626295 kubelet[2436]: E1029 00:10:30.626217 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:31.292995 systemd-networkd[1579]: veth7be1e07b: Gained IPv6LL Oct 29 00:10:31.627057 kubelet[2436]: E1029 00:10:31.627000 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:32.256292 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2797833771.mount: Deactivated successfully. Oct 29 00:10:32.628263 kubelet[2436]: E1029 00:10:32.628185 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:33.628814 kubelet[2436]: E1029 00:10:33.628386 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:34.157145 ntpd[1947]: Listen normally on 11 veth7be1e07b [fe80::bc51:4ff:fe67:836%6]:123 Oct 29 00:10:34.158035 ntpd[1947]: 29 Oct 00:10:34 ntpd[1947]: Listen normally on 11 veth7be1e07b [fe80::bc51:4ff:fe67:836%6]:123 Oct 29 00:10:34.629059 kubelet[2436]: E1029 00:10:34.629017 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:35.285331 containerd[1975]: time="2025-10-29T00:10:35.285252052Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:35.288536 containerd[1975]: time="2025-10-29T00:10:35.288451768Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Oct 29 00:10:35.289586 containerd[1975]: time="2025-10-29T00:10:35.289518808Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:35.295586 containerd[1975]: time="2025-10-29T00:10:35.295506112Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:35.299352 containerd[1975]: time="2025-10-29T00:10:35.299251060Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 5.572125988s" Oct 29 00:10:35.299352 containerd[1975]: time="2025-10-29T00:10:35.299329792Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 29 00:10:35.306793 containerd[1975]: time="2025-10-29T00:10:35.305338660Z" level=info msg="CreateContainer within sandbox \"5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 29 00:10:35.318956 containerd[1975]: time="2025-10-29T00:10:35.318877552Z" level=info msg="Container 461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:35.333148 containerd[1975]: time="2025-10-29T00:10:35.333074032Z" level=info msg="CreateContainer within sandbox \"5fa269946e136bb9300001ba8ceb047edd73558e7deb060ab6e031d45a8750a7\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513\"" Oct 29 00:10:35.334786 containerd[1975]: time="2025-10-29T00:10:35.334216552Z" level=info msg="StartContainer for \"461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513\"" Oct 29 00:10:35.335996 containerd[1975]: time="2025-10-29T00:10:35.335928736Z" level=info msg="connecting to shim 461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513" address="unix:///run/containerd/s/713cc332fe815896bc1493d606b07d04f92ed8b2d327199669647f395087f28c" protocol=ttrpc version=3 Oct 29 00:10:35.375050 systemd[1]: Started cri-containerd-461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513.scope - libcontainer container 461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513. Oct 29 00:10:35.438037 containerd[1975]: time="2025-10-29T00:10:35.437154077Z" level=info msg="StartContainer for \"461fa8905d72f5d27535cff91de5ef9b1e9397c97de80914c58ed5e8d8f2a513\" returns successfully" Oct 29 00:10:35.630487 kubelet[2436]: E1029 00:10:35.630411 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:35.913104 kubelet[2436]: I1029 00:10:35.912924 2436 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.337956239 podStartE2EDuration="6.912901747s" podCreationTimestamp="2025-10-29 00:10:29 +0000 UTC" firstStartedPulling="2025-10-29 00:10:29.725841756 +0000 UTC m=+23.204655644" lastFinishedPulling="2025-10-29 00:10:35.300787264 +0000 UTC m=+28.779601152" observedRunningTime="2025-10-29 00:10:35.911398795 +0000 UTC m=+29.390212683" watchObservedRunningTime="2025-10-29 00:10:35.912901747 +0000 UTC m=+29.391715635" Oct 29 00:10:36.631417 kubelet[2436]: E1029 00:10:36.631337 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:37.632288 kubelet[2436]: E1029 00:10:37.632241 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:38.633752 kubelet[2436]: E1029 00:10:38.633683 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:39.634812 kubelet[2436]: E1029 00:10:39.634732 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:40.635548 kubelet[2436]: E1029 00:10:40.635475 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:40.802848 update_engine[1956]: I20251029 00:10:40.802785 1956 update_attempter.cc:509] Updating boot flags... Oct 29 00:10:40.874928 systemd[1]: Created slice kubepods-besteffort-pode028309f_60fa_4cbd_a6d4_e26d09dce8dc.slice - libcontainer container kubepods-besteffort-pode028309f_60fa_4cbd_a6d4_e26d09dce8dc.slice. Oct 29 00:10:40.898482 kubelet[2436]: I1029 00:10:40.898241 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-b4e98f99-118b-442e-9944-6c4965adc5d0\" (UniqueName: \"kubernetes.io/nfs/e028309f-60fa-4cbd-a6d4-e26d09dce8dc-pvc-b4e98f99-118b-442e-9944-6c4965adc5d0\") pod \"test-pod-1\" (UID: \"e028309f-60fa-4cbd-a6d4-e26d09dce8dc\") " pod="default/test-pod-1" Oct 29 00:10:40.898482 kubelet[2436]: I1029 00:10:40.898346 2436 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-z9t6d\" (UniqueName: \"kubernetes.io/projected/e028309f-60fa-4cbd-a6d4-e26d09dce8dc-kube-api-access-z9t6d\") pod \"test-pod-1\" (UID: \"e028309f-60fa-4cbd-a6d4-e26d09dce8dc\") " pod="default/test-pod-1" Oct 29 00:10:41.074952 kernel: netfs: FS-Cache loaded Oct 29 00:10:41.167776 kernel: RPC: Registered named UNIX socket transport module. Oct 29 00:10:41.167898 kernel: RPC: Registered udp transport module. Oct 29 00:10:41.169437 kernel: RPC: Registered tcp transport module. Oct 29 00:10:41.173850 kernel: RPC: Registered tcp-with-tls transport module. Oct 29 00:10:41.173971 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 29 00:10:41.622527 kernel: NFS: Registering the id_resolver key type Oct 29 00:10:41.622721 kernel: Key type id_resolver registered Oct 29 00:10:41.622824 kernel: Key type id_legacy registered Oct 29 00:10:41.636759 kubelet[2436]: E1029 00:10:41.635788 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:41.670367 nfsidmap[3597]: 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 Oct 29 00:10:41.672010 nfsidmap[3597]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 00:10:41.676648 nfsidmap[3598]: 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 Oct 29 00:10:41.677450 nfsidmap[3598]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 00:10:41.695554 nfsrahead[3600]: setting /var/lib/kubelet/pods/e028309f-60fa-4cbd-a6d4-e26d09dce8dc/volumes/kubernetes.io~nfs/pvc-b4e98f99-118b-442e-9944-6c4965adc5d0 readahead to 128 Oct 29 00:10:41.806204 containerd[1975]: time="2025-10-29T00:10:41.806050980Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e028309f-60fa-4cbd-a6d4-e26d09dce8dc,Namespace:default,Attempt:0,}" Oct 29 00:10:41.872563 (udev-worker)[3412]: Network interface NamePolicy= disabled on kernel command line. Oct 29 00:10:41.874913 systemd-networkd[1579]: vethd74afccb: Link UP Oct 29 00:10:41.879562 kernel: cni0: port 3(vethd74afccb) entered blocking state Oct 29 00:10:41.879716 kernel: cni0: port 3(vethd74afccb) entered disabled state Oct 29 00:10:41.883325 kernel: vethd74afccb: entered allmulticast mode Oct 29 00:10:41.883473 kernel: vethd74afccb: entered promiscuous mode Oct 29 00:10:41.896185 kernel: cni0: port 3(vethd74afccb) entered blocking state Oct 29 00:10:41.896341 kernel: cni0: port 3(vethd74afccb) entered forwarding state Oct 29 00:10:41.896396 systemd-networkd[1579]: vethd74afccb: Gained carrier Oct 29 00:10:41.902549 containerd[1975]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 29 00:10:41.902549 containerd[1975]: delegateAdd: netconf sent to delegate plugin: Oct 29 00:10:41.994847 containerd[1975]: {"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-10-29T00:10:41.993853765Z" level=info msg="connecting to shim f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29" address="unix:///run/containerd/s/b048ae799ba9f1b21c786bb541214db9ba21ba6352d7eecfedd1929196269f2d" namespace=k8s.io protocol=ttrpc version=3 Oct 29 00:10:42.071340 systemd[1]: Started cri-containerd-f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29.scope - libcontainer container f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29. Oct 29 00:10:42.277550 containerd[1975]: time="2025-10-29T00:10:42.277284431Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e028309f-60fa-4cbd-a6d4-e26d09dce8dc,Namespace:default,Attempt:0,} returns sandbox id \"f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29\"" Oct 29 00:10:42.281446 containerd[1975]: time="2025-10-29T00:10:42.281315207Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 00:10:42.579489 containerd[1975]: time="2025-10-29T00:10:42.579021816Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 00:10:42.580433 containerd[1975]: time="2025-10-29T00:10:42.580372476Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 29 00:10:42.585683 containerd[1975]: time="2025-10-29T00:10:42.585624252Z" 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 304.228213ms" Oct 29 00:10:42.586036 containerd[1975]: time="2025-10-29T00:10:42.585683688Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 00:10:42.591191 containerd[1975]: time="2025-10-29T00:10:42.591120252Z" level=info msg="CreateContainer within sandbox \"f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 29 00:10:42.602621 containerd[1975]: time="2025-10-29T00:10:42.601327764Z" level=info msg="Container f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f: CDI devices from CRI Config.CDIDevices: []" Oct 29 00:10:42.623345 containerd[1975]: time="2025-10-29T00:10:42.623296728Z" level=info msg="CreateContainer within sandbox \"f69a53c228ba82a837ff4daed15725adb5e44ae369714620e1a1f933805efa29\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f\"" Oct 29 00:10:42.624824 containerd[1975]: time="2025-10-29T00:10:42.624783901Z" level=info msg="StartContainer for \"f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f\"" Oct 29 00:10:42.627608 containerd[1975]: time="2025-10-29T00:10:42.627510805Z" level=info msg="connecting to shim f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f" address="unix:///run/containerd/s/b048ae799ba9f1b21c786bb541214db9ba21ba6352d7eecfedd1929196269f2d" protocol=ttrpc version=3 Oct 29 00:10:42.640308 kubelet[2436]: E1029 00:10:42.640223 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:42.670068 systemd[1]: Started cri-containerd-f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f.scope - libcontainer container f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f. Oct 29 00:10:42.725485 containerd[1975]: time="2025-10-29T00:10:42.725400457Z" level=info msg="StartContainer for \"f9decec38641c6b70fe77274efd57df37b35f8b8af3c8249e15a7208603de75f\" returns successfully" Oct 29 00:10:42.930926 kubelet[2436]: I1029 00:10:42.930832 2436 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=13.624201817 podStartE2EDuration="13.930811814s" podCreationTimestamp="2025-10-29 00:10:29 +0000 UTC" firstStartedPulling="2025-10-29 00:10:42.279970079 +0000 UTC m=+35.758783967" lastFinishedPulling="2025-10-29 00:10:42.586580076 +0000 UTC m=+36.065393964" observedRunningTime="2025-10-29 00:10:42.930213614 +0000 UTC m=+36.409027514" watchObservedRunningTime="2025-10-29 00:10:42.930811814 +0000 UTC m=+36.409625738" Oct 29 00:10:43.517087 systemd-networkd[1579]: vethd74afccb: Gained IPv6LL Oct 29 00:10:43.641344 kubelet[2436]: E1029 00:10:43.641279 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:44.641933 kubelet[2436]: E1029 00:10:44.641871 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:45.642247 kubelet[2436]: E1029 00:10:45.642181 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:46.643188 kubelet[2436]: E1029 00:10:46.643136 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:47.157044 ntpd[1947]: Listen normally on 12 vethd74afccb [fe80::4452:3ff:fe9d:433b%7]:123 Oct 29 00:10:47.157650 ntpd[1947]: 29 Oct 00:10:47 ntpd[1947]: Listen normally on 12 vethd74afccb [fe80::4452:3ff:fe9d:433b%7]:123 Oct 29 00:10:47.644377 kubelet[2436]: E1029 00:10:47.644305 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:48.604650 kubelet[2436]: E1029 00:10:48.604577 2436 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:48.645356 kubelet[2436]: E1029 00:10:48.645297 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:49.646355 kubelet[2436]: E1029 00:10:49.646288 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:50.647238 kubelet[2436]: E1029 00:10:50.647172 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:51.647474 kubelet[2436]: E1029 00:10:51.647405 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:52.648194 kubelet[2436]: E1029 00:10:52.648111 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:53.648540 kubelet[2436]: E1029 00:10:53.648473 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:54.649573 kubelet[2436]: E1029 00:10:54.649494 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:55.650643 kubelet[2436]: E1029 00:10:55.650578 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:56.651709 kubelet[2436]: E1029 00:10:56.651635 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:57.651860 kubelet[2436]: E1029 00:10:57.651799 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:58.652270 kubelet[2436]: E1029 00:10:58.652206 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:10:59.652905 kubelet[2436]: E1029 00:10:59.652820 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:00.653247 kubelet[2436]: E1029 00:11:00.653182 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:01.654113 kubelet[2436]: E1029 00:11:01.654047 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:02.654524 kubelet[2436]: E1029 00:11:02.654461 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:03.654683 kubelet[2436]: E1029 00:11:03.654604 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:04.655357 kubelet[2436]: E1029 00:11:04.655265 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:05.656335 kubelet[2436]: E1029 00:11:05.656291 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:06.658176 kubelet[2436]: E1029 00:11:06.658100 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:07.658938 kubelet[2436]: E1029 00:11:07.658868 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:08.604729 kubelet[2436]: E1029 00:11:08.604651 2436 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:08.659898 kubelet[2436]: E1029 00:11:08.659830 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:09.660733 kubelet[2436]: E1029 00:11:09.660669 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:09.679205 kubelet[2436]: E1029 00:11:09.678908 2436 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 29 00:11:10.660885 kubelet[2436]: E1029 00:11:10.660815 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:11.661366 kubelet[2436]: E1029 00:11:11.661300 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:12.661884 kubelet[2436]: E1029 00:11:12.661816 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:13.662301 kubelet[2436]: E1029 00:11:13.662232 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:14.662667 kubelet[2436]: E1029 00:11:14.662593 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:15.662814 kubelet[2436]: E1029 00:11:15.662725 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:16.663772 kubelet[2436]: E1029 00:11:16.663702 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:17.664902 kubelet[2436]: E1029 00:11:17.664821 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:18.665830 kubelet[2436]: E1029 00:11:18.665662 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:19.666654 kubelet[2436]: E1029 00:11:19.666584 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:19.680015 kubelet[2436]: E1029 00:11:19.679941 2436 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 29 00:11:20.667526 kubelet[2436]: E1029 00:11:20.667445 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:21.668661 kubelet[2436]: E1029 00:11:21.668586 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:22.669004 kubelet[2436]: E1029 00:11:22.668935 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:23.669777 kubelet[2436]: E1029 00:11:23.669667 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:24.670779 kubelet[2436]: E1029 00:11:24.670421 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:25.670818 kubelet[2436]: E1029 00:11:25.670723 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:26.670984 kubelet[2436]: E1029 00:11:26.670905 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:27.671378 kubelet[2436]: E1029 00:11:27.671324 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:28.604140 kubelet[2436]: E1029 00:11:28.604085 2436 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:28.672847 kubelet[2436]: E1029 00:11:28.672785 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:29.673612 kubelet[2436]: E1029 00:11:29.673542 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:29.680924 kubelet[2436]: E1029 00:11:29.680852 2436 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": context deadline exceeded" Oct 29 00:11:30.674706 kubelet[2436]: E1029 00:11:30.674645 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:31.501816 kubelet[2436]: E1029 00:11:31.501264 2436 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": unexpected EOF" Oct 29 00:11:31.512160 kubelet[2436]: E1029 00:11:31.512088 2436 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": read tcp 172.31.28.41:47048->172.31.29.160:6443: read: connection reset by peer" Oct 29 00:11:31.512730 kubelet[2436]: I1029 00:11:31.512687 2436 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Oct 29 00:11:31.513813 kubelet[2436]: E1029 00:11:31.513697 2436 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": dial tcp 172.31.29.160:6443: connect: connection refused" interval="200ms" Oct 29 00:11:31.675773 kubelet[2436]: E1029 00:11:31.675663 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:31.715475 kubelet[2436]: E1029 00:11:31.715390 2436 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": dial tcp 172.31.29.160:6443: connect: connection refused" interval="400ms" Oct 29 00:11:32.116711 kubelet[2436]: E1029 00:11:32.116609 2436 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": dial tcp 172.31.29.160:6443: connect: connection refused" interval="800ms" Oct 29 00:11:32.675951 kubelet[2436]: E1029 00:11:32.675861 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:33.676327 kubelet[2436]: E1029 00:11:33.676233 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:34.677211 kubelet[2436]: E1029 00:11:34.677133 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:35.677603 kubelet[2436]: E1029 00:11:35.677521 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:36.678503 kubelet[2436]: E1029 00:11:36.678433 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:37.679521 kubelet[2436]: E1029 00:11:37.679452 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:38.680341 kubelet[2436]: E1029 00:11:38.680261 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:39.680890 kubelet[2436]: E1029 00:11:39.680819 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:40.682089 kubelet[2436]: E1029 00:11:40.682009 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:41.682647 kubelet[2436]: E1029 00:11:41.682568 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:42.683604 kubelet[2436]: E1029 00:11:42.683536 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:42.918150 kubelet[2436]: E1029 00:11:42.918063 2436 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="1.6s" Oct 29 00:11:43.684142 kubelet[2436]: E1029 00:11:43.684078 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:44.685016 kubelet[2436]: E1029 00:11:44.684942 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:45.685269 kubelet[2436]: E1029 00:11:45.685222 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:46.685823 kubelet[2436]: E1029 00:11:46.685729 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:47.686616 kubelet[2436]: E1029 00:11:47.686487 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:48.604648 kubelet[2436]: E1029 00:11:48.604588 2436 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:48.687565 kubelet[2436]: E1029 00:11:48.687502 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:49.688394 kubelet[2436]: E1029 00:11:49.688343 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:50.689873 kubelet[2436]: E1029 00:11:50.689812 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:51.690769 kubelet[2436]: E1029 00:11:51.690697 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:52.691675 kubelet[2436]: E1029 00:11:52.691597 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:53.692197 kubelet[2436]: E1029 00:11:53.692110 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 00:11:54.519289 kubelet[2436]: E1029 00:11:54.519221 2436 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.160:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.41?timeout=10s\": context deadline exceeded" interval="3.2s" Oct 29 00:11:54.692994 kubelet[2436]: E1029 00:11:54.692928 2436 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"