Oct 27 10:56:36.260739 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Oct 27 10:56:36.260841 kernel: Linux version 6.6.160-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Tue Oct 27 09:30:16 -00 2026 Oct 27 10:56:36.260893 kernel: KASLR disabled due to lack of seed Oct 27 10:56:36.260913 kernel: efi: EFI v2.7 by EDK II Oct 27 10:56:36.260931 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7aabca98 MEMRESERVE=0x7852ee18 Oct 27 10:56:36.260947 kernel: ACPI: Early table checksum verification disabled Oct 27 10:56:36.260965 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Oct 27 10:56:36.260981 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Oct 27 10:56:36.260998 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Oct 27 10:56:36.261013 kernel: ACPI: DSDT 0x0000000078640000 0013D2 (v02 AMAZON AMZNDSDT 00000001 AMZN 00000001) Oct 27 10:56:36.261035 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Oct 27 10:56:36.261051 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Oct 27 10:56:36.261067 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Oct 27 10:56:36.261083 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Oct 27 10:56:36.261102 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Oct 27 10:56:36.261123 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Oct 27 10:56:36.261140 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Oct 27 10:56:36.261157 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Oct 27 10:56:36.261174 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Oct 27 10:56:36.261223 kernel: printk: bootconsole [uart0] enabled Oct 27 10:56:36.261244 kernel: NUMA: Failed to initialise from firmware Oct 27 10:56:36.261262 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Oct 27 10:56:36.261279 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Oct 27 10:56:36.261296 kernel: Zone ranges: Oct 27 10:56:36.261313 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Oct 27 10:56:36.261330 kernel: DMA32 empty Oct 27 10:56:36.261373 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Oct 27 10:56:36.261394 kernel: Movable zone start for each node Oct 27 10:56:36.261411 kernel: Early memory node ranges Oct 27 10:56:36.261428 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Oct 27 10:56:36.261444 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Oct 27 10:56:36.261461 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Oct 27 10:56:36.261478 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Oct 27 10:56:36.261494 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Oct 27 10:56:36.261511 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Oct 27 10:56:36.261528 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Oct 27 10:56:36.261544 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Oct 27 10:56:36.261561 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Oct 27 10:56:36.261583 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Oct 27 10:56:36.261600 kernel: psci: probing for conduit method from ACPI. Oct 27 10:56:36.261624 kernel: psci: PSCIv1.0 detected in firmware. Oct 27 10:56:36.261642 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 10:56:36.261660 kernel: psci: Trusted OS migration not required Oct 27 10:56:36.261681 kernel: psci: SMC Calling Convention v1.1 Oct 27 10:56:36.261699 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000001) Oct 27 10:56:36.261717 kernel: percpu: Embedded 30 pages/cpu s85800 r8192 d28888 u122880 Oct 27 10:56:36.261735 kernel: pcpu-alloc: s85800 r8192 d28888 u122880 alloc=30*4096 Oct 27 10:56:36.261753 kernel: pcpu-alloc: [0] 0 [0] 1 Oct 27 10:56:36.261770 kernel: Detected PIPT I-cache on CPU0 Oct 27 10:56:36.261788 kernel: CPU features: detected: GIC system register CPU interface Oct 27 10:56:36.261805 kernel: CPU features: detected: Spectre-v2 Oct 27 10:56:36.261823 kernel: CPU features: detected: Spectre-v3a Oct 27 10:56:36.261840 kernel: CPU features: detected: Spectre-BHB Oct 27 10:56:36.261858 kernel: CPU features: detected: ARM erratum 1742098 Oct 27 10:56:36.261879 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 27 10:56:36.261897 kernel: alternatives: applying boot alternatives Oct 27 10:56:36.261918 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=0c9434fd3cf822a8cd06ec53b4aaba0db7ce0576443fb8035e7cb7cc9f22d70b Oct 27 10:56:36.261936 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 10:56:36.261954 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 10:56:36.261971 kernel: Fallback order for Node 0: 0 Oct 27 10:56:36.261989 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Oct 27 10:56:36.262007 kernel: Policy zone: Normal Oct 27 10:56:36.262024 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 10:56:36.262042 kernel: software IO TLB: area num 2. Oct 27 10:56:36.262059 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Oct 27 10:56:36.262082 kernel: Memory: 3819968K/4030464K available (10368K kernel code, 2180K rwdata, 8148K rodata, 39424K init, 901K bss, 210496K reserved, 0K cma-reserved) Oct 27 10:56:36.262100 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 27 10:56:36.262118 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 10:56:36.262136 kernel: rcu: RCU event tracing is enabled. Oct 27 10:56:36.262155 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 27 10:56:36.262173 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 10:56:36.265237 kernel: Tracing variant of Tasks RCU enabled. Oct 27 10:56:36.265276 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 10:56:36.265295 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 27 10:56:36.265314 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 10:56:36.265333 kernel: GICv3: 96 SPIs implemented Oct 27 10:56:36.265378 kernel: GICv3: 0 Extended SPIs implemented Oct 27 10:56:36.265398 kernel: Root IRQ handler: gic_handle_irq Oct 27 10:56:36.265416 kernel: GICv3: GICv3 features: 16 PPIs Oct 27 10:56:36.265434 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Oct 27 10:56:36.265451 kernel: ITS [mem 0x10080000-0x1009ffff] Oct 27 10:56:36.265469 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Oct 27 10:56:36.265487 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Oct 27 10:56:36.265505 kernel: GICv3: using LPI property table @0x00000004000d0000 Oct 27 10:56:36.265523 kernel: ITS: Using hypervisor restricted LPI range [128] Oct 27 10:56:36.265540 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Oct 27 10:56:36.265558 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 27 10:56:36.265576 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Oct 27 10:56:36.265600 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Oct 27 10:56:36.265618 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Oct 27 10:56:36.265636 kernel: Console: colour dummy device 80x25 Oct 27 10:56:36.265654 kernel: printk: console [tty1] enabled Oct 27 10:56:36.265672 kernel: ACPI: Core revision 20230628 Oct 27 10:56:36.265691 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Oct 27 10:56:36.265709 kernel: pid_max: default: 32768 minimum: 301 Oct 27 10:56:36.265727 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 27 10:56:36.265745 kernel: landlock: Up and running. Oct 27 10:56:36.265767 kernel: SELinux: Initializing. Oct 27 10:56:36.265786 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 10:56:36.265804 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 10:56:36.265822 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 27 10:56:36.265841 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 27 10:56:36.265859 kernel: rcu: Hierarchical SRCU implementation. Oct 27 10:56:36.265877 kernel: rcu: Max phase no-delay instances is 400. Oct 27 10:56:36.265896 kernel: Platform MSI: ITS@0x10080000 domain created Oct 27 10:56:36.265914 kernel: PCI/MSI: ITS@0x10080000 domain created Oct 27 10:56:36.265937 kernel: Remapping and enabling EFI services. Oct 27 10:56:36.265956 kernel: smp: Bringing up secondary CPUs ... Oct 27 10:56:36.265974 kernel: Detected PIPT I-cache on CPU1 Oct 27 10:56:36.265992 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Oct 27 10:56:36.266010 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Oct 27 10:56:36.266028 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Oct 27 10:56:36.266046 kernel: smp: Brought up 1 node, 2 CPUs Oct 27 10:56:36.266064 kernel: SMP: Total of 2 processors activated. Oct 27 10:56:36.266082 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 10:56:36.266103 kernel: CPU features: detected: 32-bit EL1 Support Oct 27 10:56:36.266122 kernel: CPU features: detected: CRC32 instructions Oct 27 10:56:36.266140 kernel: CPU: All CPU(s) started at EL1 Oct 27 10:56:36.266169 kernel: alternatives: applying system-wide alternatives Oct 27 10:56:36.266217 kernel: devtmpfs: initialized Oct 27 10:56:36.266241 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 10:56:36.266260 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 27 10:56:36.266279 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 10:56:36.266298 kernel: SMBIOS 3.0.0 present. Oct 27 10:56:36.266323 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Oct 27 10:56:36.266342 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 27 10:56:36.266361 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 10:56:36.266380 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 10:56:36.266399 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 10:56:36.266418 kernel: audit: initializing netlink subsys (disabled) Oct 27 10:56:36.266437 kernel: audit: type=2000 audit(0.296:1): state=initialized audit_enabled=0 res=1 Oct 27 10:56:36.266456 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 10:56:36.266481 kernel: cpuidle: using governor menu Oct 27 10:56:36.266500 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 10:56:36.266519 kernel: ASID allocator initialised with 65536 entries Oct 27 10:56:36.266538 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 10:56:36.266557 kernel: Serial: AMBA PL011 UART driver Oct 27 10:56:36.266575 kernel: Modules: 17456 pages in range for non-PLT usage Oct 27 10:56:36.266594 kernel: Modules: 508976 pages in range for PLT usage Oct 27 10:56:36.266613 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 10:56:36.266632 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 27 10:56:36.266654 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 10:56:36.266674 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 27 10:56:36.266692 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 10:56:36.266711 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 27 10:56:36.266730 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 10:56:36.266748 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 27 10:56:36.266767 kernel: ACPI: Added _OSI(Module Device) Oct 27 10:56:36.266786 kernel: ACPI: Added _OSI(Processor Device) Oct 27 10:56:36.266805 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 10:56:36.266827 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 10:56:36.266847 kernel: ACPI: Interpreter enabled Oct 27 10:56:36.266865 kernel: ACPI: Using GIC for interrupt routing Oct 27 10:56:36.266885 kernel: ACPI: MCFG table detected, 1 entries Oct 27 10:56:36.266904 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00]) Oct 27 10:56:36.268473 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 10:56:36.268706 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 10:56:36.268921 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 10:56:36.269137 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x200fffff] reserved by PNP0C02:00 Oct 27 10:56:36.270500 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x200fffff] for [bus 00] Oct 27 10:56:36.270544 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Oct 27 10:56:36.270565 kernel: acpiphp: Slot [1] registered Oct 27 10:56:36.270585 kernel: acpiphp: Slot [2] registered Oct 27 10:56:36.270604 kernel: acpiphp: Slot [3] registered Oct 27 10:56:36.270623 kernel: acpiphp: Slot [4] registered Oct 27 10:56:36.270641 kernel: acpiphp: Slot [5] registered Oct 27 10:56:36.270669 kernel: acpiphp: Slot [6] registered Oct 27 10:56:36.270688 kernel: acpiphp: Slot [7] registered Oct 27 10:56:36.270706 kernel: acpiphp: Slot [8] registered Oct 27 10:56:36.270725 kernel: acpiphp: Slot [9] registered Oct 27 10:56:36.270744 kernel: acpiphp: Slot [10] registered Oct 27 10:56:36.270762 kernel: acpiphp: Slot [11] registered Oct 27 10:56:36.270795 kernel: acpiphp: Slot [12] registered Oct 27 10:56:36.270817 kernel: acpiphp: Slot [13] registered Oct 27 10:56:36.270836 kernel: acpiphp: Slot [14] registered Oct 27 10:56:36.270855 kernel: acpiphp: Slot [15] registered Oct 27 10:56:36.270879 kernel: acpiphp: Slot [16] registered Oct 27 10:56:36.270898 kernel: acpiphp: Slot [17] registered Oct 27 10:56:36.270916 kernel: acpiphp: Slot [18] registered Oct 27 10:56:36.270935 kernel: acpiphp: Slot [19] registered Oct 27 10:56:36.270953 kernel: acpiphp: Slot [20] registered Oct 27 10:56:36.270972 kernel: acpiphp: Slot [21] registered Oct 27 10:56:36.270990 kernel: acpiphp: Slot [22] registered Oct 27 10:56:36.271009 kernel: acpiphp: Slot [23] registered Oct 27 10:56:36.271028 kernel: acpiphp: Slot [24] registered Oct 27 10:56:36.271051 kernel: acpiphp: Slot [25] registered Oct 27 10:56:36.271070 kernel: acpiphp: Slot [26] registered Oct 27 10:56:36.271088 kernel: acpiphp: Slot [27] registered Oct 27 10:56:36.271106 kernel: acpiphp: Slot [28] registered Oct 27 10:56:36.271125 kernel: acpiphp: Slot [29] registered Oct 27 10:56:36.271143 kernel: acpiphp: Slot [30] registered Oct 27 10:56:36.271161 kernel: acpiphp: Slot [31] registered Oct 27 10:56:36.271180 kernel: PCI host bridge to bus 0000:00 Oct 27 10:56:36.277611 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Oct 27 10:56:36.277845 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 10:56:36.278039 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Oct 27 10:56:36.279388 kernel: pci_bus 0000:00: root bus resource [bus 00] Oct 27 10:56:36.279661 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Oct 27 10:56:36.279890 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Oct 27 10:56:36.280138 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80118000-0x80118fff] Oct 27 10:56:36.280414 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Oct 27 10:56:36.280634 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80114000-0x80117fff] Oct 27 10:56:36.280854 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 27 10:56:36.281084 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Oct 27 10:56:36.286029 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80110000-0x80113fff] Oct 27 10:56:36.286382 kernel: pci 0000:00:05.0: BAR 2 [mem 0x80000000-0x800fffff pref] Oct 27 10:56:36.286641 kernel: pci 0000:00:05.0: BAR 4 [mem 0x80100000-0x8010ffff] Oct 27 10:56:36.286900 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 27 10:56:36.287107 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Oct 27 10:56:36.287371 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 10:56:36.292436 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Oct 27 10:56:36.292483 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 27 10:56:36.292504 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 27 10:56:36.292524 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 27 10:56:36.292544 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 27 10:56:36.292572 kernel: iommu: Default domain type: Translated Oct 27 10:56:36.292592 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 27 10:56:36.292611 kernel: efivars: Registered efivars operations Oct 27 10:56:36.292630 kernel: vgaarb: loaded Oct 27 10:56:36.292649 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 10:56:36.292668 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 10:56:36.292687 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 10:56:36.292706 kernel: pnp: PnP ACPI init Oct 27 10:56:36.292936 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Oct 27 10:56:36.292971 kernel: pnp: PnP ACPI: found 1 devices Oct 27 10:56:36.292990 kernel: NET: Registered PF_INET protocol family Oct 27 10:56:36.293009 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 10:56:36.293029 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 10:56:36.293048 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 27 10:56:36.293068 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 10:56:36.293087 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 27 10:56:36.293106 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 10:56:36.293129 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 10:56:36.293149 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 10:56:36.293168 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 27 10:56:36.293227 kernel: PCI: CLS 0 bytes, default 64 Oct 27 10:56:36.293254 kernel: kvm [1]: HYP mode not available Oct 27 10:56:36.293274 kernel: Initialise system trusted keyrings Oct 27 10:56:36.293293 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 10:56:36.293312 kernel: Key type asymmetric registered Oct 27 10:56:36.293331 kernel: Asymmetric key parser 'x509' registered Oct 27 10:56:36.293376 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 27 10:56:36.293398 kernel: io scheduler mq-deadline registered Oct 27 10:56:36.293418 kernel: io scheduler kyber registered Oct 27 10:56:36.293437 kernel: io scheduler bfq registered Oct 27 10:56:36.293670 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Oct 27 10:56:36.293699 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 10:56:36.293719 kernel: ACPI: button: Power Button [PWRB] Oct 27 10:56:36.293739 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Oct 27 10:56:36.293757 kernel: ACPI: button: Sleep Button [SLPB] Oct 27 10:56:36.293783 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 10:56:36.293802 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Oct 27 10:56:36.294020 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Oct 27 10:56:36.294047 kernel: printk: console [ttyS0] disabled Oct 27 10:56:36.294067 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Oct 27 10:56:36.294086 kernel: printk: console [ttyS0] enabled Oct 27 10:56:36.294104 kernel: printk: bootconsole [uart0] disabled Oct 27 10:56:36.294123 kernel: thunder_xcv, ver 1.0 Oct 27 10:56:36.294141 kernel: thunder_bgx, ver 1.0 Oct 27 10:56:36.294166 kernel: nicpf, ver 1.0 Oct 27 10:56:36.294185 kernel: nicvf, ver 1.0 Oct 27 10:56:36.296274 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 10:56:36.296490 kernel: rtc-efi rtc-efi.0: setting system clock to 2026-10-27T10:56:35 UTC (1793098595) Oct 27 10:56:36.296517 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 10:56:36.296538 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Oct 27 10:56:36.296560 kernel: watchdog: Delayed init of the lockup detector failed: -19 Oct 27 10:56:36.296579 kernel: watchdog: Hard watchdog permanently disabled Oct 27 10:56:36.296608 kernel: NET: Registered PF_INET6 protocol family Oct 27 10:56:36.296627 kernel: Segment Routing with IPv6 Oct 27 10:56:36.296646 kernel: In-situ OAM (IOAM) with IPv6 Oct 27 10:56:36.296665 kernel: NET: Registered PF_PACKET protocol family Oct 27 10:56:36.296684 kernel: Key type dns_resolver registered Oct 27 10:56:36.296703 kernel: registered taskstats version 1 Oct 27 10:56:36.296722 kernel: Loading compiled-in X.509 certificates Oct 27 10:56:36.296741 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.160-flatcar: 93df8a8833693372867a5648dac3dbd6374b975d' Oct 27 10:56:36.296760 kernel: Key type .fscrypt registered Oct 27 10:56:36.296799 kernel: Key type fscrypt-provisioning registered Oct 27 10:56:36.296820 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 10:56:36.296840 kernel: ima: Allocated hash algorithm: sha1 Oct 27 10:56:36.296859 kernel: ima: No architecture policies found Oct 27 10:56:36.296878 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 27 10:56:36.296897 kernel: clk: Disabling unused clocks Oct 27 10:56:36.296915 kernel: Freeing unused kernel memory: 39424K Oct 27 10:56:36.296934 kernel: Run /init as init process Oct 27 10:56:36.296953 kernel: with arguments: Oct 27 10:56:36.296977 kernel: /init Oct 27 10:56:36.296997 kernel: with environment: Oct 27 10:56:36.297015 kernel: HOME=/ Oct 27 10:56:36.297034 kernel: TERM=linux Oct 27 10:56:36.297057 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 27 10:56:36.297082 systemd[1]: Detected virtualization amazon. Oct 27 10:56:36.297103 systemd[1]: Detected architecture arm64. Oct 27 10:56:36.297123 systemd[1]: Running in initrd. Oct 27 10:56:36.297148 systemd[1]: No hostname configured, using default hostname. Oct 27 10:56:36.297168 systemd[1]: Hostname set to . Oct 27 10:56:36.297227 systemd[1]: Initializing machine ID from VM UUID. Oct 27 10:56:36.297254 systemd[1]: Queued start job for default target initrd.target. Oct 27 10:56:36.297275 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 10:56:36.297296 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 10:56:36.297319 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 27 10:56:36.297341 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 10:56:36.297390 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 27 10:56:36.297413 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 27 10:56:36.297437 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 27 10:56:36.297459 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 27 10:56:36.297480 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 10:56:36.297501 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 10:56:36.297528 systemd[1]: Reached target paths.target - Path Units. Oct 27 10:56:36.297549 systemd[1]: Reached target slices.target - Slice Units. Oct 27 10:56:36.297570 systemd[1]: Reached target swap.target - Swaps. Oct 27 10:56:36.297590 systemd[1]: Reached target timers.target - Timer Units. Oct 27 10:56:36.297611 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 10:56:36.297632 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 10:56:36.297653 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 27 10:56:36.297673 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 27 10:56:36.297695 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 10:56:36.297721 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 10:56:36.297742 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 10:56:36.297763 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 10:56:36.297783 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 27 10:56:36.297804 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 10:56:36.297825 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 27 10:56:36.297846 systemd[1]: Starting systemd-fsck-usr.service... Oct 27 10:56:36.297867 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 10:56:36.297888 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 10:56:36.297913 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 10:56:36.297934 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 27 10:56:36.297955 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 10:56:36.297976 systemd[1]: Finished systemd-fsck-usr.service. Oct 27 10:56:36.298039 systemd-journald[252]: Collecting audit messages is disabled. Oct 27 10:56:36.298090 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 10:56:36.298112 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 27 10:56:36.298131 kernel: Bridge firewalling registered Oct 27 10:56:36.298156 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 10:56:36.298177 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 10:56:36.298262 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 10:56:36.298286 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 27 10:56:36.298307 systemd-journald[252]: Journal started Oct 27 10:56:36.298346 systemd-journald[252]: Runtime Journal (/run/log/journal/ec29d260b227e507563c657846f4c8f9) is 8.0M, max 75.3M, 67.3M free. Oct 27 10:56:36.220801 systemd-modules-load[253]: Inserted module 'overlay' Oct 27 10:56:36.269326 systemd-modules-load[253]: Inserted module 'br_netfilter' Oct 27 10:56:36.321414 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 10:56:36.327936 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 10:56:36.328378 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 10:56:36.349583 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 10:56:36.371517 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 10:56:36.375556 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 10:56:36.389709 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 10:56:36.409433 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 27 10:56:36.413860 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 10:56:36.431546 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 10:56:36.441959 dracut-cmdline[287]: dracut-dracut-053 Oct 27 10:56:36.448183 dracut-cmdline[287]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=0c9434fd3cf822a8cd06ec53b4aaba0db7ce0576443fb8035e7cb7cc9f22d70b Oct 27 10:56:36.519018 systemd-resolved[294]: Positive Trust Anchors: Oct 27 10:56:36.519636 systemd-resolved[294]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 10:56:36.519701 systemd-resolved[294]: 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 27 10:56:36.619236 kernel: SCSI subsystem initialized Oct 27 10:56:36.627337 kernel: Loading iSCSI transport class v2.0-870. Oct 27 10:56:36.640323 kernel: iscsi: registered transport (tcp) Oct 27 10:56:36.663306 kernel: iscsi: registered transport (qla4xxx) Oct 27 10:56:36.663373 kernel: QLogic iSCSI HBA Driver Oct 27 10:56:36.759338 kernel: random: crng init done Oct 27 10:56:36.757500 systemd-resolved[294]: Defaulting to hostname 'linux'. Oct 27 10:56:36.761887 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 10:56:36.764512 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 10:56:36.791066 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 27 10:56:36.804470 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 27 10:56:36.841613 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 27 10:56:36.841686 kernel: device-mapper: uevent: version 1.0.3 Oct 27 10:56:36.841726 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 27 10:56:36.908253 kernel: raid6: neonx8 gen() 6687 MB/s Oct 27 10:56:36.925226 kernel: raid6: neonx4 gen() 6492 MB/s Oct 27 10:56:36.942223 kernel: raid6: neonx2 gen() 5434 MB/s Oct 27 10:56:36.959223 kernel: raid6: neonx1 gen() 3931 MB/s Oct 27 10:56:36.976224 kernel: raid6: int64x8 gen() 3771 MB/s Oct 27 10:56:36.993225 kernel: raid6: int64x4 gen() 3681 MB/s Oct 27 10:56:37.010224 kernel: raid6: int64x2 gen() 3574 MB/s Oct 27 10:56:37.028240 kernel: raid6: int64x1 gen() 2742 MB/s Oct 27 10:56:37.028280 kernel: raid6: using algorithm neonx8 gen() 6687 MB/s Oct 27 10:56:37.047227 kernel: raid6: .... xor() 4847 MB/s, rmw enabled Oct 27 10:56:37.047274 kernel: raid6: using neon recovery algorithm Oct 27 10:56:37.055228 kernel: xor: measuring software checksum speed Oct 27 10:56:37.057425 kernel: 8regs : 10244 MB/sec Oct 27 10:56:37.057457 kernel: 32regs : 11910 MB/sec Oct 27 10:56:37.058709 kernel: arm64_neon : 9561 MB/sec Oct 27 10:56:37.058742 kernel: xor: using function: 32regs (11910 MB/sec) Oct 27 10:56:37.147238 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 27 10:56:37.166534 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 27 10:56:37.179429 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 10:56:37.216269 systemd-udevd[472]: Using default interface naming scheme 'v255'. Oct 27 10:56:37.225680 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 10:56:37.248578 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 27 10:56:37.278819 dracut-pre-trigger[481]: rd.md=0: removing MD RAID activation Oct 27 10:56:37.335891 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 10:56:37.346562 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 10:56:37.462484 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 10:56:37.470438 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 27 10:56:37.517845 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 27 10:56:37.526797 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 10:56:37.540826 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 10:56:37.545863 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 10:56:37.562473 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 27 10:56:37.609856 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 27 10:56:37.674228 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 27 10:56:37.674294 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Oct 27 10:56:37.682667 kernel: ena 0000:00:05.0: ENA device version: 0.10 Oct 27 10:56:37.683031 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Oct 27 10:56:37.688082 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 10:56:37.688532 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 10:56:37.709563 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 27 10:56:37.717868 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 10:56:37.720324 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80110000, mac addr 06:ff:e6:74:eb:db Oct 27 10:56:37.720352 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 10:56:37.723806 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 10:56:37.736219 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Oct 27 10:56:37.738226 kernel: nvme nvme0: pci function 0000:00:04.0 Oct 27 10:56:37.744094 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 10:56:37.752213 kernel: nvme nvme0: 2/0/0 default/read/poll queues Oct 27 10:56:37.763882 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 27 10:56:37.763949 kernel: GPT:9289727 != 33554431 Oct 27 10:56:37.765377 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 27 10:56:37.765416 kernel: GPT:9289727 != 33554431 Oct 27 10:56:37.765442 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 27 10:56:37.765466 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 27 10:56:37.774953 (udev-worker)[530]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:56:37.782393 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 10:56:37.795574 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 27 10:56:37.838713 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 10:56:37.912262 kernel: BTRFS: device fsid f2f16861-2ac5-4cb7-96b9-a6277c10152a devid 1 transid 36 /dev/nvme0n1p3 scanned by (udev-worker) (523) Oct 27 10:56:37.933299 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (532) Oct 27 10:56:37.966408 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Oct 27 10:56:38.005924 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Oct 27 10:56:38.040000 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 27 10:56:38.053796 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Oct 27 10:56:38.053963 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Oct 27 10:56:38.069596 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 27 10:56:38.086704 disk-uuid[664]: Primary Header is updated. Oct 27 10:56:38.086704 disk-uuid[664]: Secondary Entries is updated. Oct 27 10:56:38.086704 disk-uuid[664]: Secondary Header is updated. Oct 27 10:56:38.101225 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 27 10:56:38.111234 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 27 10:56:38.120235 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 27 10:56:39.122234 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 27 10:56:39.126226 disk-uuid[665]: The operation has completed successfully. Oct 27 10:56:39.306501 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 10:56:39.309053 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 27 10:56:39.361674 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 27 10:56:39.372208 sh[1009]: Success Oct 27 10:56:39.398753 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 27 10:56:39.519572 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 27 10:56:39.536446 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 27 10:56:39.543729 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 27 10:56:39.585900 kernel: BTRFS info (device dm-0): first mount of filesystem f2f16861-2ac5-4cb7-96b9-a6277c10152a Oct 27 10:56:39.585962 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 27 10:56:39.587990 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 27 10:56:39.589496 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 27 10:56:39.589532 kernel: BTRFS info (device dm-0): using free space tree Oct 27 10:56:39.718238 kernel: BTRFS info (device dm-0: state E): enabling ssd optimizations Oct 27 10:56:39.743396 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 27 10:56:39.748014 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 27 10:56:39.759489 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 27 10:56:39.776502 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 27 10:56:39.813169 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ec18c36b-8699-4a93-85a9-1c162ace0865 Oct 27 10:56:39.813294 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 27 10:56:39.813351 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 27 10:56:39.831233 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 27 10:56:39.848158 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 27 10:56:39.851572 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem ec18c36b-8699-4a93-85a9-1c162ace0865 Oct 27 10:56:39.862732 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 27 10:56:39.879561 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 27 10:56:39.952173 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 10:56:39.964489 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 10:56:40.029449 systemd-networkd[1201]: lo: Link UP Oct 27 10:56:40.031214 systemd-networkd[1201]: lo: Gained carrier Oct 27 10:56:40.035566 systemd-networkd[1201]: Enumeration completed Oct 27 10:56:40.035862 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 10:56:40.042536 systemd[1]: Reached target network.target - Network. Oct 27 10:56:40.046796 systemd-networkd[1201]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 10:56:40.046817 systemd-networkd[1201]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 10:56:40.057304 systemd-networkd[1201]: eth0: Link UP Oct 27 10:56:40.057335 systemd-networkd[1201]: eth0: Gained carrier Oct 27 10:56:40.057355 systemd-networkd[1201]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 10:56:40.077280 systemd-networkd[1201]: eth0: DHCPv4 address 172.31.16.227/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 27 10:56:40.302509 ignition[1136]: Ignition 2.19.0 Oct 27 10:56:40.303032 ignition[1136]: Stage: fetch-offline Oct 27 10:56:40.304985 ignition[1136]: no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:40.305011 ignition[1136]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:40.305632 ignition[1136]: Ignition finished successfully Oct 27 10:56:40.314634 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 10:56:40.325513 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 27 10:56:40.359367 ignition[1211]: Ignition 2.19.0 Oct 27 10:56:40.359398 ignition[1211]: Stage: fetch Oct 27 10:56:40.361827 ignition[1211]: no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:40.361862 ignition[1211]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:40.362053 ignition[1211]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:40.379800 ignition[1211]: PUT result: OK Oct 27 10:56:40.382885 ignition[1211]: parsed url from cmdline: "" Oct 27 10:56:40.382900 ignition[1211]: no config URL provided Oct 27 10:56:40.382917 ignition[1211]: reading system config file "/usr/lib/ignition/user.ign" Oct 27 10:56:40.382943 ignition[1211]: no config at "/usr/lib/ignition/user.ign" Oct 27 10:56:40.382974 ignition[1211]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:40.385172 ignition[1211]: PUT result: OK Oct 27 10:56:40.385272 ignition[1211]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Oct 27 10:56:40.389807 ignition[1211]: GET result: OK Oct 27 10:56:40.389972 ignition[1211]: parsing config with SHA512: e147fdc1eacdba6bd5aad8b2f4b5a9dd1149281dbf3fba5187ed7792f47454d6db825444946b7cc64155d31936329ee2daeb9124d74ef2a12930b84c74400120 Oct 27 10:56:40.402210 unknown[1211]: fetched base config from "system" Oct 27 10:56:40.402443 unknown[1211]: fetched base config from "system" Oct 27 10:56:40.403016 ignition[1211]: fetch: fetch complete Oct 27 10:56:40.402458 unknown[1211]: fetched user config from "aws" Oct 27 10:56:40.403028 ignition[1211]: fetch: fetch passed Oct 27 10:56:40.403111 ignition[1211]: Ignition finished successfully Oct 27 10:56:40.416596 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 27 10:56:40.427479 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 27 10:56:40.455446 ignition[1218]: Ignition 2.19.0 Oct 27 10:56:40.457532 ignition[1218]: Stage: kargs Oct 27 10:56:40.458375 ignition[1218]: no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:40.458403 ignition[1218]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:40.458917 ignition[1218]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:40.466527 ignition[1218]: PUT result: OK Oct 27 10:56:40.471735 ignition[1218]: kargs: kargs passed Oct 27 10:56:40.472050 ignition[1218]: Ignition finished successfully Oct 27 10:56:40.477522 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 27 10:56:40.493941 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 27 10:56:40.514859 ignition[1224]: Ignition 2.19.0 Oct 27 10:56:40.514879 ignition[1224]: Stage: disks Oct 27 10:56:40.516044 ignition[1224]: no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:40.516071 ignition[1224]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:40.516305 ignition[1224]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:40.525140 ignition[1224]: PUT result: OK Oct 27 10:56:40.529272 ignition[1224]: disks: disks passed Oct 27 10:56:40.529397 ignition[1224]: Ignition finished successfully Oct 27 10:56:40.531664 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 27 10:56:40.536467 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 27 10:56:40.542724 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 27 10:56:40.545486 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 10:56:40.547809 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 10:56:40.550617 systemd[1]: Reached target basic.target - Basic System. Oct 27 10:56:40.567504 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 27 10:56:40.611104 systemd-fsck[1232]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 27 10:56:40.616993 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 27 10:56:40.632443 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 27 10:56:40.726225 kernel: EXT4-fs (nvme0n1p9): mounted filesystem c22e6712-f5a9-4d61-bf2d-132c48d70c1e r/w with ordered data mode. Quota mode: none. Oct 27 10:56:40.727686 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 27 10:56:40.731994 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 27 10:56:40.747424 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 10:56:40.754329 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 27 10:56:40.766027 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 27 10:56:40.766131 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 27 10:56:40.766184 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 10:56:40.787073 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/nvme0n1p6 scanned by mount (1251) Oct 27 10:56:40.792229 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ec18c36b-8699-4a93-85a9-1c162ace0865 Oct 27 10:56:40.792305 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 27 10:56:40.792344 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 27 10:56:40.797640 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 27 10:56:40.805516 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 27 10:56:40.818745 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 27 10:56:40.820448 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 10:56:41.251321 initrd-setup-root[1275]: cut: /sysroot/etc/passwd: No such file or directory Oct 27 10:56:41.261970 initrd-setup-root[1282]: cut: /sysroot/etc/group: No such file or directory Oct 27 10:56:41.261465 systemd-networkd[1201]: eth0: Gained IPv6LL Oct 27 10:56:41.273221 initrd-setup-root[1289]: cut: /sysroot/etc/shadow: No such file or directory Oct 27 10:56:41.283757 initrd-setup-root[1296]: cut: /sysroot/etc/gshadow: No such file or directory Oct 27 10:56:41.588295 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 27 10:56:41.600551 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 27 10:56:41.609828 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 27 10:56:41.629150 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 27 10:56:41.637483 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem ec18c36b-8699-4a93-85a9-1c162ace0865 Oct 27 10:56:41.672279 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 27 10:56:41.683793 ignition[1364]: INFO : Ignition 2.19.0 Oct 27 10:56:41.687341 ignition[1364]: INFO : Stage: mount Oct 27 10:56:41.689139 ignition[1364]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:41.689139 ignition[1364]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:41.694759 ignition[1364]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:41.698247 ignition[1364]: INFO : PUT result: OK Oct 27 10:56:41.702530 ignition[1364]: INFO : mount: mount passed Oct 27 10:56:41.704342 ignition[1364]: INFO : Ignition finished successfully Oct 27 10:56:41.708520 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 27 10:56:41.719557 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 27 10:56:41.748509 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 10:56:41.773236 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1375) Oct 27 10:56:41.773310 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ec18c36b-8699-4a93-85a9-1c162ace0865 Oct 27 10:56:41.776943 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 27 10:56:41.776981 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 27 10:56:41.784227 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 27 10:56:41.787863 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 10:56:41.827021 ignition[1392]: INFO : Ignition 2.19.0 Oct 27 10:56:41.827021 ignition[1392]: INFO : Stage: files Oct 27 10:56:41.830812 ignition[1392]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:41.830812 ignition[1392]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:41.830812 ignition[1392]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:41.838656 ignition[1392]: INFO : PUT result: OK Oct 27 10:56:41.844472 ignition[1392]: DEBUG : files: compiled without relabeling support, skipping Oct 27 10:56:41.847496 ignition[1392]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 27 10:56:41.847496 ignition[1392]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 27 10:56:41.877985 ignition[1392]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 27 10:56:41.881366 ignition[1392]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 27 10:56:41.884900 unknown[1392]: wrote ssh authorized keys file for user: core Oct 27 10:56:41.887538 ignition[1392]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 27 10:56:41.897338 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 10:56:41.901067 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Oct 27 10:56:41.927724 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Oct 27 10:56:41.927724 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Oct 27 10:56:41.927724 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.35.1-arm64.raw: attempt #1 Oct 27 10:56:42.429377 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Oct 27 10:56:42.802757 ignition[1392]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-arm64.raw" Oct 27 10:56:42.802757 ignition[1392]: INFO : files: op(8): [started] processing unit "containerd.service" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: op(8): [finished] processing unit "containerd.service" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 27 10:56:42.810246 ignition[1392]: INFO : files: files passed Oct 27 10:56:42.810246 ignition[1392]: INFO : Ignition finished successfully Oct 27 10:56:42.828336 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 27 10:56:42.846571 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 27 10:56:42.852789 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 27 10:56:42.873182 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 27 10:56:42.875341 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 27 10:56:42.886998 initrd-setup-root-after-ignition[1421]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 10:56:42.886998 initrd-setup-root-after-ignition[1421]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 27 10:56:42.898161 initrd-setup-root-after-ignition[1425]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 10:56:42.900304 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 10:56:42.905479 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 27 10:56:42.917615 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 27 10:56:42.976117 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 10:56:42.976583 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 27 10:56:42.987419 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 27 10:56:42.989712 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 27 10:56:42.992768 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 27 10:56:43.011578 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 27 10:56:43.039491 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 10:56:43.055623 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 27 10:56:43.079414 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 27 10:56:43.082769 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 10:56:43.090015 systemd[1]: Stopped target timers.target - Timer Units. Oct 27 10:56:43.092712 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 10:56:43.092971 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 10:56:43.101803 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 27 10:56:43.106567 systemd[1]: Stopped target basic.target - Basic System. Oct 27 10:56:43.110567 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 27 10:56:43.115440 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 10:56:43.120636 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 27 10:56:43.124063 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 27 10:56:43.127093 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 10:56:43.137556 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 27 10:56:43.140249 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 27 10:56:43.146918 systemd[1]: Stopped target swap.target - Swaps. Oct 27 10:56:43.148833 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 10:56:43.149067 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 27 10:56:43.157554 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 27 10:56:43.160064 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 10:56:43.163342 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 27 10:56:43.165111 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 10:56:43.168881 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 10:56:43.169140 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 27 10:56:43.184383 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 27 10:56:43.184907 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 10:56:43.196127 systemd[1]: ignition-files.service: Deactivated successfully. Oct 27 10:56:43.196578 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 27 10:56:43.209509 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 27 10:56:43.215043 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 10:56:43.215454 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 10:56:43.235516 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 27 10:56:43.242357 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 10:56:43.242691 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 10:56:43.262285 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 10:56:43.262611 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 10:56:43.283440 ignition[1445]: INFO : Ignition 2.19.0 Oct 27 10:56:43.283440 ignition[1445]: INFO : Stage: umount Oct 27 10:56:43.291812 ignition[1445]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 10:56:43.291812 ignition[1445]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 27 10:56:43.291812 ignition[1445]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 27 10:56:43.291486 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 27 10:56:43.312561 ignition[1445]: INFO : PUT result: OK Oct 27 10:56:43.312561 ignition[1445]: INFO : umount: umount passed Oct 27 10:56:43.312561 ignition[1445]: INFO : Ignition finished successfully Oct 27 10:56:43.296049 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 10:56:43.298243 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 27 10:56:43.309130 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 27 10:56:43.309376 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 27 10:56:43.316850 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 27 10:56:43.318493 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 27 10:56:43.334675 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 27 10:56:43.334836 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 27 10:56:43.344827 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 27 10:56:43.344933 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 27 10:56:43.347659 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 27 10:56:43.347740 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 27 10:56:43.350072 systemd[1]: Stopped target network.target - Network. Oct 27 10:56:43.351980 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 27 10:56:43.352064 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 10:56:43.354946 systemd[1]: Stopped target paths.target - Path Units. Oct 27 10:56:43.356922 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 10:56:43.365466 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 10:56:43.368428 systemd[1]: Stopped target slices.target - Slice Units. Oct 27 10:56:43.370465 systemd[1]: Stopped target sockets.target - Socket Units. Oct 27 10:56:43.372988 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 10:56:43.373070 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 10:56:43.375304 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 10:56:43.375374 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 10:56:43.377862 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 27 10:56:43.377949 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 27 10:56:43.380585 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 27 10:56:43.380664 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 27 10:56:43.383079 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 27 10:56:43.383159 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 27 10:56:43.386068 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 27 10:56:43.393816 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 27 10:56:43.396307 systemd-networkd[1201]: eth0: DHCPv6 lease lost Oct 27 10:56:43.399777 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 10:56:43.400015 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 27 10:56:43.404427 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 10:56:43.404501 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 27 10:56:43.424365 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 27 10:56:43.431594 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 27 10:56:43.431713 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 10:56:43.434648 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 10:56:43.452148 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 10:56:43.453106 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 27 10:56:43.510924 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 10:56:43.513071 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 10:56:43.520797 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 27 10:56:43.521305 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 27 10:56:43.527931 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 10:56:43.528052 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 27 10:56:43.528520 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 10:56:43.528589 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 10:56:43.535330 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 10:56:43.535426 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 27 10:56:43.538334 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 10:56:43.538419 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 27 10:56:43.547843 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 10:56:43.547929 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 10:56:43.572479 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 27 10:56:43.574965 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 10:56:43.575081 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 27 10:56:43.577737 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 27 10:56:43.577823 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 27 10:56:43.580493 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 10:56:43.580573 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 10:56:43.583976 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 27 10:56:43.584056 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 10:56:43.589305 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 10:56:43.589408 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 10:56:43.619065 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 10:56:43.619586 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 27 10:56:43.629899 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 27 10:56:43.641548 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 27 10:56:43.671414 systemd[1]: Switching root. Oct 27 10:56:43.720131 systemd-journald[252]: Journal stopped Oct 27 10:56:46.311893 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Oct 27 10:56:46.312029 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 10:56:46.312074 kernel: SELinux: policy capability open_perms=1 Oct 27 10:56:46.312111 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 10:56:46.312142 kernel: SELinux: policy capability always_check_network=0 Oct 27 10:56:46.312173 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 10:56:46.312237 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 10:56:46.312270 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 10:56:46.312301 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 27 10:56:46.312341 kernel: audit: type=1403 audit(1793098604.450:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 10:56:46.312375 systemd[1]: Successfully loaded SELinux policy in 50.483ms. Oct 27 10:56:46.312420 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.691ms. Oct 27 10:56:46.312459 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 27 10:56:46.312496 systemd[1]: Detected virtualization amazon. Oct 27 10:56:46.312527 systemd[1]: Detected architecture arm64. Oct 27 10:56:46.312559 systemd[1]: Detected first boot. Oct 27 10:56:46.312591 systemd[1]: Initializing machine ID from VM UUID. Oct 27 10:56:46.312626 zram_generator::config[1504]: No configuration found. Oct 27 10:56:46.312663 systemd[1]: Populated /etc with preset unit settings. Oct 27 10:56:46.312693 systemd[1]: Queued start job for default target multi-user.target. Oct 27 10:56:46.312725 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Oct 27 10:56:46.312757 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 27 10:56:46.312790 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 27 10:56:46.312821 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 27 10:56:46.312853 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 27 10:56:46.312888 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 27 10:56:46.312925 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 27 10:56:46.312957 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 27 10:56:46.312989 systemd[1]: Created slice user.slice - User and Session Slice. Oct 27 10:56:46.313023 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 10:56:46.313053 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 10:56:46.313085 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 27 10:56:46.313117 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 27 10:56:46.313149 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 27 10:56:46.313186 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 10:56:46.318798 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 27 10:56:46.318834 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 10:56:46.318955 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 27 10:56:46.318992 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 10:56:46.319028 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 10:56:46.319062 systemd[1]: Reached target slices.target - Slice Units. Oct 27 10:56:46.319094 systemd[1]: Reached target swap.target - Swaps. Oct 27 10:56:46.319132 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 27 10:56:46.319167 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 27 10:56:46.319294 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 27 10:56:46.319331 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 27 10:56:46.319364 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 10:56:46.319397 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 10:56:46.319429 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 10:56:46.319461 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 27 10:56:46.319491 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 27 10:56:46.319521 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 27 10:56:46.319556 systemd[1]: Mounting media.mount - External Media Directory... Oct 27 10:56:46.319598 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 27 10:56:46.319629 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 27 10:56:46.319659 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 27 10:56:46.319692 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 27 10:56:46.319728 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 10:56:46.319761 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 10:56:46.319791 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 27 10:56:46.319825 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 10:56:46.319855 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 10:56:46.319889 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 10:56:46.319919 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 27 10:56:46.319951 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 10:56:46.319982 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 27 10:56:46.320014 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Oct 27 10:56:46.320047 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Oct 27 10:56:46.320089 kernel: fuse: init (API version 7.39) Oct 27 10:56:46.320119 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 10:56:46.320149 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 10:56:46.320181 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 27 10:56:46.320236 kernel: loop: module loaded Oct 27 10:56:46.320276 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 27 10:56:46.320309 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 10:56:46.320341 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 27 10:56:46.320374 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 27 10:56:46.320404 systemd[1]: Mounted media.mount - External Media Directory. Oct 27 10:56:46.320438 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 27 10:56:46.320470 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 27 10:56:46.320501 kernel: ACPI: bus type drm_connector registered Oct 27 10:56:46.320532 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 27 10:56:46.320563 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 10:56:46.320595 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 10:56:46.320625 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 27 10:56:46.320654 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 10:56:46.320689 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 10:56:46.320720 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 10:56:46.320750 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 10:56:46.320780 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 10:56:46.320810 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 10:56:46.320843 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 10:56:46.320922 systemd-journald[1600]: Collecting audit messages is disabled. Oct 27 10:56:46.320972 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 27 10:56:46.321003 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 10:56:46.321035 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 10:56:46.321065 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 10:56:46.321096 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 10:56:46.321126 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 27 10:56:46.321162 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 27 10:56:46.324865 systemd-journald[1600]: Journal started Oct 27 10:56:46.328257 systemd-journald[1600]: Runtime Journal (/run/log/journal/ec29d260b227e507563c657846f4c8f9) is 8.0M, max 75.3M, 67.3M free. Oct 27 10:56:46.328375 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 27 10:56:46.344590 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 27 10:56:46.356274 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 27 10:56:46.371042 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 27 10:56:46.377235 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 10:56:46.389840 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 27 10:56:46.406922 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 10:56:46.418025 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 10:56:46.435227 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 10:56:46.439291 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 10:56:46.455078 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 27 10:56:46.458153 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 27 10:56:46.461807 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 27 10:56:46.466423 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 27 10:56:46.502379 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 27 10:56:46.512693 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 27 10:56:46.565600 systemd-journald[1600]: Time spent on flushing to /var/log/journal/ec29d260b227e507563c657846f4c8f9 is 97.091ms for 873 entries. Oct 27 10:56:46.565600 systemd-journald[1600]: System Journal (/var/log/journal/ec29d260b227e507563c657846f4c8f9) is 8.0M, max 195.6M, 187.6M free. Oct 27 10:56:46.673982 systemd-journald[1600]: Received client request to flush runtime journal. Oct 27 10:56:46.580896 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 10:56:46.586175 systemd-tmpfiles[1639]: ACLs are not supported, ignoring. Oct 27 10:56:46.588275 systemd-tmpfiles[1639]: ACLs are not supported, ignoring. Oct 27 10:56:46.613208 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 10:56:46.632535 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 27 10:56:46.679979 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 27 10:56:46.693098 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 10:56:46.707677 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 27 10:56:46.748477 udevadm[1675]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 27 10:56:46.756916 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 27 10:56:46.775503 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 10:56:46.815172 systemd-tmpfiles[1678]: ACLs are not supported, ignoring. Oct 27 10:56:46.815253 systemd-tmpfiles[1678]: ACLs are not supported, ignoring. Oct 27 10:56:46.825638 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 10:56:47.403850 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 27 10:56:47.416556 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 10:56:47.468899 systemd-udevd[1684]: Using default interface naming scheme 'v255'. Oct 27 10:56:47.505821 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 10:56:47.529497 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 10:56:47.557996 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 27 10:56:47.712624 (udev-worker)[1685]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:56:47.714580 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Oct 27 10:56:47.724360 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 27 10:56:47.902085 systemd-networkd[1692]: lo: Link UP Oct 27 10:56:47.902111 systemd-networkd[1692]: lo: Gained carrier Oct 27 10:56:47.908676 systemd-networkd[1692]: Enumeration completed Oct 27 10:56:47.908897 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 10:56:47.916547 systemd-networkd[1692]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 10:56:47.916579 systemd-networkd[1692]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 10:56:47.921378 systemd-networkd[1692]: eth0: Link UP Oct 27 10:56:47.921761 systemd-networkd[1692]: eth0: Gained carrier Oct 27 10:56:47.921795 systemd-networkd[1692]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 10:56:47.922481 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 27 10:56:47.935405 systemd-networkd[1692]: eth0: DHCPv4 address 172.31.16.227/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 27 10:56:47.970272 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 10:56:47.984294 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (1685) Oct 27 10:56:48.200635 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 27 10:56:48.204086 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 10:56:48.235947 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 27 10:56:48.245552 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 27 10:56:48.282256 lvm[1813]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 10:56:48.319947 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 27 10:56:48.323512 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 10:56:48.341562 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 27 10:56:48.352421 lvm[1816]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 27 10:56:48.387826 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 27 10:56:48.391122 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 27 10:56:48.394036 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 27 10:56:48.394086 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 10:56:48.396468 systemd[1]: Reached target machines.target - Containers. Oct 27 10:56:48.400908 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 27 10:56:48.416662 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 27 10:56:48.423424 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 27 10:56:48.428579 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 10:56:48.435461 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 27 10:56:48.448639 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 27 10:56:48.468576 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 27 10:56:48.472756 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 27 10:56:48.509637 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 27 10:56:48.516814 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 27 10:56:48.525352 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 27 10:56:48.537346 kernel: loop0: detected capacity change from 0 to 52536 Oct 27 10:56:48.630252 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 27 10:56:48.660229 kernel: loop1: detected capacity change from 0 to 197488 Oct 27 10:56:48.983454 kernel: loop2: detected capacity change from 0 to 114328 Oct 27 10:56:49.069719 systemd-networkd[1692]: eth0: Gained IPv6LL Oct 27 10:56:49.078557 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 27 10:56:49.108967 kernel: loop3: detected capacity change from 0 to 114432 Oct 27 10:56:49.209256 kernel: loop4: detected capacity change from 0 to 52536 Oct 27 10:56:49.229492 kernel: loop5: detected capacity change from 0 to 197488 Oct 27 10:56:49.260231 kernel: loop6: detected capacity change from 0 to 114328 Oct 27 10:56:49.279239 kernel: loop7: detected capacity change from 0 to 114432 Oct 27 10:56:49.291842 (sd-merge)[1843]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Oct 27 10:56:49.292828 (sd-merge)[1843]: Merged extensions into '/usr'. Oct 27 10:56:49.300217 systemd[1]: Reloading requested from client PID 1824 ('systemd-sysext') (unit systemd-sysext.service)... Oct 27 10:56:49.300246 systemd[1]: Reloading... Oct 27 10:56:49.411236 zram_generator::config[1870]: No configuration found. Oct 27 10:56:49.450852 ldconfig[1820]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 27 10:56:49.693562 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 27 10:56:49.845584 systemd[1]: Reloading finished in 544 ms. Oct 27 10:56:49.869392 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 27 10:56:49.872663 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 27 10:56:49.891723 systemd[1]: Starting ensure-sysext.service... Oct 27 10:56:49.898626 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 10:56:49.915347 systemd[1]: Reloading requested from client PID 1929 ('systemctl') (unit ensure-sysext.service)... Oct 27 10:56:49.915534 systemd[1]: Reloading... Oct 27 10:56:49.943829 systemd-tmpfiles[1930]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 27 10:56:49.944572 systemd-tmpfiles[1930]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 27 10:56:49.947221 systemd-tmpfiles[1930]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 10:56:49.947784 systemd-tmpfiles[1930]: ACLs are not supported, ignoring. Oct 27 10:56:49.947944 systemd-tmpfiles[1930]: ACLs are not supported, ignoring. Oct 27 10:56:49.954620 systemd-tmpfiles[1930]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 10:56:49.954648 systemd-tmpfiles[1930]: Skipping /boot Oct 27 10:56:49.979680 systemd-tmpfiles[1930]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 10:56:49.979710 systemd-tmpfiles[1930]: Skipping /boot Oct 27 10:56:50.065941 zram_generator::config[1957]: No configuration found. Oct 27 10:56:50.316715 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 27 10:56:50.470275 systemd[1]: Reloading finished in 553 ms. Oct 27 10:56:50.503382 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 10:56:50.520597 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 27 10:56:50.530472 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 27 10:56:50.538923 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 27 10:56:50.555524 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 10:56:50.571466 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 27 10:56:50.592309 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 10:56:50.598721 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 10:56:50.606181 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 10:56:50.629943 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 10:56:50.632460 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 10:56:50.662825 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 27 10:56:50.682541 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 10:56:50.682928 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 10:56:50.693951 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 10:56:50.695511 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 10:56:50.706063 systemd[1]: Finished ensure-sysext.service. Oct 27 10:56:50.710650 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 27 10:56:50.715705 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 10:56:50.717720 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 10:56:50.729422 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 10:56:50.743656 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 10:56:50.747812 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 10:56:50.747916 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 10:56:50.748017 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 10:56:50.748087 systemd[1]: Reached target time-set.target - System Time Set. Oct 27 10:56:50.762585 augenrules[2056]: No rules Oct 27 10:56:50.765514 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 27 10:56:50.773081 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 27 10:56:50.788976 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 27 10:56:50.800418 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 10:56:50.801356 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 10:56:50.805662 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 27 10:56:50.823861 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 27 10:56:50.888339 systemd-resolved[2027]: Positive Trust Anchors: Oct 27 10:56:50.888946 systemd-resolved[2027]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 10:56:50.889017 systemd-resolved[2027]: 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 27 10:56:50.920535 systemd-resolved[2027]: Defaulting to hostname 'linux'. Oct 27 10:56:50.924031 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 10:56:50.926800 systemd[1]: Reached target network.target - Network. Oct 27 10:56:50.928862 systemd[1]: Reached target network-online.target - Network is Online. Oct 27 10:56:50.931293 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 10:56:50.934037 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 10:56:50.936538 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 27 10:56:50.939333 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 27 10:56:50.942403 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 27 10:56:50.945036 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 27 10:56:50.947800 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 27 10:56:50.950595 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 27 10:56:50.950662 systemd[1]: Reached target paths.target - Path Units. Oct 27 10:56:50.952753 systemd[1]: Reached target timers.target - Timer Units. Oct 27 10:56:50.955692 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 27 10:56:50.960998 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 27 10:56:50.966426 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 27 10:56:50.981175 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 27 10:56:50.983795 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 10:56:50.986060 systemd[1]: Reached target basic.target - Basic System. Oct 27 10:56:50.988645 systemd[1]: System is tainted: cgroupsv1 Oct 27 10:56:50.988722 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 27 10:56:50.988767 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 27 10:56:50.993417 systemd[1]: Starting containerd.service - containerd container runtime... Oct 27 10:56:51.011602 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 27 10:56:51.017529 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 27 10:56:51.023609 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 27 10:56:51.040772 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 27 10:56:51.044065 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 27 10:56:51.050411 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 10:56:51.068371 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 27 10:56:51.098001 jq[2077]: false Oct 27 10:56:51.096891 systemd[1]: Started ntpd.service - Network Time Service. Oct 27 10:56:51.123908 extend-filesystems[2078]: Found loop4 Oct 27 10:56:51.128246 extend-filesystems[2078]: Found loop5 Oct 27 10:56:51.128246 extend-filesystems[2078]: Found loop6 Oct 27 10:56:51.128246 extend-filesystems[2078]: Found loop7 Oct 27 10:56:51.128246 extend-filesystems[2078]: Found nvme0n1 Oct 27 10:56:51.124412 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 27 10:56:51.137024 systemd[1]: Starting setup-oem.service - Setup OEM... Oct 27 10:56:51.156388 extend-filesystems[2078]: Found nvme0n1p1 Oct 27 10:56:51.158302 extend-filesystems[2078]: Found nvme0n1p2 Oct 27 10:56:51.160310 extend-filesystems[2078]: Found nvme0n1p3 Oct 27 10:56:51.160310 extend-filesystems[2078]: Found usr Oct 27 10:56:51.160310 extend-filesystems[2078]: Found nvme0n1p4 Oct 27 10:56:51.160310 extend-filesystems[2078]: Found nvme0n1p6 Oct 27 10:56:51.160310 extend-filesystems[2078]: Found nvme0n1p7 Oct 27 10:56:51.172101 extend-filesystems[2078]: Found nvme0n1p9 Oct 27 10:56:51.172101 extend-filesystems[2078]: Checking size of /dev/nvme0n1p9 Oct 27 10:56:51.181714 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 27 10:56:51.202500 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 27 10:56:51.223814 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 27 10:56:51.228350 dbus-daemon[2075]: [system] SELinux support is enabled Oct 27 10:56:51.244718 dbus-daemon[2075]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1692 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Oct 27 10:56:51.247959 ntpd[2082]: ntpd 4.2.8p17@1.4004-o Tue Oct 27 08:57:48 UTC 2026 (1): Starting Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: ntpd 4.2.8p17@1.4004-o Tue Oct 27 08:57:48 UTC 2026 (1): Starting Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: ---------------------------------------------------- Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: ntp-4 is maintained by Network Time Foundation, Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: corporation. Support and training for ntp-4 are Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: available at https://www.nwtime.org/support Oct 27 10:56:51.249484 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: ---------------------------------------------------- Oct 27 10:56:51.249342 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 27 10:56:51.248020 ntpd[2082]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 27 10:56:51.248041 ntpd[2082]: ---------------------------------------------------- Oct 27 10:56:51.257414 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: proto: precision = 0.108 usec (-23) Oct 27 10:56:51.248060 ntpd[2082]: ntp-4 is maintained by Network Time Foundation, Oct 27 10:56:51.248079 ntpd[2082]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 27 10:56:51.248099 ntpd[2082]: corporation. Support and training for ntp-4 are Oct 27 10:56:51.248119 ntpd[2082]: available at https://www.nwtime.org/support Oct 27 10:56:51.248138 ntpd[2082]: ---------------------------------------------------- Oct 27 10:56:51.256890 ntpd[2082]: proto: precision = 0.108 usec (-23) Oct 27 10:56:51.259540 ntpd[2082]: basedate set to 2026-10-15 Oct 27 10:56:51.261357 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: basedate set to 2026-10-15 Oct 27 10:56:51.261357 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: gps base set to 2026-10-18 (week 2441) Oct 27 10:56:51.259577 ntpd[2082]: gps base set to 2026-10-18 (week 2441) Oct 27 10:56:51.262446 ntpd[2082]: Listen and drop on 0 v6wildcard [::]:123 Oct 27 10:56:51.262917 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen and drop on 0 v6wildcard [::]:123 Oct 27 10:56:51.262917 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 27 10:56:51.262683 ntpd[2082]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 27 10:56:51.263123 ntpd[2082]: Listen normally on 2 lo 127.0.0.1:123 Oct 27 10:56:51.263335 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen normally on 2 lo 127.0.0.1:123 Oct 27 10:56:51.263335 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen normally on 3 eth0 172.31.16.227:123 Oct 27 10:56:51.263224 ntpd[2082]: Listen normally on 3 eth0 172.31.16.227:123 Oct 27 10:56:51.263561 ntpd[2082]: Listen normally on 4 lo [::1]:123 Oct 27 10:56:51.265236 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen normally on 4 lo [::1]:123 Oct 27 10:56:51.265236 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listen normally on 5 eth0 [fe80::4ff:e6ff:fe74:ebdb%2]:123 Oct 27 10:56:51.265236 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: Listening on routing socket on fd #22 for interface updates Oct 27 10:56:51.263741 ntpd[2082]: Listen normally on 5 eth0 [fe80::4ff:e6ff:fe74:ebdb%2]:123 Oct 27 10:56:51.263806 ntpd[2082]: Listening on routing socket on fd #22 for interface updates Oct 27 10:56:51.273405 extend-filesystems[2078]: Resized partition /dev/nvme0n1p9 Oct 27 10:56:51.279986 extend-filesystems[2106]: resize2fs 1.47.1 (20-May-2024) Oct 27 10:56:51.315681 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 3587067 blocks Oct 27 10:56:51.302887 systemd[1]: Starting update-engine.service - Update Engine... Oct 27 10:56:51.321886 ntpd[2082]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 10:56:51.322135 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 10:56:51.322135 ntpd[2082]: 27 Oct 10:56:51 ntpd[2082]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 10:56:51.321962 ntpd[2082]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 27 10:56:51.327642 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 27 10:56:51.331803 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 27 10:56:51.349555 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 27 10:56:51.350106 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 27 10:56:51.358080 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 10:56:51.358641 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 27 10:56:51.387305 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 10:56:51.388857 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 27 10:56:51.401392 jq[2112]: true Oct 27 10:56:51.456387 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 27 10:56:51.468926 (ntainerd)[2129]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 27 10:56:51.480503 update_engine[2104]: I20261027 10:56:51.480077 2104 main.cc:92] Flatcar Update Engine starting Oct 27 10:56:51.490266 update_engine[2104]: I20261027 10:56:51.489671 2104 update_check_scheduler.cc:74] Next update check in 7m14s Oct 27 10:56:51.510080 dbus-daemon[2075]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 27 10:56:51.515539 systemd[1]: Started update-engine.service - Update Engine. Oct 27 10:56:51.524436 jq[2126]: true Oct 27 10:56:51.529043 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 27 10:56:51.535155 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 27 10:56:51.535288 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 27 10:56:51.539819 coreos-metadata[2074]: Oct 27 10:56:51.539 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 27 10:56:51.547530 coreos-metadata[2074]: Oct 27 10:56:51.547 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Oct 27 10:56:51.559579 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 27 10:56:51.561968 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 27 10:56:51.562005 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 27 10:56:51.567383 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 10:56:51.569232 coreos-metadata[2074]: Oct 27 10:56:51.568 INFO Fetch successful Oct 27 10:56:51.569232 coreos-metadata[2074]: Oct 27 10:56:51.568 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Oct 27 10:56:51.571800 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 27 10:56:51.579424 coreos-metadata[2074]: Oct 27 10:56:51.579 INFO Fetch successful Oct 27 10:56:51.579424 coreos-metadata[2074]: Oct 27 10:56:51.579 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Oct 27 10:56:51.588716 coreos-metadata[2074]: Oct 27 10:56:51.588 INFO Fetch successful Oct 27 10:56:51.588716 coreos-metadata[2074]: Oct 27 10:56:51.588 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Oct 27 10:56:51.604331 coreos-metadata[2074]: Oct 27 10:56:51.600 INFO Fetch successful Oct 27 10:56:51.604331 coreos-metadata[2074]: Oct 27 10:56:51.600 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Oct 27 10:56:51.619475 coreos-metadata[2074]: Oct 27 10:56:51.607 INFO Fetch failed with 404: resource not found Oct 27 10:56:51.619475 coreos-metadata[2074]: Oct 27 10:56:51.607 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Oct 27 10:56:51.619475 coreos-metadata[2074]: Oct 27 10:56:51.614 INFO Fetch successful Oct 27 10:56:51.619475 coreos-metadata[2074]: Oct 27 10:56:51.614 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Oct 27 10:56:51.627430 coreos-metadata[2074]: Oct 27 10:56:51.627 INFO Fetch successful Oct 27 10:56:51.628692 coreos-metadata[2074]: Oct 27 10:56:51.627 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Oct 27 10:56:51.657967 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 3587067 Oct 27 10:56:51.689471 coreos-metadata[2074]: Oct 27 10:56:51.638 INFO Fetch successful Oct 27 10:56:51.689471 coreos-metadata[2074]: Oct 27 10:56:51.658 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Oct 27 10:56:51.689471 coreos-metadata[2074]: Oct 27 10:56:51.658 INFO Fetch successful Oct 27 10:56:51.689471 coreos-metadata[2074]: Oct 27 10:56:51.658 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Oct 27 10:56:51.689471 coreos-metadata[2074]: Oct 27 10:56:51.658 INFO Fetch successful Oct 27 10:56:51.698701 extend-filesystems[2106]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Oct 27 10:56:51.698701 extend-filesystems[2106]: old_desc_blocks = 1, new_desc_blocks = 2 Oct 27 10:56:51.698701 extend-filesystems[2106]: The filesystem on /dev/nvme0n1p9 is now 3587067 (4k) blocks long. Oct 27 10:56:51.713326 extend-filesystems[2078]: Resized filesystem in /dev/nvme0n1p9 Oct 27 10:56:51.741632 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 10:56:51.742136 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 27 10:56:51.746453 systemd[1]: Finished setup-oem.service - Setup OEM. Oct 27 10:56:51.775480 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Oct 27 10:56:51.837633 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 27 10:56:51.841680 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 27 10:56:51.850076 systemd-logind[2102]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 10:56:51.851225 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (2156) Oct 27 10:56:51.855418 systemd-logind[2102]: Watching system buttons on /dev/input/event1 (Sleep Button) Oct 27 10:56:51.859501 systemd-logind[2102]: New seat seat0. Oct 27 10:56:51.863275 systemd[1]: Started systemd-logind.service - User Login Management. Oct 27 10:56:51.869806 locksmithd[2150]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 27 10:56:51.892303 bash[2196]: Updated "/home/core/.ssh/authorized_keys" Oct 27 10:56:51.897031 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 27 10:56:51.971178 systemd[1]: Starting sshkeys.service... Oct 27 10:56:51.986085 amazon-ssm-agent[2179]: Initializing new seelog logger Oct 27 10:56:51.992492 amazon-ssm-agent[2179]: New Seelog Logger Creation Complete Oct 27 10:56:51.992681 amazon-ssm-agent[2179]: 2026/10/27 10:56:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:51.992681 amazon-ssm-agent[2179]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:51.993790 amazon-ssm-agent[2179]: 2026/10/27 10:56:51 processing appconfig overrides Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: 2026/10/27 10:56:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: 2026/10/27 10:56:52 processing appconfig overrides Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: 2026/10/27 10:56:52 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.001707 amazon-ssm-agent[2179]: 2026/10/27 10:56:52 processing appconfig overrides Oct 27 10:56:52.009671 amazon-ssm-agent[2179]: 2026-10-27 10:56:51 INFO Proxy environment variables: Oct 27 10:56:52.020856 amazon-ssm-agent[2179]: 2026/10/27 10:56:52 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.020856 amazon-ssm-agent[2179]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 27 10:56:52.021033 amazon-ssm-agent[2179]: 2026/10/27 10:56:52 processing appconfig overrides Oct 27 10:56:52.028348 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 27 10:56:52.036204 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 27 10:56:52.111307 amazon-ssm-agent[2179]: 2026-10-27 10:56:51 INFO https_proxy: Oct 27 10:56:52.213171 amazon-ssm-agent[2179]: 2026-10-27 10:56:51 INFO http_proxy: Oct 27 10:56:52.269106 coreos-metadata[2234]: Oct 27 10:56:52.268 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 27 10:56:52.277499 coreos-metadata[2234]: Oct 27 10:56:52.277 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Oct 27 10:56:52.281411 coreos-metadata[2234]: Oct 27 10:56:52.281 INFO Fetch successful Oct 27 10:56:52.281411 coreos-metadata[2234]: Oct 27 10:56:52.281 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 27 10:56:52.283066 coreos-metadata[2234]: Oct 27 10:56:52.282 INFO Fetch successful Oct 27 10:56:52.294450 unknown[2234]: wrote ssh authorized keys file for user: core Oct 27 10:56:52.316314 amazon-ssm-agent[2179]: 2026-10-27 10:56:51 INFO no_proxy: Oct 27 10:56:52.373657 dbus-daemon[2075]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 27 10:56:52.374489 dbus-daemon[2075]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=2144 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Oct 27 10:56:52.384209 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 27 10:56:52.409376 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO Checking if agent identity type OnPrem can be assumed Oct 27 10:56:52.436901 systemd[1]: Starting polkit.service - Authorization Manager... Oct 27 10:56:52.452217 update-ssh-keys[2276]: Updated "/home/core/.ssh/authorized_keys" Oct 27 10:56:52.470399 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 27 10:56:52.489364 systemd[1]: Finished sshkeys.service. Oct 27 10:56:52.512211 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO Checking if agent identity type EC2 can be assumed Oct 27 10:56:52.554781 polkitd[2298]: Started polkitd version 121 Oct 27 10:56:52.613120 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO Agent will take identity from EC2 Oct 27 10:56:52.636238 polkitd[2298]: Loading rules from directory /etc/polkit-1/rules.d Oct 27 10:56:52.638009 polkitd[2298]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 27 10:56:52.645609 polkitd[2298]: Finished loading, compiling and executing 2 rules Oct 27 10:56:52.652788 dbus-daemon[2075]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 27 10:56:52.653057 systemd[1]: Started polkit.service - Authorization Manager. Oct 27 10:56:52.656039 polkitd[2298]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 27 10:56:52.684003 containerd[2129]: time="2026-10-27T10:56:52.683178541Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Oct 27 10:56:52.717786 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 27 10:56:52.723410 systemd-resolved[2027]: System hostname changed to 'ip-172-31-16-227'. Oct 27 10:56:52.723411 systemd-hostnamed[2144]: Hostname set to (transient) Oct 27 10:56:52.763931 containerd[2129]: time="2026-10-27T10:56:52.763596385Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.766594 containerd[2129]: time="2026-10-27T10:56:52.766527301Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.160-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.766723393Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.766763917Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.767070421Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.767102869Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.767272297Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 27 10:56:52.767421 containerd[2129]: time="2026-10-27T10:56:52.767302357Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.768020 containerd[2129]: time="2026-10-27T10:56:52.767984737Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 27 10:56:52.768123 containerd[2129]: time="2026-10-27T10:56:52.768096409Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.768304 containerd[2129]: time="2026-10-27T10:56:52.768274597Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 27 10:56:52.768401 containerd[2129]: time="2026-10-27T10:56:52.768375253Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.768650 containerd[2129]: time="2026-10-27T10:56:52.768622525Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.769148 containerd[2129]: time="2026-10-27T10:56:52.769116553Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 27 10:56:52.770097 containerd[2129]: time="2026-10-27T10:56:52.769573189Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 27 10:56:52.770097 containerd[2129]: time="2026-10-27T10:56:52.769612741Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 27 10:56:52.770097 containerd[2129]: time="2026-10-27T10:56:52.769798069Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 27 10:56:52.770097 containerd[2129]: time="2026-10-27T10:56:52.769895689Z" level=info msg="metadata content store policy set" policy=shared Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781086961Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781183609Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781326529Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781364029Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781403137Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.781649713Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782271673Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782469289Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782503621Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782533537Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782564581Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782594809Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782624917Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783219 containerd[2129]: time="2026-10-27T10:56:52.782655829Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782687449Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782717077Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782746393Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782779057Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782820709Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782851861Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782881117Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782911273Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782945641Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.782976697Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.783004609Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.783034237Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.783064393Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.783843 containerd[2129]: time="2026-10-27T10:56:52.783099085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.784428 containerd[2129]: time="2026-10-27T10:56:52.783128137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.784428 containerd[2129]: time="2026-10-27T10:56:52.783166801Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786236221Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786294409Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786341449Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786370765Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786398137Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786624301Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786662413Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786693829Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786722869Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786748213Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786776689Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786807973Z" level=info msg="NRI interface is disabled by configuration." Oct 27 10:56:52.788227 containerd[2129]: time="2026-10-27T10:56:52.786839161Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 27 10:56:52.788851 containerd[2129]: time="2026-10-27T10:56:52.787343149Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 27 10:56:52.788851 containerd[2129]: time="2026-10-27T10:56:52.787449769Z" level=info msg="Connect containerd service" Oct 27 10:56:52.788851 containerd[2129]: time="2026-10-27T10:56:52.787647793Z" level=info msg="using legacy CRI server" Oct 27 10:56:52.788851 containerd[2129]: time="2026-10-27T10:56:52.787668193Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 27 10:56:52.788851 containerd[2129]: time="2026-10-27T10:56:52.787819885Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 27 10:56:52.794058 containerd[2129]: time="2026-10-27T10:56:52.792016537Z" 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 27 10:56:52.795082 containerd[2129]: time="2026-10-27T10:56:52.795041893Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 27 10:56:52.795306 containerd[2129]: time="2026-10-27T10:56:52.795280273Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797367469Z" level=info msg="Start subscribing containerd event" Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797460613Z" level=info msg="Start recovering state" Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797587837Z" level=info msg="Start event monitor" Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797622637Z" level=info msg="Start snapshots syncer" Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797645401Z" level=info msg="Start cni network conf syncer for default" Oct 27 10:56:52.799483 containerd[2129]: time="2026-10-27T10:56:52.797668177Z" level=info msg="Start streaming server" Oct 27 10:56:52.798098 systemd[1]: Started containerd.service - containerd container runtime. Oct 27 10:56:52.803726 containerd[2129]: time="2026-10-27T10:56:52.801393685Z" level=info msg="containerd successfully booted in 0.123003s" Oct 27 10:56:52.819690 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 27 10:56:52.879005 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] Starting Core Agent Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [amazon-ssm-agent] registrar detected. Attempting registration Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [Registrar] Starting registrar module Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [EC2Identity] EC2 registration was successful. Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [CredentialRefresher] credentialRefresher has started Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [CredentialRefresher] Starting credentials refresher loop Oct 27 10:56:52.881600 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO EC2RoleProvider Successfully connected with instance profile role credentials Oct 27 10:56:52.919619 amazon-ssm-agent[2179]: 2026-10-27 10:56:52 INFO [CredentialRefresher] Next credential rotation will be in 30.366618843366666 minutes Oct 27 10:56:53.053933 sshd_keygen[2136]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 27 10:56:53.096038 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 27 10:56:53.110177 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 27 10:56:53.121307 systemd[1]: Started sshd@0-172.31.16.227:22-20.76.155.168:39102.service - OpenSSH per-connection server daemon (20.76.155.168:39102). Oct 27 10:56:53.145737 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 10:56:53.146539 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 27 10:56:53.160131 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 27 10:56:53.194513 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 27 10:56:53.210867 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 27 10:56:53.218809 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 27 10:56:53.223002 systemd[1]: Reached target getty.target - Login Prompts. Oct 27 10:56:53.908945 amazon-ssm-agent[2179]: 2026-10-27 10:56:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Oct 27 10:56:54.010047 amazon-ssm-agent[2179]: 2026-10-27 10:56:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2352) started Oct 27 10:56:54.109743 amazon-ssm-agent[2179]: 2026-10-27 10:56:53 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Oct 27 10:56:54.122703 sshd[2340]: Accepted publickey for core from 20.76.155.168 port 39102 ssh2: RSA SHA256:0Vp5vhiyy7Hrk1NT9vncDmNt4mDGEb0G9qXwYgiV3Jg Oct 27 10:56:54.125963 sshd[2340]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 10:56:54.147175 systemd-logind[2102]: New session 1 of user core. Oct 27 10:56:54.149664 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 27 10:56:54.159865 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 27 10:56:54.193626 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 27 10:56:54.209840 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 27 10:56:54.236412 (systemd)[2366]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 10:56:54.465309 systemd[2366]: Queued start job for default target default.target. Oct 27 10:56:54.466077 systemd[2366]: Created slice app.slice - User Application Slice. Oct 27 10:56:54.466148 systemd[2366]: Reached target paths.target - Paths. Oct 27 10:56:54.466183 systemd[2366]: Reached target timers.target - Timers. Oct 27 10:56:54.474383 systemd[2366]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 27 10:56:54.491265 systemd[2366]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 27 10:56:54.491394 systemd[2366]: Reached target sockets.target - Sockets. Oct 27 10:56:54.491427 systemd[2366]: Reached target basic.target - Basic System. Oct 27 10:56:54.491516 systemd[2366]: Reached target default.target - Main User Target. Oct 27 10:56:54.491573 systemd[2366]: Startup finished in 243ms. Oct 27 10:56:54.491861 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 27 10:56:54.504719 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 27 10:56:55.196606 systemd[1]: Started sshd@1-172.31.16.227:22-20.76.155.168:39114.service - OpenSSH per-connection server daemon (20.76.155.168:39114). Oct 27 10:56:55.618582 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 10:56:55.623078 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 27 10:56:55.629349 systemd[1]: Startup finished in 9.890s (kernel) + 11.229s (userspace) = 21.120s. Oct 27 10:56:55.652414 (kubelet)[2388]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 27 10:56:56.155242 sshd[2378]: Accepted publickey for core from 20.76.155.168 port 39114 ssh2: RSA SHA256:0Vp5vhiyy7Hrk1NT9vncDmNt4mDGEb0G9qXwYgiV3Jg Oct 27 10:56:56.157675 sshd[2378]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 10:56:56.168940 systemd-logind[2102]: New session 2 of user core. Oct 27 10:56:56.179753 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 27 10:56:56.833464 sshd[2378]: pam_unix(sshd:session): session closed for user core Oct 27 10:56:56.840524 systemd-logind[2102]: Session 2 logged out. Waiting for processes to exit. Oct 27 10:56:56.844360 systemd[1]: sshd@1-172.31.16.227:22-20.76.155.168:39114.service: Deactivated successfully. Oct 27 10:56:56.851541 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 10:56:56.854082 systemd-logind[2102]: Removed session 2. Oct 27 10:56:56.998946 systemd[1]: Started sshd@2-172.31.16.227:22-20.76.155.168:39124.service - OpenSSH per-connection server daemon (20.76.155.168:39124). Oct 27 10:56:57.438520 kubelet[2388]: E1027 10:56:57.438422 2388 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 27 10:56:57.443256 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 27 10:56:57.443668 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 27 10:56:57.963224 sshd[2403]: Accepted publickey for core from 20.76.155.168 port 39124 ssh2: RSA SHA256:0Vp5vhiyy7Hrk1NT9vncDmNt4mDGEb0G9qXwYgiV3Jg Oct 27 10:56:57.964901 sshd[2403]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 10:56:57.974079 systemd-logind[2102]: New session 3 of user core. Oct 27 10:56:57.981686 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 27 10:56:58.107783 systemd-resolved[2027]: Clock change detected. Flushing caches. Oct 27 10:56:58.479063 sshd[2403]: pam_unix(sshd:session): session closed for user core Oct 27 10:56:58.484896 systemd[1]: sshd@2-172.31.16.227:22-20.76.155.168:39124.service: Deactivated successfully. Oct 27 10:56:58.491541 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 10:56:58.493531 systemd-logind[2102]: Session 3 logged out. Waiting for processes to exit. Oct 27 10:56:58.496265 systemd-logind[2102]: Removed session 3. Oct 27 10:56:58.644706 systemd[1]: Started sshd@3-172.31.16.227:22-20.76.155.168:39132.service - OpenSSH per-connection server daemon (20.76.155.168:39132). Oct 27 10:56:59.600710 sshd[2415]: Accepted publickey for core from 20.76.155.168 port 39132 ssh2: RSA SHA256:0Vp5vhiyy7Hrk1NT9vncDmNt4mDGEb0G9qXwYgiV3Jg Oct 27 10:56:59.603267 sshd[2415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 10:56:59.610758 systemd-logind[2102]: New session 4 of user core. Oct 27 10:56:59.619272 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 27 10:57:00.266918 sshd[2415]: pam_unix(sshd:session): session closed for user core Oct 27 10:57:00.273484 systemd[1]: sshd@3-172.31.16.227:22-20.76.155.168:39132.service: Deactivated successfully. Oct 27 10:57:00.279298 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 10:57:00.281009 systemd-logind[2102]: Session 4 logged out. Waiting for processes to exit. Oct 27 10:57:00.282778 systemd-logind[2102]: Removed session 4. Oct 27 10:57:00.434244 systemd[1]: Started sshd@4-172.31.16.227:22-20.76.155.168:42400.service - OpenSSH per-connection server daemon (20.76.155.168:42400). Oct 27 10:57:01.395441 sshd[2423]: Accepted publickey for core from 20.76.155.168 port 42400 ssh2: RSA SHA256:0Vp5vhiyy7Hrk1NT9vncDmNt4mDGEb0G9qXwYgiV3Jg Oct 27 10:57:01.397128 sshd[2423]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 10:57:01.405531 systemd-logind[2102]: New session 5 of user core. Oct 27 10:57:01.417311 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 27 10:57:01.927666 sudo[2427]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 27 10:57:01.928352 sudo[2427]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 10:57:03.754491 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 10:57:03.766312 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 10:57:03.836456 systemd[1]: Reloading requested from client PID 2468 ('systemctl') (unit session-5.scope)... Oct 27 10:57:03.836483 systemd[1]: Reloading... Oct 27 10:57:04.078854 zram_generator::config[2514]: No configuration found. Oct 27 10:57:04.327560 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 27 10:57:04.498684 systemd[1]: Reloading finished in 661 ms. Oct 27 10:57:04.586783 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 27 10:57:04.587316 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 27 10:57:04.588198 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 10:57:04.596536 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 10:57:04.946152 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 10:57:04.968516 (kubelet)[2583]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 27 10:57:05.037421 kubelet[2583]: 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 27 10:57:05.670158 kubelet[2583]: I1027 10:57:05.670062 2583 server.go:525] "Kubelet version" kubeletVersion="v1.35.1" Oct 27 10:57:05.670158 kubelet[2583]: I1027 10:57:05.670141 2583 server.go:527] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 27 10:57:05.673406 kubelet[2583]: I1027 10:57:05.672844 2583 watchdog_linux.go:95] "Systemd watchdog is not enabled" Oct 27 10:57:05.673406 kubelet[2583]: I1027 10:57:05.672920 2583 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 27 10:57:05.674845 kubelet[2583]: I1027 10:57:05.674787 2583 server.go:951] "Client rotation is on, will bootstrap in background" Oct 27 10:57:05.680852 kubelet[2583]: I1027 10:57:05.680788 2583 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 27 10:57:05.686868 kubelet[2583]: E1027 10:57:05.685795 2583 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 27 10:57:05.686868 kubelet[2583]: I1027 10:57:05.685900 2583 server.go:1395] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Oct 27 10:57:05.691522 kubelet[2583]: I1027 10:57:05.691489 2583 server.go:775] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Oct 27 10:57:05.692579 kubelet[2583]: I1027 10:57:05.692539 2583 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 27 10:57:05.692973 kubelet[2583]: I1027 10:57:05.692673 2583 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.16.227","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} Oct 27 10:57:05.693183 kubelet[2583]: I1027 10:57:05.693163 2583 topology_manager.go:143] "Creating topology manager with none policy" Oct 27 10:57:05.693274 kubelet[2583]: I1027 10:57:05.693257 2583 container_manager_linux.go:308] "Creating device plugin manager" Oct 27 10:57:05.693517 kubelet[2583]: I1027 10:57:05.693498 2583 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager" Oct 27 10:57:05.696793 kubelet[2583]: I1027 10:57:05.696748 2583 state_mem.go:41] "Initialized" logger="CPUManager state memory" Oct 27 10:57:05.697736 kubelet[2583]: I1027 10:57:05.697438 2583 kubelet.go:482] "Attempting to sync node with API server" Oct 27 10:57:05.697736 kubelet[2583]: I1027 10:57:05.697473 2583 kubelet.go:383] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 27 10:57:05.697736 kubelet[2583]: I1027 10:57:05.697506 2583 kubelet.go:394] "Adding apiserver pod source" Oct 27 10:57:05.697736 kubelet[2583]: I1027 10:57:05.697524 2583 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 27 10:57:05.698046 kubelet[2583]: E1027 10:57:05.697988 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:05.698046 kubelet[2583]: E1027 10:57:05.698033 2583 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:05.703940 kubelet[2583]: I1027 10:57:05.703894 2583 kuberuntime_manager.go:294] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Oct 27 10:57:05.705775 kubelet[2583]: I1027 10:57:05.705737 2583 kubelet.go:943] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 27 10:57:05.705902 kubelet[2583]: I1027 10:57:05.705801 2583 kubelet.go:970] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Oct 27 10:57:05.705902 kubelet[2583]: W1027 10:57:05.705894 2583 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 27 10:57:05.713890 kubelet[2583]: I1027 10:57:05.712067 2583 server.go:1257] "Started kubelet" Oct 27 10:57:05.715627 kubelet[2583]: I1027 10:57:05.715554 2583 server.go:182] "Starting to listen" address="0.0.0.0" port=10250 Oct 27 10:57:05.717551 kubelet[2583]: I1027 10:57:05.717519 2583 server.go:317] "Adding debug handlers to kubelet server" Oct 27 10:57:05.728271 kubelet[2583]: I1027 10:57:05.721468 2583 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Oct 27 10:57:05.736119 kubelet[2583]: I1027 10:57:05.721505 2583 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 27 10:57:05.736381 kubelet[2583]: I1027 10:57:05.736337 2583 server_v1.go:49] "podresources" method="list" useActivePods=true Oct 27 10:57:05.736742 kubelet[2583]: I1027 10:57:05.736718 2583 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 27 10:57:05.741463 kubelet[2583]: I1027 10:57:05.721878 2583 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 27 10:57:05.741630 kubelet[2583]: I1027 10:57:05.738039 2583 volume_manager.go:311] "Starting Kubelet Volume Manager" Oct 27 10:57:05.742750 kubelet[2583]: I1027 10:57:05.738056 2583 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Oct 27 10:57:05.749231 kubelet[2583]: E1027 10:57:05.738088 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:05.749231 kubelet[2583]: E1027 10:57:05.740325 2583 reflector.go:204] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Service" Oct 27 10:57:05.749231 kubelet[2583]: E1027 10:57:05.740592 2583 reflector.go:204] "Failed to watch" err="failed to list *v1.Node: nodes \"172.31.16.227\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Node" Oct 27 10:57:05.749231 kubelet[2583]: I1027 10:57:05.742998 2583 reconciler.go:29] "Reconciler: start to sync state" Oct 27 10:57:05.749231 kubelet[2583]: I1027 10:57:05.743557 2583 factory.go:223] Registration of the systemd container factory successfully Oct 27 10:57:05.749231 kubelet[2583]: I1027 10:57:05.748345 2583 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 27 10:57:05.750471 kubelet[2583]: E1027 10:57:05.750412 2583 controller.go:201] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.16.227\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Oct 27 10:57:05.754992 kubelet[2583]: E1027 10:57:05.754952 2583 kubelet.go:1656] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 27 10:57:05.756696 kubelet[2583]: I1027 10:57:05.756531 2583 factory.go:223] Registration of the containerd container factory successfully Oct 27 10:57:05.792352 kubelet[2583]: I1027 10:57:05.792302 2583 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Oct 27 10:57:05.810776 kubelet[2583]: I1027 10:57:05.810742 2583 cpu_manager.go:225] "Starting" policy="none" Oct 27 10:57:05.811368 kubelet[2583]: I1027 10:57:05.811011 2583 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s" Oct 27 10:57:05.811368 kubelet[2583]: I1027 10:57:05.811049 2583 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Oct 27 10:57:05.813600 kubelet[2583]: I1027 10:57:05.813572 2583 policy_none.go:50] "Start" Oct 27 10:57:05.813862 kubelet[2583]: I1027 10:57:05.813796 2583 memory_manager.go:187] "Starting memorymanager" policy="None" Oct 27 10:57:05.813995 kubelet[2583]: I1027 10:57:05.813974 2583 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Oct 27 10:57:05.816569 kubelet[2583]: I1027 10:57:05.816537 2583 policy_none.go:44] "Start" Oct 27 10:57:05.827129 kubelet[2583]: E1027 10:57:05.827089 2583 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 27 10:57:05.830864 kubelet[2583]: I1027 10:57:05.829864 2583 eviction_manager.go:194] "Eviction manager: starting control loop" Oct 27 10:57:05.830864 kubelet[2583]: I1027 10:57:05.829897 2583 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 27 10:57:05.832367 kubelet[2583]: I1027 10:57:05.832317 2583 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Oct 27 10:57:05.835446 kubelet[2583]: E1027 10:57:05.835384 2583 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 27 10:57:05.837605 kubelet[2583]: E1027 10:57:05.837567 2583 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.16.227\" not found" Oct 27 10:57:05.870507 kubelet[2583]: I1027 10:57:05.870467 2583 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Oct 27 10:57:05.870740 kubelet[2583]: I1027 10:57:05.870723 2583 status_manager.go:249] "Starting to sync pod status with apiserver" Oct 27 10:57:05.870967 kubelet[2583]: I1027 10:57:05.870948 2583 kubelet.go:2501] "Starting kubelet main sync loop" Oct 27 10:57:05.871207 kubelet[2583]: E1027 10:57:05.871181 2583 kubelet.go:2525] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Oct 27 10:57:05.935296 kubelet[2583]: I1027 10:57:05.935148 2583 kubelet_node_status.go:74] "Attempting to register node" node="172.31.16.227" Oct 27 10:57:05.947970 kubelet[2583]: I1027 10:57:05.947924 2583 kubelet_node_status.go:77] "Successfully registered node" node="172.31.16.227" Oct 27 10:57:05.947970 kubelet[2583]: E1027 10:57:05.947981 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.16.227\": node \"172.31.16.227\" not found" Oct 27 10:57:05.975670 sudo[2427]: pam_unix(sudo:session): session closed for user root Oct 27 10:57:05.979526 kubelet[2583]: E1027 10:57:05.979479 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.080118 kubelet[2583]: E1027 10:57:06.080062 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.131605 sshd[2423]: pam_unix(sshd:session): session closed for user core Oct 27 10:57:06.139137 systemd[1]: sshd@4-172.31.16.227:22-20.76.155.168:42400.service: Deactivated successfully. Oct 27 10:57:06.146358 systemd-logind[2102]: Session 5 logged out. Waiting for processes to exit. Oct 27 10:57:06.146380 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 10:57:06.151173 systemd-logind[2102]: Removed session 5. Oct 27 10:57:06.181136 kubelet[2583]: E1027 10:57:06.181079 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.281307 kubelet[2583]: E1027 10:57:06.281251 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.382055 kubelet[2583]: E1027 10:57:06.381995 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.482845 kubelet[2583]: E1027 10:57:06.482774 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.583682 kubelet[2583]: E1027 10:57:06.583544 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.677407 kubelet[2583]: I1027 10:57:06.677351 2583 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 27 10:57:06.677627 kubelet[2583]: I1027 10:57:06.677577 2583 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Oct 27 10:57:06.677690 kubelet[2583]: I1027 10:57:06.677578 2583 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Oct 27 10:57:06.683675 kubelet[2583]: E1027 10:57:06.683630 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.698926 kubelet[2583]: E1027 10:57:06.698876 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:06.784702 kubelet[2583]: E1027 10:57:06.784644 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.885130 kubelet[2583]: E1027 10:57:06.885000 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:06.985144 kubelet[2583]: E1027 10:57:06.985099 2583 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.16.227\" not found" Oct 27 10:57:07.086244 kubelet[2583]: I1027 10:57:07.086189 2583 kuberuntime_manager.go:2062] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 27 10:57:07.086987 containerd[2129]: time="2026-10-27T10:57:07.086721808Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 27 10:57:07.088101 kubelet[2583]: I1027 10:57:07.087045 2583 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 27 10:57:07.698937 kubelet[2583]: I1027 10:57:07.698893 2583 apiserver.go:52] "Watching apiserver" Oct 27 10:57:07.699083 kubelet[2583]: E1027 10:57:07.699000 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:07.748434 kubelet[2583]: I1027 10:57:07.748376 2583 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Oct 27 10:57:07.761115 kubelet[2583]: I1027 10:57:07.760898 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-cni\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:07.761115 kubelet[2583]: I1027 10:57:07.760957 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-xtables-lock\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:07.761115 kubelet[2583]: I1027 10:57:07.761006 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/a9a1aac4-b99c-4b2b-9af2-02e77f011b0c-kube-proxy\") pod \"kube-proxy-7fw7v\" (UID: \"a9a1aac4-b99c-4b2b-9af2-02e77f011b0c\") " pod="kube-system/kube-proxy-7fw7v" Oct 27 10:57:07.761115 kubelet[2583]: I1027 10:57:07.761044 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a9a1aac4-b99c-4b2b-9af2-02e77f011b0c-xtables-lock\") pod \"kube-proxy-7fw7v\" (UID: \"a9a1aac4-b99c-4b2b-9af2-02e77f011b0c\") " pod="kube-system/kube-proxy-7fw7v" Oct 27 10:57:07.761115 kubelet[2583]: I1027 10:57:07.761078 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/a9a1aac4-b99c-4b2b-9af2-02e77f011b0c-lib-modules\") pod \"kube-proxy-7fw7v\" (UID: \"a9a1aac4-b99c-4b2b-9af2-02e77f011b0c\") " pod="kube-system/kube-proxy-7fw7v" Oct 27 10:57:07.761463 kubelet[2583]: I1027 10:57:07.761171 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6c8b4\" (UniqueName: \"kubernetes.io/projected/a9a1aac4-b99c-4b2b-9af2-02e77f011b0c-kube-api-access-6c8b4\") pod \"kube-proxy-7fw7v\" (UID: \"a9a1aac4-b99c-4b2b-9af2-02e77f011b0c\") " pod="kube-system/kube-proxy-7fw7v" Oct 27 10:57:07.761463 kubelet[2583]: I1027 10:57:07.761247 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-cni-plugin\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:07.761463 kubelet[2583]: I1027 10:57:07.761312 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-flannel-cfg\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:07.761463 kubelet[2583]: I1027 10:57:07.761390 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4q6v9\" (UniqueName: \"kubernetes.io/projected/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-kube-api-access-4q6v9\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:07.761463 kubelet[2583]: I1027 10:57:07.761432 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/067be9f6-1fae-4fec-8dd3-169cac0f3ee1-run\") pod \"kube-flannel-ds-9rfnc\" (UID: \"067be9f6-1fae-4fec-8dd3-169cac0f3ee1\") " pod="kube-flannel/kube-flannel-ds-9rfnc" Oct 27 10:57:08.019954 containerd[2129]: time="2026-10-27T10:57:08.019895129Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7fw7v,Uid:a9a1aac4-b99c-4b2b-9af2-02e77f011b0c,Namespace:kube-system,Attempt:0,}" Oct 27 10:57:08.028957 containerd[2129]: time="2026-10-27T10:57:08.028500065Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9rfnc,Uid:067be9f6-1fae-4fec-8dd3-169cac0f3ee1,Namespace:kube-flannel,Attempt:0,}" Oct 27 10:57:08.601447 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2277237755.mount: Deactivated successfully. Oct 27 10:57:08.614030 containerd[2129]: time="2026-10-27T10:57:08.613952360Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 27 10:57:08.615742 containerd[2129]: time="2026-10-27T10:57:08.615620288Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 27 10:57:08.616730 containerd[2129]: time="2026-10-27T10:57:08.616685024Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Oct 27 10:57:08.617532 containerd[2129]: time="2026-10-27T10:57:08.617427476Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269321" Oct 27 10:57:08.619139 containerd[2129]: time="2026-10-27T10:57:08.618743744Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 27 10:57:08.626403 containerd[2129]: time="2026-10-27T10:57:08.626328728Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 27 10:57:08.629868 containerd[2129]: time="2026-10-27T10:57:08.628377740Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 599.761695ms" Oct 27 10:57:08.632969 containerd[2129]: time="2026-10-27T10:57:08.632912180Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 612.883947ms" Oct 27 10:57:08.699584 kubelet[2583]: E1027 10:57:08.699263 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:08.811638 containerd[2129]: time="2026-10-27T10:57:08.811462881Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 27 10:57:08.811953 containerd[2129]: time="2026-10-27T10:57:08.811619169Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 27 10:57:08.811953 containerd[2129]: time="2026-10-27T10:57:08.811659393Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:08.812167 containerd[2129]: time="2026-10-27T10:57:08.811973877Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:08.816784 containerd[2129]: time="2026-10-27T10:57:08.816638601Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 27 10:57:08.816959 containerd[2129]: time="2026-10-27T10:57:08.816748089Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 27 10:57:08.816959 containerd[2129]: time="2026-10-27T10:57:08.816787485Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:08.817246 containerd[2129]: time="2026-10-27T10:57:08.817172061Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:08.937698 systemd[1]: run-containerd-runc-k8s.io-6ddc0610b1cdf0bdc4078de7dd58facd1fba04741ab6fbbc801a06f57b62008b-runc.UuggJV.mount: Deactivated successfully. Oct 27 10:57:09.013520 containerd[2129]: time="2026-10-27T10:57:09.013465854Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7fw7v,Uid:a9a1aac4-b99c-4b2b-9af2-02e77f011b0c,Namespace:kube-system,Attempt:0,} returns sandbox id \"6ddc0610b1cdf0bdc4078de7dd58facd1fba04741ab6fbbc801a06f57b62008b\"" Oct 27 10:57:09.021472 containerd[2129]: time="2026-10-27T10:57:09.021413622Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.9\"" Oct 27 10:57:09.031598 containerd[2129]: time="2026-10-27T10:57:09.031497618Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9rfnc,Uid:067be9f6-1fae-4fec-8dd3-169cac0f3ee1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\"" Oct 27 10:57:09.701164 kubelet[2583]: E1027 10:57:09.700115 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:10.547607 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3022018554.mount: Deactivated successfully. Oct 27 10:57:10.701363 kubelet[2583]: E1027 10:57:10.701251 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:10.938599 containerd[2129]: time="2026-10-27T10:57:10.938431103Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.35.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:10.940861 containerd[2129]: time="2026-10-27T10:57:10.940468307Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.35.9: active requests=0, bytes read=23745751" Oct 27 10:57:10.942222 containerd[2129]: time="2026-10-27T10:57:10.942144755Z" level=info msg="ImageCreate event name:\"sha256:f935e45af8074d1ce0133285843441be67fb7d8c4ee8565d72eb37cbe2cc7722\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:10.946143 containerd[2129]: time="2026-10-27T10:57:10.946074947Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:10.948494 containerd[2129]: time="2026-10-27T10:57:10.947609555Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.35.9\" with image id \"sha256:f935e45af8074d1ce0133285843441be67fb7d8c4ee8565d72eb37cbe2cc7722\", repo tag \"registry.k8s.io/kube-proxy:v1.35.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\", size \"23744585\" in 1.925856825s" Oct 27 10:57:10.948494 containerd[2129]: time="2026-10-27T10:57:10.947680007Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.9\" returns image reference \"sha256:f935e45af8074d1ce0133285843441be67fb7d8c4ee8565d72eb37cbe2cc7722\"" Oct 27 10:57:10.950721 containerd[2129]: time="2026-10-27T10:57:10.950676707Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 27 10:57:10.955696 containerd[2129]: time="2026-10-27T10:57:10.955622795Z" level=info msg="CreateContainer within sandbox \"6ddc0610b1cdf0bdc4078de7dd58facd1fba04741ab6fbbc801a06f57b62008b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 27 10:57:10.980112 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3063630779.mount: Deactivated successfully. Oct 27 10:57:10.980894 containerd[2129]: time="2026-10-27T10:57:10.980571203Z" level=info msg="CreateContainer within sandbox \"6ddc0610b1cdf0bdc4078de7dd58facd1fba04741ab6fbbc801a06f57b62008b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"71c370b197da8837a8b038ccea3bf774ac34236220ef53bff0351ecc60295661\"" Oct 27 10:57:10.983097 containerd[2129]: time="2026-10-27T10:57:10.983048087Z" level=info msg="StartContainer for \"71c370b197da8837a8b038ccea3bf774ac34236220ef53bff0351ecc60295661\"" Oct 27 10:57:11.097688 containerd[2129]: time="2026-10-27T10:57:11.097631336Z" level=info msg="StartContainer for \"71c370b197da8837a8b038ccea3bf774ac34236220ef53bff0351ecc60295661\" returns successfully" Oct 27 10:57:11.702240 kubelet[2583]: E1027 10:57:11.702169 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:12.296707 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2907506687.mount: Deactivated successfully. Oct 27 10:57:12.357577 containerd[2129]: time="2026-10-27T10:57:12.357502822Z" 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 27 10:57:12.359715 containerd[2129]: time="2026-10-27T10:57:12.359530786Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 27 10:57:12.360616 containerd[2129]: time="2026-10-27T10:57:12.360527122Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:12.368949 containerd[2129]: time="2026-10-27T10:57:12.368868370Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:12.371409 containerd[2129]: time="2026-10-27T10:57:12.371360350Z" 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.420414579s" Oct 27 10:57:12.371675 containerd[2129]: time="2026-10-27T10:57:12.371526622Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 27 10:57:12.379980 containerd[2129]: time="2026-10-27T10:57:12.379626706Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 27 10:57:12.400157 containerd[2129]: time="2026-10-27T10:57:12.400051702Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6\"" Oct 27 10:57:12.401228 containerd[2129]: time="2026-10-27T10:57:12.401126050Z" level=info msg="StartContainer for \"e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6\"" Oct 27 10:57:12.489319 containerd[2129]: time="2026-10-27T10:57:12.489250175Z" level=info msg="StartContainer for \"e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6\" returns successfully" Oct 27 10:57:12.703619 kubelet[2583]: E1027 10:57:12.702854 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:12.918022 kubelet[2583]: I1027 10:57:12.917516 2583 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-system/kube-proxy-7fw7v" podStartSLOduration=5.988974408 podStartE2EDuration="7.917477449s" podCreationTimestamp="2026-10-27 10:57:05 +0000 UTC" firstStartedPulling="2026-10-27 10:57:09.020490102 +0000 UTC m=+4.045973613" lastFinishedPulling="2026-10-27 10:57:10.948993119 +0000 UTC m=+5.974476654" observedRunningTime="2026-10-27 10:57:11.911330892 +0000 UTC m=+6.936814439" watchObservedRunningTime="2026-10-27 10:57:12.917477449 +0000 UTC m=+7.942960972" Oct 27 10:57:12.931142 containerd[2129]: time="2026-10-27T10:57:12.930775897Z" level=info msg="shim disconnected" id=e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6 namespace=k8s.io Oct 27 10:57:12.931142 containerd[2129]: time="2026-10-27T10:57:12.930883621Z" level=warning msg="cleaning up after shim disconnected" id=e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6 namespace=k8s.io Oct 27 10:57:12.931142 containerd[2129]: time="2026-10-27T10:57:12.930904525Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 27 10:57:13.123407 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e5b570d0e66dccd87523d03ae644ef2c11a8440fa4b7e8727506a0db932f40b6-rootfs.mount: Deactivated successfully. Oct 27 10:57:13.704051 kubelet[2583]: E1027 10:57:13.703980 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:13.906166 containerd[2129]: time="2026-10-27T10:57:13.906079334Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 27 10:57:14.704729 kubelet[2583]: E1027 10:57:14.704674 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:15.705641 kubelet[2583]: E1027 10:57:15.705590 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:16.327529 containerd[2129]: time="2026-10-27T10:57:16.327461714Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:16.330441 containerd[2129]: time="2026-10-27T10:57:16.330368198Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 27 10:57:16.331866 containerd[2129]: time="2026-10-27T10:57:16.330906062Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:16.338735 containerd[2129]: time="2026-10-27T10:57:16.338647718Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:16.342164 containerd[2129]: time="2026-10-27T10:57:16.341973938Z" 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.435807268s" Oct 27 10:57:16.342164 containerd[2129]: time="2026-10-27T10:57:16.342032498Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 27 10:57:16.349373 containerd[2129]: time="2026-10-27T10:57:16.349300982Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 27 10:57:16.371448 containerd[2129]: time="2026-10-27T10:57:16.371288870Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e\"" Oct 27 10:57:16.373797 containerd[2129]: time="2026-10-27T10:57:16.372215090Z" level=info msg="StartContainer for \"a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e\"" Oct 27 10:57:16.465493 containerd[2129]: time="2026-10-27T10:57:16.465428439Z" level=info msg="StartContainer for \"a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e\" returns successfully" Oct 27 10:57:16.472490 kubelet[2583]: I1027 10:57:16.472440 2583 kubelet_node_status.go:427] "Fast updating node status as it just became ready" Oct 27 10:57:16.503432 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e-rootfs.mount: Deactivated successfully. Oct 27 10:57:16.626850 containerd[2129]: time="2026-10-27T10:57:16.626615187Z" level=info msg="shim disconnected" id=a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e namespace=k8s.io Oct 27 10:57:16.626850 containerd[2129]: time="2026-10-27T10:57:16.626691003Z" level=warning msg="cleaning up after shim disconnected" id=a2de1f205160c4a643534340d68fa9b0c1ec1cb966e1f7a9662bb935ecab550e namespace=k8s.io Oct 27 10:57:16.627359 containerd[2129]: time="2026-10-27T10:57:16.626715807Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 27 10:57:16.706180 kubelet[2583]: E1027 10:57:16.706115 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:16.931315 containerd[2129]: time="2026-10-27T10:57:16.931156937Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 27 10:57:16.953195 containerd[2129]: time="2026-10-27T10:57:16.953118977Z" level=info msg="CreateContainer within sandbox \"4f185c2636735d26f4b1775569b5bb85caf7f7bde690cd650f9cb9ec403b75d8\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"35965c0b67f3d800a3604f2ae4d6ed1ba004da02b1fe372994d4860003ca7952\"" Oct 27 10:57:16.954086 containerd[2129]: time="2026-10-27T10:57:16.954036413Z" level=info msg="StartContainer for \"35965c0b67f3d800a3604f2ae4d6ed1ba004da02b1fe372994d4860003ca7952\"" Oct 27 10:57:17.039393 containerd[2129]: time="2026-10-27T10:57:17.039229370Z" level=info msg="StartContainer for \"35965c0b67f3d800a3604f2ae4d6ed1ba004da02b1fe372994d4860003ca7952\" returns successfully" Oct 27 10:57:17.707044 kubelet[2583]: E1027 10:57:17.706980 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:17.949724 kubelet[2583]: I1027 10:57:17.949645 2583 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-9rfnc" podStartSLOduration=5.057285151 podStartE2EDuration="12.949626522s" podCreationTimestamp="2026-10-27 10:57:05 +0000 UTC" firstStartedPulling="2026-10-27 10:57:09.03370239 +0000 UTC m=+4.059185889" lastFinishedPulling="2026-10-27 10:57:16.926043749 +0000 UTC m=+11.951527260" observedRunningTime="2026-10-27 10:57:17.949562646 +0000 UTC m=+12.975046193" watchObservedRunningTime="2026-10-27 10:57:17.949626522 +0000 UTC m=+12.975110033" Oct 27 10:57:18.148640 (udev-worker)[3088]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:57:18.163589 systemd-networkd[1692]: flannel.1: Link UP Oct 27 10:57:18.163626 systemd-networkd[1692]: flannel.1: Gained carrier Oct 27 10:57:18.707312 kubelet[2583]: E1027 10:57:18.707248 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:19.707995 kubelet[2583]: E1027 10:57:19.707928 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:20.032439 systemd-networkd[1692]: flannel.1: Gained IPv6LL Oct 27 10:57:20.708894 kubelet[2583]: E1027 10:57:20.708842 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:21.709355 kubelet[2583]: E1027 10:57:21.709288 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:22.107735 ntpd[2082]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 27 10:57:22.108545 ntpd[2082]: 27 Oct 10:57:22 ntpd[2082]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 27 10:57:22.108545 ntpd[2082]: 27 Oct 10:57:22 ntpd[2082]: Listen normally on 7 flannel.1 [fe80::6056:50ff:fe87:7e56%3]:123 Oct 27 10:57:22.107894 ntpd[2082]: Listen normally on 7 flannel.1 [fe80::6056:50ff:fe87:7e56%3]:123 Oct 27 10:57:22.617695 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 27 10:57:22.709510 kubelet[2583]: E1027 10:57:22.709439 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:23.709648 kubelet[2583]: E1027 10:57:23.709582 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:23.868913 kubelet[2583]: I1027 10:57:23.868840 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-w59qs\" (UniqueName: \"kubernetes.io/projected/f8f6aad9-46ca-41d0-a380-812df7c3e12f-kube-api-access-w59qs\") pod \"nginx-deployment-6cc69d4fc7-nmq2d\" (UID: \"f8f6aad9-46ca-41d0-a380-812df7c3e12f\") " pod="default/nginx-deployment-6cc69d4fc7-nmq2d" Oct 27 10:57:24.128803 containerd[2129]: time="2026-10-27T10:57:24.128748021Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-nmq2d,Uid:f8f6aad9-46ca-41d0-a380-812df7c3e12f,Namespace:default,Attempt:0,}" Oct 27 10:57:24.174498 systemd-networkd[1692]: cni0: Link UP Oct 27 10:57:24.174512 systemd-networkd[1692]: cni0: Gained carrier Oct 27 10:57:24.184491 systemd-networkd[1692]: vethb29c4122: Link UP Oct 27 10:57:24.187604 kernel: cni0: port 1(vethb29c4122) entered blocking state Oct 27 10:57:24.187704 kernel: cni0: port 1(vethb29c4122) entered disabled state Oct 27 10:57:24.187747 kernel: vethb29c4122: entered allmulticast mode Oct 27 10:57:24.190124 kernel: vethb29c4122: entered promiscuous mode Oct 27 10:57:24.190609 systemd-networkd[1692]: cni0: Lost carrier Oct 27 10:57:24.190849 (udev-worker)[3183]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:57:24.192663 (udev-worker)[3186]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:57:24.216956 kernel: cni0: port 1(vethb29c4122) entered blocking state Oct 27 10:57:24.217184 kernel: cni0: port 1(vethb29c4122) entered forwarding state Oct 27 10:57:24.221044 systemd-networkd[1692]: vethb29c4122: Gained carrier Oct 27 10:57:24.225947 systemd-networkd[1692]: cni0: Gained carrier Oct 27 10:57:24.227791 containerd[2129]: 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 27 10:57:24.227791 containerd[2129]: delegateAdd: netconf sent to delegate plugin: Oct 27 10:57:24.265165 containerd[2129]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2026-10-27T10:57:24.264966081Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 27 10:57:24.266511 containerd[2129]: time="2026-10-27T10:57:24.265463145Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 27 10:57:24.266511 containerd[2129]: time="2026-10-27T10:57:24.266198001Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:24.266511 containerd[2129]: time="2026-10-27T10:57:24.266398833Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:24.361963 containerd[2129]: time="2026-10-27T10:57:24.361861138Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-nmq2d,Uid:f8f6aad9-46ca-41d0-a380-812df7c3e12f,Namespace:default,Attempt:0,} returns sandbox id \"4bfe2e7f56bcdd069e626acd3c0116292f68dd44ebc10773c62b5f568152d0e8\"" Oct 27 10:57:24.364691 containerd[2129]: time="2026-10-27T10:57:24.364408930Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 27 10:57:24.710046 kubelet[2583]: E1027 10:57:24.709978 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:25.408489 systemd-networkd[1692]: cni0: Gained IPv6LL Oct 27 10:57:25.697996 kubelet[2583]: E1027 10:57:25.697745 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:25.710697 kubelet[2583]: E1027 10:57:25.710625 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:25.792153 systemd-networkd[1692]: vethb29c4122: Gained IPv6LL Oct 27 10:57:26.711759 kubelet[2583]: E1027 10:57:26.711693 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:27.514731 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1410062472.mount: Deactivated successfully. Oct 27 10:57:27.713204 kubelet[2583]: E1027 10:57:27.712773 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:28.107687 ntpd[2082]: Listen normally on 8 cni0 192.168.1.1:123 Oct 27 10:57:28.107848 ntpd[2082]: Listen normally on 9 cni0 [fe80::5cda:4ff:feb4:5af8%4]:123 Oct 27 10:57:28.108301 ntpd[2082]: 27 Oct 10:57:28 ntpd[2082]: Listen normally on 8 cni0 192.168.1.1:123 Oct 27 10:57:28.108301 ntpd[2082]: 27 Oct 10:57:28 ntpd[2082]: Listen normally on 9 cni0 [fe80::5cda:4ff:feb4:5af8%4]:123 Oct 27 10:57:28.108301 ntpd[2082]: 27 Oct 10:57:28 ntpd[2082]: Listen normally on 10 vethb29c4122 [fe80::a838:baff:fee5:a280%5]:123 Oct 27 10:57:28.107937 ntpd[2082]: Listen normally on 10 vethb29c4122 [fe80::a838:baff:fee5:a280%5]:123 Oct 27 10:57:28.713233 kubelet[2583]: E1027 10:57:28.713163 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:28.826854 containerd[2129]: time="2026-10-27T10:57:28.825624676Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:28.828860 containerd[2129]: time="2026-10-27T10:57:28.828781732Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=63102541" Oct 27 10:57:28.830554 containerd[2129]: time="2026-10-27T10:57:28.830509708Z" level=info msg="ImageCreate event name:\"sha256:aad3c5ef3cbc1c541a85b31130ad500eefdcd7e0e9a340c42632f00420459296\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:28.837414 containerd[2129]: time="2026-10-27T10:57:28.837362452Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:f867c76db3679f8221a6d4b4797d33df9ca88c36704f34c3217c0d8a646232ad\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:28.838910 containerd[2129]: time="2026-10-27T10:57:28.838867444Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:aad3c5ef3cbc1c541a85b31130ad500eefdcd7e0e9a340c42632f00420459296\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:f867c76db3679f8221a6d4b4797d33df9ca88c36704f34c3217c0d8a646232ad\", size \"63102419\" in 4.474402102s" Oct 27 10:57:28.839074 containerd[2129]: time="2026-10-27T10:57:28.839045068Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:aad3c5ef3cbc1c541a85b31130ad500eefdcd7e0e9a340c42632f00420459296\"" Oct 27 10:57:28.846713 containerd[2129]: time="2026-10-27T10:57:28.846654712Z" level=info msg="CreateContainer within sandbox \"4bfe2e7f56bcdd069e626acd3c0116292f68dd44ebc10773c62b5f568152d0e8\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 27 10:57:28.865474 containerd[2129]: time="2026-10-27T10:57:28.865397248Z" level=info msg="CreateContainer within sandbox \"4bfe2e7f56bcdd069e626acd3c0116292f68dd44ebc10773c62b5f568152d0e8\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"380139eaf10d9ff8ba4d7055919a3502b734db91260aa3c5c7d91e511b17c5b3\"" Oct 27 10:57:28.867672 containerd[2129]: time="2026-10-27T10:57:28.866157196Z" level=info msg="StartContainer for \"380139eaf10d9ff8ba4d7055919a3502b734db91260aa3c5c7d91e511b17c5b3\"" Oct 27 10:57:28.924980 systemd[1]: run-containerd-runc-k8s.io-380139eaf10d9ff8ba4d7055919a3502b734db91260aa3c5c7d91e511b17c5b3-runc.ztQGpG.mount: Deactivated successfully. Oct 27 10:57:28.979077 containerd[2129]: time="2026-10-27T10:57:28.978797369Z" level=info msg="StartContainer for \"380139eaf10d9ff8ba4d7055919a3502b734db91260aa3c5c7d91e511b17c5b3\" returns successfully" Oct 27 10:57:29.714338 kubelet[2583]: E1027 10:57:29.714278 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:30.714464 kubelet[2583]: E1027 10:57:30.714403 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:31.714925 kubelet[2583]: E1027 10:57:31.714859 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:32.715640 kubelet[2583]: E1027 10:57:32.715580 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:33.715953 kubelet[2583]: E1027 10:57:33.715888 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:34.716939 kubelet[2583]: E1027 10:57:34.716861 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:35.717523 kubelet[2583]: E1027 10:57:35.717461 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:36.382035 update_engine[2104]: I20261027 10:57:36.381943 2104 update_attempter.cc:509] Updating boot flags... Oct 27 10:57:36.457990 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (3382) Oct 27 10:57:36.695229 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (3384) Oct 27 10:57:36.717687 kubelet[2583]: E1027 10:57:36.717638 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:36.929973 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 36 scanned by (udev-worker) (3384) Oct 27 10:57:37.401644 kubelet[2583]: I1027 10:57:37.401569 2583 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nginx-deployment-6cc69d4fc7-nmq2d" podStartSLOduration=9.923246769 podStartE2EDuration="14.401548367s" podCreationTimestamp="2026-10-27 10:57:23 +0000 UTC" firstStartedPulling="2026-10-27 10:57:24.36348541 +0000 UTC m=+19.388968921" lastFinishedPulling="2026-10-27 10:57:28.841787008 +0000 UTC m=+23.867270519" observedRunningTime="2026-10-27 10:57:29.980074326 +0000 UTC m=+25.005557849" watchObservedRunningTime="2026-10-27 10:57:37.401548367 +0000 UTC m=+32.427031902" Oct 27 10:57:37.452393 kubelet[2583]: I1027 10:57:37.452317 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6dxpw\" (UniqueName: \"kubernetes.io/projected/327c0d2b-9dae-4f7a-887a-9d690e7a238c-kube-api-access-6dxpw\") pod \"nfs-server-provisioner-0\" (UID: \"327c0d2b-9dae-4f7a-887a-9d690e7a238c\") " pod="default/nfs-server-provisioner-0" Oct 27 10:57:37.452393 kubelet[2583]: I1027 10:57:37.452393 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/327c0d2b-9dae-4f7a-887a-9d690e7a238c-data\") pod \"nfs-server-provisioner-0\" (UID: \"327c0d2b-9dae-4f7a-887a-9d690e7a238c\") " pod="default/nfs-server-provisioner-0" Oct 27 10:57:37.713558 containerd[2129]: time="2026-10-27T10:57:37.713387448Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:327c0d2b-9dae-4f7a-887a-9d690e7a238c,Namespace:default,Attempt:0,}" Oct 27 10:57:37.718692 kubelet[2583]: E1027 10:57:37.718618 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:37.750616 (udev-worker)[3383]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:57:37.752629 systemd-networkd[1692]: vethf385ca32: Link UP Oct 27 10:57:37.757837 kernel: cni0: port 2(vethf385ca32) entered blocking state Oct 27 10:57:37.757958 kernel: cni0: port 2(vethf385ca32) entered disabled state Oct 27 10:57:37.758005 kernel: vethf385ca32: entered allmulticast mode Oct 27 10:57:37.759124 kernel: vethf385ca32: entered promiscuous mode Oct 27 10:57:37.768972 kernel: cni0: port 2(vethf385ca32) entered blocking state Oct 27 10:57:37.769082 kernel: cni0: port 2(vethf385ca32) entered forwarding state Oct 27 10:57:37.769113 systemd-networkd[1692]: vethf385ca32: Gained carrier Oct 27 10:57:37.779156 containerd[2129]: 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)(0x40001047f0), "name":"cbr0", "type":"bridge"} Oct 27 10:57:37.779156 containerd[2129]: delegateAdd: netconf sent to delegate plugin: Oct 27 10:57:37.813537 containerd[2129]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2026-10-27T10:57:37.812997697Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 27 10:57:37.813537 containerd[2129]: time="2026-10-27T10:57:37.813116869Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 27 10:57:37.813537 containerd[2129]: time="2026-10-27T10:57:37.813154969Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:37.814172 containerd[2129]: time="2026-10-27T10:57:37.813507805Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:37.904170 containerd[2129]: time="2026-10-27T10:57:37.904120609Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:327c0d2b-9dae-4f7a-887a-9d690e7a238c,Namespace:default,Attempt:0,} returns sandbox id \"3a951099bc98e530e9be86f1ca5dc8ef0479e9f80d1dbd07e7b70de6797740e9\"" Oct 27 10:57:37.908425 containerd[2129]: time="2026-10-27T10:57:37.908369269Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 27 10:57:38.719662 kubelet[2583]: E1027 10:57:38.719595 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:39.296214 systemd-networkd[1692]: vethf385ca32: Gained IPv6LL Oct 27 10:57:39.720903 kubelet[2583]: E1027 10:57:39.720727 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:40.722021 kubelet[2583]: E1027 10:57:40.721916 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:41.382805 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3567224983.mount: Deactivated successfully. Oct 27 10:57:41.723081 kubelet[2583]: E1027 10:57:41.722937 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:42.107769 ntpd[2082]: Listen normally on 11 vethf385ca32 [fe80::cc63:61ff:fe8a:e4e1%6]:123 Oct 27 10:57:42.108632 ntpd[2082]: 27 Oct 10:57:42 ntpd[2082]: Listen normally on 11 vethf385ca32 [fe80::cc63:61ff:fe8a:e4e1%6]:123 Oct 27 10:57:42.723341 kubelet[2583]: E1027 10:57:42.723294 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:43.724985 kubelet[2583]: E1027 10:57:43.724913 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:44.725846 kubelet[2583]: E1027 10:57:44.725782 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:44.860368 containerd[2129]: time="2026-10-27T10:57:44.860300864Z" 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 27 10:57:44.862266 containerd[2129]: time="2026-10-27T10:57:44.862210940Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87374067" Oct 27 10:57:44.863864 containerd[2129]: time="2026-10-27T10:57:44.863307224Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:44.868614 containerd[2129]: time="2026-10-27T10:57:44.868530332Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:44.870973 containerd[2129]: time="2026-10-27T10:57:44.870754988Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 6.962170175s" Oct 27 10:57:44.870973 containerd[2129]: time="2026-10-27T10:57:44.870809732Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 27 10:57:44.877848 containerd[2129]: time="2026-10-27T10:57:44.877760792Z" level=info msg="CreateContainer within sandbox \"3a951099bc98e530e9be86f1ca5dc8ef0479e9f80d1dbd07e7b70de6797740e9\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 27 10:57:44.901855 containerd[2129]: time="2026-10-27T10:57:44.901766192Z" level=info msg="CreateContainer within sandbox \"3a951099bc98e530e9be86f1ca5dc8ef0479e9f80d1dbd07e7b70de6797740e9\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"2fe5c6b69e4c61a1b2579fd8a0d6c9fca0a4b8d4c4709f9930ee598622a5b192\"" Oct 27 10:57:44.903869 containerd[2129]: time="2026-10-27T10:57:44.902494772Z" level=info msg="StartContainer for \"2fe5c6b69e4c61a1b2579fd8a0d6c9fca0a4b8d4c4709f9930ee598622a5b192\"" Oct 27 10:57:45.001749 containerd[2129]: time="2026-10-27T10:57:45.001667020Z" level=info msg="StartContainer for \"2fe5c6b69e4c61a1b2579fd8a0d6c9fca0a4b8d4c4709f9930ee598622a5b192\" returns successfully" Oct 27 10:57:45.698500 kubelet[2583]: E1027 10:57:45.698430 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:45.726203 kubelet[2583]: E1027 10:57:45.726157 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:46.727152 kubelet[2583]: E1027 10:57:46.727083 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:47.727586 kubelet[2583]: E1027 10:57:47.727524 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:48.728506 kubelet[2583]: E1027 10:57:48.728443 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:49.729549 kubelet[2583]: E1027 10:57:49.729476 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:50.401249 kubelet[2583]: I1027 10:57:50.400374 2583 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.434727556 podStartE2EDuration="13.400352111s" podCreationTimestamp="2026-10-27 10:57:37 +0000 UTC" firstStartedPulling="2026-10-27 10:57:37.907052185 +0000 UTC m=+32.932535684" lastFinishedPulling="2026-10-27 10:57:44.872676728 +0000 UTC m=+39.898160239" observedRunningTime="2026-10-27 10:57:45.031551809 +0000 UTC m=+40.057035320" watchObservedRunningTime="2026-10-27 10:57:50.400352111 +0000 UTC m=+45.425835634" Oct 27 10:57:50.531915 kubelet[2583]: I1027 10:57:50.531858 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-646jb\" (UniqueName: \"kubernetes.io/projected/0ebf3837-9f95-47e9-8269-03a0fd1623e8-kube-api-access-646jb\") pod \"test-pod-1\" (UID: \"0ebf3837-9f95-47e9-8269-03a0fd1623e8\") " pod="default/test-pod-1" Oct 27 10:57:50.532096 kubelet[2583]: I1027 10:57:50.531999 2583 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-240d82de-67b8-4630-a6ac-5dcc051016c0\" (UniqueName: \"kubernetes.io/nfs/0ebf3837-9f95-47e9-8269-03a0fd1623e8-pvc-240d82de-67b8-4630-a6ac-5dcc051016c0\") pod \"test-pod-1\" (UID: \"0ebf3837-9f95-47e9-8269-03a0fd1623e8\") " pod="default/test-pod-1" Oct 27 10:57:50.670885 kernel: FS-Cache: Loaded Oct 27 10:57:50.716519 kernel: RPC: Registered named UNIX socket transport module. Oct 27 10:57:50.716651 kernel: RPC: Registered udp transport module. Oct 27 10:57:50.717966 kernel: RPC: Registered tcp transport module. Oct 27 10:57:50.718026 kernel: RPC: Registered tcp-with-tls transport module. Oct 27 10:57:50.718995 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 27 10:57:50.730440 kubelet[2583]: E1027 10:57:50.730315 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:51.081357 kernel: NFS: Registering the id_resolver key type Oct 27 10:57:51.081484 kernel: Key type id_resolver registered Oct 27 10:57:51.081528 kernel: Key type id_legacy registered Oct 27 10:57:51.123986 nfsidmap[3891]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Oct 27 10:57:51.129925 nfsidmap[3892]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Oct 27 10:57:51.312613 containerd[2129]: time="2026-10-27T10:57:51.312541824Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0ebf3837-9f95-47e9-8269-03a0fd1623e8,Namespace:default,Attempt:0,}" Oct 27 10:57:51.353956 (udev-worker)[3878]: Network interface NamePolicy= disabled on kernel command line. Oct 27 10:57:51.354871 systemd-networkd[1692]: veth56db1c62: Link UP Oct 27 10:57:51.361194 kernel: cni0: port 3(veth56db1c62) entered blocking state Oct 27 10:57:51.361318 kernel: cni0: port 3(veth56db1c62) entered disabled state Oct 27 10:57:51.361364 kernel: veth56db1c62: entered allmulticast mode Oct 27 10:57:51.362781 kernel: veth56db1c62: entered promiscuous mode Oct 27 10:57:51.372330 kernel: cni0: port 3(veth56db1c62) entered blocking state Oct 27 10:57:51.372406 kernel: cni0: port 3(veth56db1c62) entered forwarding state Oct 27 10:57:51.376516 systemd-networkd[1692]: veth56db1c62: Gained carrier Oct 27 10:57:51.380886 containerd[2129]: 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 27 10:57:51.380886 containerd[2129]: delegateAdd: netconf sent to delegate plugin: Oct 27 10:57:51.417673 containerd[2129]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2026-10-27T10:57:51.416978592Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 27 10:57:51.419328 containerd[2129]: time="2026-10-27T10:57:51.419195820Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 27 10:57:51.419328 containerd[2129]: time="2026-10-27T10:57:51.419235672Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:51.419680 containerd[2129]: time="2026-10-27T10:57:51.419451552Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 27 10:57:51.500411 containerd[2129]: time="2026-10-27T10:57:51.500356597Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0ebf3837-9f95-47e9-8269-03a0fd1623e8,Namespace:default,Attempt:0,} returns sandbox id \"ef50fa0d85b0bdf8c2f33a45108d82690d71954899ec1648d77dbe17af020237\"" Oct 27 10:57:51.503100 containerd[2129]: time="2026-10-27T10:57:51.503056417Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 27 10:57:51.730750 kubelet[2583]: E1027 10:57:51.730601 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:51.862122 containerd[2129]: time="2026-10-27T10:57:51.862057767Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 10:57:51.864667 containerd[2129]: time="2026-10-27T10:57:51.863720319Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 27 10:57:51.875660 containerd[2129]: time="2026-10-27T10:57:51.875599503Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:aad3c5ef3cbc1c541a85b31130ad500eefdcd7e0e9a340c42632f00420459296\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:f867c76db3679f8221a6d4b4797d33df9ca88c36704f34c3217c0d8a646232ad\", size \"63102419\" in 372.317198ms" Oct 27 10:57:51.875938 containerd[2129]: time="2026-10-27T10:57:51.875810307Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:aad3c5ef3cbc1c541a85b31130ad500eefdcd7e0e9a340c42632f00420459296\"" Oct 27 10:57:51.882604 containerd[2129]: time="2026-10-27T10:57:51.882553503Z" level=info msg="CreateContainer within sandbox \"ef50fa0d85b0bdf8c2f33a45108d82690d71954899ec1648d77dbe17af020237\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 27 10:57:51.906971 containerd[2129]: time="2026-10-27T10:57:51.906901983Z" level=info msg="CreateContainer within sandbox \"ef50fa0d85b0bdf8c2f33a45108d82690d71954899ec1648d77dbe17af020237\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"7cb0651c06d17c9ad2a130ba216da5ea2fefa8108248d4e642b83fed590f895e\"" Oct 27 10:57:51.908126 containerd[2129]: time="2026-10-27T10:57:51.908073039Z" level=info msg="StartContainer for \"7cb0651c06d17c9ad2a130ba216da5ea2fefa8108248d4e642b83fed590f895e\"" Oct 27 10:57:52.004103 containerd[2129]: time="2026-10-27T10:57:52.003934247Z" level=info msg="StartContainer for \"7cb0651c06d17c9ad2a130ba216da5ea2fefa8108248d4e642b83fed590f895e\" returns successfully" Oct 27 10:57:52.731806 kubelet[2583]: E1027 10:57:52.731742 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:53.440087 systemd-networkd[1692]: veth56db1c62: Gained IPv6LL Oct 27 10:57:53.732680 kubelet[2583]: E1027 10:57:53.732622 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:54.733728 kubelet[2583]: E1027 10:57:54.733662 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:55.734868 kubelet[2583]: E1027 10:57:55.734784 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:56.107789 ntpd[2082]: Listen normally on 12 veth56db1c62 [fe80::30fd:efff:feb4:3052%7]:123 Oct 27 10:57:56.108376 ntpd[2082]: 27 Oct 10:57:56 ntpd[2082]: Listen normally on 12 veth56db1c62 [fe80::30fd:efff:feb4:3052%7]:123 Oct 27 10:57:56.735390 kubelet[2583]: E1027 10:57:56.735323 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:57.735695 kubelet[2583]: E1027 10:57:57.735633 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:58.736370 kubelet[2583]: E1027 10:57:58.736307 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:57:59.737535 kubelet[2583]: E1027 10:57:59.737470 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:00.738562 kubelet[2583]: E1027 10:58:00.738499 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:01.738961 kubelet[2583]: E1027 10:58:01.738901 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:02.740067 kubelet[2583]: E1027 10:58:02.739995 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:03.740883 kubelet[2583]: E1027 10:58:03.740795 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:04.741700 kubelet[2583]: E1027 10:58:04.741634 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:05.697638 kubelet[2583]: E1027 10:58:05.697575 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:05.742434 kubelet[2583]: E1027 10:58:05.742375 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:06.742608 kubelet[2583]: E1027 10:58:06.742546 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:07.742877 kubelet[2583]: E1027 10:58:07.742799 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:08.743036 kubelet[2583]: E1027 10:58:08.742970 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:09.743162 kubelet[2583]: E1027 10:58:09.743082 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:10.744161 kubelet[2583]: E1027 10:58:10.744104 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:11.745146 kubelet[2583]: E1027 10:58:11.745079 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:12.746278 kubelet[2583]: E1027 10:58:12.746202 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:13.747431 kubelet[2583]: E1027 10:58:13.747369 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:14.747977 kubelet[2583]: E1027 10:58:14.747898 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:15.748082 kubelet[2583]: E1027 10:58:15.748011 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:16.748388 kubelet[2583]: E1027 10:58:16.748325 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:17.060637 kubelet[2583]: E1027 10:58:17.060152 2583 controller.go:251] "Failed to update lease" err="Put \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": context deadline exceeded" Oct 27 10:58:17.231639 kubelet[2583]: E1027 10:58:17.231302 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="failed to patch status \"{\\\"status\\\":{\\\"$setElementOrder/conditions\\\":[{\\\"type\\\":\\\"NetworkUnavailable\\\"},{\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"type\\\":\\\"DiskPressure\\\"},{\\\"type\\\":\\\"PIDPressure\\\"},{\\\"type\\\":\\\"Ready\\\"}],\\\"conditions\\\":[{\\\"lastHeartbeatTime\\\":\\\"2026-10-27T10:58:07Z\\\",\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-27T10:58:07Z\\\",\\\"type\\\":\\\"DiskPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-27T10:58:07Z\\\",\\\"type\\\":\\\"PIDPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-27T10:58:07Z\\\",\\\"type\\\":\\\"Ready\\\"}],\\\"images\\\":[{\\\"names\\\":[\\\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\\\",\\\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\\\"],\\\"sizeBytes\\\":87371201},{\\\"names\\\":[\\\"ghcr.io/flatcar/nginx@sha256:f867c76db3679f8221a6d4b4797d33df9ca88c36704f34c3217c0d8a646232ad\\\",\\\"ghcr.io/flatcar/nginx:latest\\\"],\\\"sizeBytes\\\":63102419},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\\\",\\\"ghcr.io/flannel-io/flannel:v0.26.7\\\"],\\\"sizeBytes\\\":32412118},{\\\"names\\\":[\\\"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\\\",\\\"registry.k8s.io/kube-proxy:v1.35.9\\\"],\\\"sizeBytes\\\":23744585},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\\\",\\\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\\\"],\\\"sizeBytes\\\":5125394},{\\\"names\\\":[\\\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\\\",\\\"registry.k8s.io/pause:3.8\\\"],\\\"sizeBytes\\\":268403}]}}\" for node \"172.31.16.227\": Patch \"https://172.31.27.227:6443/api/v1/nodes/172.31.16.227/status?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 27 10:58:17.749514 kubelet[2583]: E1027 10:58:17.749456 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:18.750794 kubelet[2583]: E1027 10:58:18.750719 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:19.751260 kubelet[2583]: E1027 10:58:19.751194 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:20.751390 kubelet[2583]: E1027 10:58:20.751331 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:21.752543 kubelet[2583]: E1027 10:58:21.752471 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:22.753060 kubelet[2583]: E1027 10:58:22.752998 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:23.753918 kubelet[2583]: E1027 10:58:23.753853 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:24.754540 kubelet[2583]: E1027 10:58:24.754475 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:25.697834 kubelet[2583]: E1027 10:58:25.697741 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:25.755676 kubelet[2583]: E1027 10:58:25.755612 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:26.755869 kubelet[2583]: E1027 10:58:26.755792 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:27.060883 kubelet[2583]: E1027 10:58:27.060703 2583 controller.go:251] "Failed to update lease" err="Put \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 27 10:58:27.232285 kubelet[2583]: E1027 10:58:27.232213 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.16.227\": Get \"https://172.31.27.227:6443/api/v1/nodes/172.31.16.227?timeout=10s\": context deadline exceeded" Oct 27 10:58:27.756748 kubelet[2583]: E1027 10:58:27.756686 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:28.758114 kubelet[2583]: E1027 10:58:28.757452 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:29.758561 kubelet[2583]: E1027 10:58:29.758503 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:30.759741 kubelet[2583]: E1027 10:58:30.759665 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:31.759944 kubelet[2583]: E1027 10:58:31.759876 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:32.760715 kubelet[2583]: E1027 10:58:32.760638 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:33.761759 kubelet[2583]: E1027 10:58:33.761688 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:34.762364 kubelet[2583]: E1027 10:58:34.762302 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:34.983717 kubelet[2583]: E1027 10:58:34.979776 2583 controller.go:251] "Failed to update lease" err="Put \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": unexpected EOF" Oct 27 10:58:34.985115 kubelet[2583]: E1027 10:58:34.984780 2583 controller.go:251] "Failed to update lease" err="Put \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" Oct 27 10:58:34.986687 kubelet[2583]: E1027 10:58:34.986408 2583 controller.go:251] "Failed to update lease" err="Put \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" Oct 27 10:58:34.986687 kubelet[2583]: I1027 10:58:34.986506 2583 controller.go:171] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Oct 27 10:58:34.987268 kubelet[2583]: E1027 10:58:34.987214 2583 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" interval="200ms" Oct 27 10:58:35.188384 kubelet[2583]: E1027 10:58:35.188227 2583 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" interval="400ms" Oct 27 10:58:35.589208 kubelet[2583]: E1027 10:58:35.589138 2583 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" interval="800ms" Oct 27 10:58:35.762972 kubelet[2583]: E1027 10:58:35.762911 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:35.987877 kubelet[2583]: E1027 10:58:35.987443 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.16.227\": Get \"https://172.31.27.227:6443/api/v1/nodes/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused - error from a previous attempt: unexpected EOF" Oct 27 10:58:35.988280 kubelet[2583]: E1027 10:58:35.988249 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.16.227\": Get \"https://172.31.27.227:6443/api/v1/nodes/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" Oct 27 10:58:35.989398 kubelet[2583]: E1027 10:58:35.989359 2583 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.16.227\": Get \"https://172.31.27.227:6443/api/v1/nodes/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" Oct 27 10:58:35.989808 kubelet[2583]: E1027 10:58:35.989782 2583 kubelet_node_status.go:461] "Unable to update node status" err="update node status exceeds retry count" Oct 27 10:58:36.391173 kubelet[2583]: E1027 10:58:36.390999 2583 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.27.227:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.16.227?timeout=10s\": dial tcp 172.31.27.227:6443: connect: connection refused" interval="1.6s" Oct 27 10:58:36.763349 kubelet[2583]: E1027 10:58:36.763284 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:37.763976 kubelet[2583]: E1027 10:58:37.763904 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:38.764704 kubelet[2583]: E1027 10:58:38.764636 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:39.765524 kubelet[2583]: E1027 10:58:39.765455 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:40.766487 kubelet[2583]: E1027 10:58:40.766419 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:41.767364 kubelet[2583]: E1027 10:58:41.767297 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:42.767517 kubelet[2583]: E1027 10:58:42.767449 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:43.768254 kubelet[2583]: E1027 10:58:43.768181 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:44.769381 kubelet[2583]: E1027 10:58:44.769314 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:45.698663 kubelet[2583]: E1027 10:58:45.698608 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 10:58:45.769552 kubelet[2583]: E1027 10:58:45.769491 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"