Apr 30 00:44:46.229158 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Apr 30 00:44:46.229228 kernel: Linux version 6.6.88-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 Apr 29 23:08:45 -00 2025 Apr 30 00:44:46.229257 kernel: KASLR disabled due to lack of seed Apr 30 00:44:46.229274 kernel: efi: EFI v2.7 by EDK II Apr 30 00:44:46.229291 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7852ee18 Apr 30 00:44:46.229308 kernel: ACPI: Early table checksum verification disabled Apr 30 00:44:46.229327 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Apr 30 00:44:46.229344 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Apr 30 00:44:46.229361 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Apr 30 00:44:46.229377 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Apr 30 00:44:46.229399 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Apr 30 00:44:46.229417 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Apr 30 00:44:46.229433 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Apr 30 00:44:46.229450 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Apr 30 00:44:46.229470 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Apr 30 00:44:46.229493 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Apr 30 00:44:46.229512 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Apr 30 00:44:46.229550 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Apr 30 00:44:46.229571 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Apr 30 00:44:46.229589 kernel: printk: bootconsole [uart0] enabled Apr 30 00:44:46.229606 kernel: NUMA: Failed to initialise from firmware Apr 30 00:44:46.229624 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Apr 30 00:44:46.229642 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Apr 30 00:44:46.229660 kernel: Zone ranges: Apr 30 00:44:46.229716 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Apr 30 00:44:46.229737 kernel: DMA32 empty Apr 30 00:44:46.229761 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Apr 30 00:44:46.229779 kernel: Movable zone start for each node Apr 30 00:44:46.229800 kernel: Early memory node ranges Apr 30 00:44:46.229819 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Apr 30 00:44:46.229837 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Apr 30 00:44:46.229854 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Apr 30 00:44:46.229871 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Apr 30 00:44:46.229887 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Apr 30 00:44:46.229904 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Apr 30 00:44:46.229921 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Apr 30 00:44:46.229939 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Apr 30 00:44:46.229956 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Apr 30 00:44:46.229977 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Apr 30 00:44:46.229996 kernel: psci: probing for conduit method from ACPI. Apr 30 00:44:46.230021 kernel: psci: PSCIv1.0 detected in firmware. Apr 30 00:44:46.230040 kernel: psci: Using standard PSCI v0.2 function IDs Apr 30 00:44:46.230058 kernel: psci: Trusted OS migration not required Apr 30 00:44:46.230080 kernel: psci: SMC Calling Convention v1.1 Apr 30 00:44:46.230100 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Apr 30 00:44:46.230118 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Apr 30 00:44:46.230761 kernel: pcpu-alloc: [0] 0 [0] 1 Apr 30 00:44:46.230783 kernel: Detected PIPT I-cache on CPU0 Apr 30 00:44:46.230801 kernel: CPU features: detected: GIC system register CPU interface Apr 30 00:44:46.230819 kernel: CPU features: detected: Spectre-v2 Apr 30 00:44:46.230837 kernel: CPU features: detected: Spectre-v3a Apr 30 00:44:46.230855 kernel: CPU features: detected: Spectre-BHB Apr 30 00:44:46.230873 kernel: CPU features: detected: ARM erratum 1742098 Apr 30 00:44:46.230892 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Apr 30 00:44:46.230919 kernel: alternatives: applying boot alternatives Apr 30 00:44:46.230940 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=2f2ec97241771b99b21726307071be4f8c5924f9157dc58cd38c4fcfbe71412a Apr 30 00:44:46.230961 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 00:44:46.230980 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 30 00:44:46.230998 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 00:44:46.231016 kernel: Fallback order for Node 0: 0 Apr 30 00:44:46.231035 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Apr 30 00:44:46.231052 kernel: Policy zone: Normal Apr 30 00:44:46.231071 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 00:44:46.231088 kernel: software IO TLB: area num 2. Apr 30 00:44:46.231106 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Apr 30 00:44:46.231131 kernel: Memory: 3820152K/4030464K available (10240K kernel code, 2186K rwdata, 8104K rodata, 39424K init, 897K bss, 210312K reserved, 0K cma-reserved) Apr 30 00:44:46.231149 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 30 00:44:46.231167 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 00:44:46.231186 kernel: rcu: RCU event tracing is enabled. Apr 30 00:44:46.231205 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 30 00:44:46.231224 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 00:44:46.231243 kernel: Tracing variant of Tasks RCU enabled. Apr 30 00:44:46.231261 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 00:44:46.231280 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 30 00:44:46.231298 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 30 00:44:46.231316 kernel: GICv3: 96 SPIs implemented Apr 30 00:44:46.231339 kernel: GICv3: 0 Extended SPIs implemented Apr 30 00:44:46.231358 kernel: Root IRQ handler: gic_handle_irq Apr 30 00:44:46.231376 kernel: GICv3: GICv3 features: 16 PPIs Apr 30 00:44:46.231394 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Apr 30 00:44:46.231411 kernel: ITS [mem 0x10080000-0x1009ffff] Apr 30 00:44:46.231430 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Apr 30 00:44:46.231448 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Apr 30 00:44:46.231466 kernel: GICv3: using LPI property table @0x00000004000d0000 Apr 30 00:44:46.231484 kernel: ITS: Using hypervisor restricted LPI range [128] Apr 30 00:44:46.231502 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Apr 30 00:44:46.231520 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 00:44:46.231538 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Apr 30 00:44:46.231562 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Apr 30 00:44:46.231580 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Apr 30 00:44:46.231598 kernel: Console: colour dummy device 80x25 Apr 30 00:44:46.231617 kernel: printk: console [tty1] enabled Apr 30 00:44:46.231635 kernel: ACPI: Core revision 20230628 Apr 30 00:44:46.231654 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Apr 30 00:44:46.231702 kernel: pid_max: default: 32768 minimum: 301 Apr 30 00:44:46.231728 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 00:44:46.231747 kernel: landlock: Up and running. Apr 30 00:44:46.231774 kernel: SELinux: Initializing. Apr 30 00:44:46.231795 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 00:44:46.231815 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 30 00:44:46.231835 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 00:44:46.231854 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 00:44:46.231873 kernel: rcu: Hierarchical SRCU implementation. Apr 30 00:44:46.231902 kernel: rcu: Max phase no-delay instances is 400. Apr 30 00:44:46.231922 kernel: Platform MSI: ITS@0x10080000 domain created Apr 30 00:44:46.231941 kernel: PCI/MSI: ITS@0x10080000 domain created Apr 30 00:44:46.231967 kernel: Remapping and enabling EFI services. Apr 30 00:44:46.231987 kernel: smp: Bringing up secondary CPUs ... Apr 30 00:44:46.232006 kernel: Detected PIPT I-cache on CPU1 Apr 30 00:44:46.232024 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Apr 30 00:44:46.232043 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Apr 30 00:44:46.232063 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Apr 30 00:44:46.232082 kernel: smp: Brought up 1 node, 2 CPUs Apr 30 00:44:46.232102 kernel: SMP: Total of 2 processors activated. Apr 30 00:44:46.232123 kernel: CPU features: detected: 32-bit EL0 Support Apr 30 00:44:46.232147 kernel: CPU features: detected: 32-bit EL1 Support Apr 30 00:44:46.232168 kernel: CPU features: detected: CRC32 instructions Apr 30 00:44:46.232187 kernel: CPU: All CPU(s) started at EL1 Apr 30 00:44:46.232219 kernel: alternatives: applying system-wide alternatives Apr 30 00:44:46.232243 kernel: devtmpfs: initialized Apr 30 00:44:46.232263 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 00:44:46.232282 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 30 00:44:46.232302 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 00:44:46.232322 kernel: SMBIOS 3.0.0 present. Apr 30 00:44:46.232342 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Apr 30 00:44:46.232369 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 00:44:46.232391 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 30 00:44:46.232412 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 30 00:44:46.232434 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 30 00:44:46.232455 kernel: audit: initializing netlink subsys (disabled) Apr 30 00:44:46.232476 kernel: audit: type=2000 audit(0.288:1): state=initialized audit_enabled=0 res=1 Apr 30 00:44:46.232496 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 00:44:46.232521 kernel: cpuidle: using governor menu Apr 30 00:44:46.232540 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 30 00:44:46.232560 kernel: ASID allocator initialised with 65536 entries Apr 30 00:44:46.232580 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 00:44:46.232600 kernel: Serial: AMBA PL011 UART driver Apr 30 00:44:46.232619 kernel: Modules: 17504 pages in range for non-PLT usage Apr 30 00:44:46.232638 kernel: Modules: 509024 pages in range for PLT usage Apr 30 00:44:46.232657 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 30 00:44:46.234783 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 30 00:44:46.234828 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 30 00:44:46.234849 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 30 00:44:46.234870 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 00:44:46.234890 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 00:44:46.234910 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 30 00:44:46.234929 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 30 00:44:46.234948 kernel: ACPI: Added _OSI(Module Device) Apr 30 00:44:46.234968 kernel: ACPI: Added _OSI(Processor Device) Apr 30 00:44:46.234987 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 00:44:46.235011 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 00:44:46.235032 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 00:44:46.235051 kernel: ACPI: Interpreter enabled Apr 30 00:44:46.235070 kernel: ACPI: Using GIC for interrupt routing Apr 30 00:44:46.235089 kernel: ACPI: MCFG table detected, 1 entries Apr 30 00:44:46.235109 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Apr 30 00:44:46.235448 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 30 00:44:46.238710 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 30 00:44:46.239063 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 30 00:44:46.239305 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Apr 30 00:44:46.239556 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Apr 30 00:44:46.239587 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Apr 30 00:44:46.239608 kernel: acpiphp: Slot [1] registered Apr 30 00:44:46.239629 kernel: acpiphp: Slot [2] registered Apr 30 00:44:46.239648 kernel: acpiphp: Slot [3] registered Apr 30 00:44:46.241709 kernel: acpiphp: Slot [4] registered Apr 30 00:44:46.241771 kernel: acpiphp: Slot [5] registered Apr 30 00:44:46.241793 kernel: acpiphp: Slot [6] registered Apr 30 00:44:46.241812 kernel: acpiphp: Slot [7] registered Apr 30 00:44:46.241832 kernel: acpiphp: Slot [8] registered Apr 30 00:44:46.241851 kernel: acpiphp: Slot [9] registered Apr 30 00:44:46.241870 kernel: acpiphp: Slot [10] registered Apr 30 00:44:46.241890 kernel: acpiphp: Slot [11] registered Apr 30 00:44:46.241911 kernel: acpiphp: Slot [12] registered Apr 30 00:44:46.241932 kernel: acpiphp: Slot [13] registered Apr 30 00:44:46.241952 kernel: acpiphp: Slot [14] registered Apr 30 00:44:46.241977 kernel: acpiphp: Slot [15] registered Apr 30 00:44:46.241996 kernel: acpiphp: Slot [16] registered Apr 30 00:44:46.242016 kernel: acpiphp: Slot [17] registered Apr 30 00:44:46.242035 kernel: acpiphp: Slot [18] registered Apr 30 00:44:46.242055 kernel: acpiphp: Slot [19] registered Apr 30 00:44:46.242074 kernel: acpiphp: Slot [20] registered Apr 30 00:44:46.242093 kernel: acpiphp: Slot [21] registered Apr 30 00:44:46.242113 kernel: acpiphp: Slot [22] registered Apr 30 00:44:46.242132 kernel: acpiphp: Slot [23] registered Apr 30 00:44:46.242156 kernel: acpiphp: Slot [24] registered Apr 30 00:44:46.242176 kernel: acpiphp: Slot [25] registered Apr 30 00:44:46.242195 kernel: acpiphp: Slot [26] registered Apr 30 00:44:46.242215 kernel: acpiphp: Slot [27] registered Apr 30 00:44:46.242234 kernel: acpiphp: Slot [28] registered Apr 30 00:44:46.242253 kernel: acpiphp: Slot [29] registered Apr 30 00:44:46.242272 kernel: acpiphp: Slot [30] registered Apr 30 00:44:46.242291 kernel: acpiphp: Slot [31] registered Apr 30 00:44:46.242311 kernel: PCI host bridge to bus 0000:00 Apr 30 00:44:46.242611 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Apr 30 00:44:46.242867 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 30 00:44:46.243081 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Apr 30 00:44:46.244847 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Apr 30 00:44:46.245134 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Apr 30 00:44:46.245387 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Apr 30 00:44:46.245648 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Apr 30 00:44:46.245972 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Apr 30 00:44:46.246196 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Apr 30 00:44:46.246417 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 30 00:44:46.247724 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Apr 30 00:44:46.248059 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Apr 30 00:44:46.248300 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Apr 30 00:44:46.248562 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Apr 30 00:44:46.248959 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 30 00:44:46.249215 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Apr 30 00:44:46.249464 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Apr 30 00:44:46.251006 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Apr 30 00:44:46.251283 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Apr 30 00:44:46.251526 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Apr 30 00:44:46.251790 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Apr 30 00:44:46.251992 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 30 00:44:46.252190 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Apr 30 00:44:46.252220 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 30 00:44:46.252242 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 30 00:44:46.252261 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 30 00:44:46.252281 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 30 00:44:46.252301 kernel: iommu: Default domain type: Translated Apr 30 00:44:46.252321 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 30 00:44:46.252352 kernel: efivars: Registered efivars operations Apr 30 00:44:46.252371 kernel: vgaarb: loaded Apr 30 00:44:46.252390 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 30 00:44:46.252411 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 00:44:46.252430 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 00:44:46.252450 kernel: pnp: PnP ACPI init Apr 30 00:44:46.252902 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Apr 30 00:44:46.254891 kernel: pnp: PnP ACPI: found 1 devices Apr 30 00:44:46.254944 kernel: NET: Registered PF_INET protocol family Apr 30 00:44:46.254965 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 30 00:44:46.254985 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 30 00:44:46.255006 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 00:44:46.255026 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 30 00:44:46.255045 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 30 00:44:46.255064 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 30 00:44:46.255084 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 00:44:46.255103 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 30 00:44:46.255128 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 00:44:46.255148 kernel: PCI: CLS 0 bytes, default 64 Apr 30 00:44:46.255167 kernel: kvm [1]: HYP mode not available Apr 30 00:44:46.255186 kernel: Initialise system trusted keyrings Apr 30 00:44:46.255207 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 30 00:44:46.255227 kernel: Key type asymmetric registered Apr 30 00:44:46.255247 kernel: Asymmetric key parser 'x509' registered Apr 30 00:44:46.255267 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 30 00:44:46.255286 kernel: io scheduler mq-deadline registered Apr 30 00:44:46.255312 kernel: io scheduler kyber registered Apr 30 00:44:46.255331 kernel: io scheduler bfq registered Apr 30 00:44:46.255644 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Apr 30 00:44:46.255736 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 30 00:44:46.255760 kernel: ACPI: button: Power Button [PWRB] Apr 30 00:44:46.255780 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Apr 30 00:44:46.255800 kernel: ACPI: button: Sleep Button [SLPB] Apr 30 00:44:46.255820 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 00:44:46.255852 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Apr 30 00:44:46.256117 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Apr 30 00:44:46.256149 kernel: printk: console [ttyS0] disabled Apr 30 00:44:46.256170 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Apr 30 00:44:46.256191 kernel: printk: console [ttyS0] enabled Apr 30 00:44:46.256211 kernel: printk: bootconsole [uart0] disabled Apr 30 00:44:46.256231 kernel: thunder_xcv, ver 1.0 Apr 30 00:44:46.256250 kernel: thunder_bgx, ver 1.0 Apr 30 00:44:46.256271 kernel: nicpf, ver 1.0 Apr 30 00:44:46.256298 kernel: nicvf, ver 1.0 Apr 30 00:44:46.256544 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 30 00:44:46.256839 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-04-30T00:44:45 UTC (1745973885) Apr 30 00:44:46.256870 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 30 00:44:46.256891 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Apr 30 00:44:46.256911 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 30 00:44:46.256930 kernel: watchdog: Hard watchdog permanently disabled Apr 30 00:44:46.256950 kernel: NET: Registered PF_INET6 protocol family Apr 30 00:44:46.256978 kernel: Segment Routing with IPv6 Apr 30 00:44:46.256998 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 00:44:46.257019 kernel: NET: Registered PF_PACKET protocol family Apr 30 00:44:46.257038 kernel: Key type dns_resolver registered Apr 30 00:44:46.257057 kernel: registered taskstats version 1 Apr 30 00:44:46.257076 kernel: Loading compiled-in X.509 certificates Apr 30 00:44:46.257096 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: e2b28159d3a83b6f5d5db45519e470b1b834e378' Apr 30 00:44:46.257116 kernel: Key type .fscrypt registered Apr 30 00:44:46.257135 kernel: Key type fscrypt-provisioning registered Apr 30 00:44:46.257158 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 00:44:46.257178 kernel: ima: Allocated hash algorithm: sha1 Apr 30 00:44:46.257198 kernel: ima: No architecture policies found Apr 30 00:44:46.257217 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 30 00:44:46.257237 kernel: clk: Disabling unused clocks Apr 30 00:44:46.257256 kernel: Freeing unused kernel memory: 39424K Apr 30 00:44:46.257275 kernel: Run /init as init process Apr 30 00:44:46.257294 kernel: with arguments: Apr 30 00:44:46.257313 kernel: /init Apr 30 00:44:46.257333 kernel: with environment: Apr 30 00:44:46.257357 kernel: HOME=/ Apr 30 00:44:46.257377 kernel: TERM=linux Apr 30 00:44:46.257396 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 00:44:46.257421 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Apr 30 00:44:46.257446 systemd[1]: Detected virtualization amazon. Apr 30 00:44:46.257467 systemd[1]: Detected architecture arm64. Apr 30 00:44:46.257487 systemd[1]: Running in initrd. Apr 30 00:44:46.257513 systemd[1]: No hostname configured, using default hostname. Apr 30 00:44:46.257556 systemd[1]: Hostname set to . Apr 30 00:44:46.257580 systemd[1]: Initializing machine ID from VM UUID. Apr 30 00:44:46.257602 systemd[1]: Queued start job for default target initrd.target. Apr 30 00:44:46.257624 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:44:46.257645 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:44:46.257688 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 00:44:46.257718 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 00:44:46.257751 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 00:44:46.257774 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 00:44:46.257800 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 00:44:46.257876 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 00:44:46.257899 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:44:46.257920 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:44:46.257947 systemd[1]: Reached target paths.target - Path Units. Apr 30 00:44:46.258012 systemd[1]: Reached target slices.target - Slice Units. Apr 30 00:44:46.258035 systemd[1]: Reached target swap.target - Swaps. Apr 30 00:44:46.258056 systemd[1]: Reached target timers.target - Timer Units. Apr 30 00:44:46.258077 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 00:44:46.258098 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 00:44:46.258120 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 00:44:46.258141 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 30 00:44:46.258163 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:44:46.258185 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 00:44:46.258213 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:44:46.258234 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 00:44:46.258255 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 00:44:46.258276 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 00:44:46.258297 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 00:44:46.258318 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 00:44:46.258340 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 00:44:46.258361 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 00:44:46.258388 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:44:46.258411 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 00:44:46.258433 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:44:46.258454 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 00:44:46.258529 systemd-journald[251]: Collecting audit messages is disabled. Apr 30 00:44:46.258588 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 00:44:46.258610 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 00:44:46.258630 kernel: Bridge firewalling registered Apr 30 00:44:46.258657 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 00:44:46.258827 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:44:46.258855 systemd-journald[251]: Journal started Apr 30 00:44:46.258897 systemd-journald[251]: Runtime Journal (/run/log/journal/ec2501cc4a65a983e670159ccbf09592) is 8.0M, max 75.3M, 67.3M free. Apr 30 00:44:46.207274 systemd-modules-load[252]: Inserted module 'overlay' Apr 30 00:44:46.249771 systemd-modules-load[252]: Inserted module 'br_netfilter' Apr 30 00:44:46.270773 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 00:44:46.275818 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 00:44:46.288968 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:44:46.297322 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 00:44:46.313035 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 00:44:46.335054 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 00:44:46.353850 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:44:46.359498 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:44:46.372249 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:44:46.385214 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 00:44:46.388440 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:44:46.421024 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 00:44:46.434614 dracut-cmdline[285]: dracut-dracut-053 Apr 30 00:44:46.440336 dracut-cmdline[285]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=2f2ec97241771b99b21726307071be4f8c5924f9157dc58cd38c4fcfbe71412a Apr 30 00:44:46.512812 systemd-resolved[290]: Positive Trust Anchors: Apr 30 00:44:46.512849 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 00:44:46.512912 systemd-resolved[290]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Apr 30 00:44:46.588711 kernel: SCSI subsystem initialized Apr 30 00:44:46.596710 kernel: Loading iSCSI transport class v2.0-870. Apr 30 00:44:46.609719 kernel: iscsi: registered transport (tcp) Apr 30 00:44:46.631714 kernel: iscsi: registered transport (qla4xxx) Apr 30 00:44:46.631785 kernel: QLogic iSCSI HBA Driver Apr 30 00:44:46.724700 kernel: random: crng init done Apr 30 00:44:46.725029 systemd-resolved[290]: Defaulting to hostname 'linux'. Apr 30 00:44:46.728545 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 00:44:46.732492 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:44:46.754219 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 00:44:46.765009 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 00:44:46.812958 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 00:44:46.813037 kernel: device-mapper: uevent: version 1.0.3 Apr 30 00:44:46.813065 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 00:44:46.882730 kernel: raid6: neonx8 gen() 6648 MB/s Apr 30 00:44:46.899719 kernel: raid6: neonx4 gen() 6509 MB/s Apr 30 00:44:46.916726 kernel: raid6: neonx2 gen() 5418 MB/s Apr 30 00:44:46.933728 kernel: raid6: neonx1 gen() 3931 MB/s Apr 30 00:44:46.950728 kernel: raid6: int64x8 gen() 3791 MB/s Apr 30 00:44:46.967726 kernel: raid6: int64x4 gen() 3687 MB/s Apr 30 00:44:46.984727 kernel: raid6: int64x2 gen() 3584 MB/s Apr 30 00:44:47.002548 kernel: raid6: int64x1 gen() 2740 MB/s Apr 30 00:44:47.002619 kernel: raid6: using algorithm neonx8 gen() 6648 MB/s Apr 30 00:44:47.020515 kernel: raid6: .... xor() 4871 MB/s, rmw enabled Apr 30 00:44:47.020600 kernel: raid6: using neon recovery algorithm Apr 30 00:44:47.028724 kernel: xor: measuring software checksum speed Apr 30 00:44:47.030800 kernel: 8regs : 10158 MB/sec Apr 30 00:44:47.030853 kernel: 32regs : 11935 MB/sec Apr 30 00:44:47.031986 kernel: arm64_neon : 9557 MB/sec Apr 30 00:44:47.032044 kernel: xor: using function: 32regs (11935 MB/sec) Apr 30 00:44:47.117729 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 00:44:47.138466 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 00:44:47.148010 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:44:47.195149 systemd-udevd[470]: Using default interface naming scheme 'v255'. Apr 30 00:44:47.205409 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:44:47.226189 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 00:44:47.258973 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Apr 30 00:44:47.324806 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 00:44:47.334993 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 00:44:47.466000 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:44:47.478176 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 00:44:47.521501 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 00:44:47.527344 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 00:44:47.533025 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:44:47.539877 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 00:44:47.553086 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 00:44:47.595763 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 00:44:47.703039 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 30 00:44:47.703119 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Apr 30 00:44:47.733900 kernel: ena 0000:00:05.0: ENA device version: 0.10 Apr 30 00:44:47.734166 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Apr 30 00:44:47.734421 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:71:97:30:56:01 Apr 30 00:44:47.711423 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 00:44:47.711662 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:44:47.714525 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:44:47.716652 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 00:44:47.717007 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:44:47.719281 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:44:47.738109 (udev-worker)[517]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:44:47.739350 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:44:47.785574 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:44:47.797620 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Apr 30 00:44:47.797718 kernel: nvme nvme0: pci function 0000:00:04.0 Apr 30 00:44:47.798201 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 00:44:47.811710 kernel: nvme nvme0: 2/0/0 default/read/poll queues Apr 30 00:44:47.821312 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 30 00:44:47.821399 kernel: GPT:9289727 != 16777215 Apr 30 00:44:47.821427 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 30 00:44:47.821454 kernel: GPT:9289727 != 16777215 Apr 30 00:44:47.821479 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 30 00:44:47.822358 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 30 00:44:47.841649 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:44:47.914727 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (520) Apr 30 00:44:47.960781 kernel: BTRFS: device fsid 7216ceb7-401c-42de-84de-44adb68241e4 devid 1 transid 39 /dev/nvme0n1p3 scanned by (udev-worker) (540) Apr 30 00:44:48.014662 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Apr 30 00:44:48.082826 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 30 00:44:48.101835 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Apr 30 00:44:48.118165 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Apr 30 00:44:48.120849 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Apr 30 00:44:48.142127 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 00:44:48.156861 disk-uuid[662]: Primary Header is updated. Apr 30 00:44:48.156861 disk-uuid[662]: Secondary Entries is updated. Apr 30 00:44:48.156861 disk-uuid[662]: Secondary Header is updated. Apr 30 00:44:48.168752 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 30 00:44:48.177714 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 30 00:44:48.187722 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 30 00:44:49.189710 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 30 00:44:49.190287 disk-uuid[663]: The operation has completed successfully. Apr 30 00:44:49.381078 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 00:44:49.381347 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 00:44:49.438024 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 00:44:49.445255 sh[1007]: Success Apr 30 00:44:49.473081 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 30 00:44:49.596045 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 00:44:49.611915 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 00:44:49.618063 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 00:44:49.660352 kernel: BTRFS info (device dm-0): first mount of filesystem 7216ceb7-401c-42de-84de-44adb68241e4 Apr 30 00:44:49.660428 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:44:49.660456 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 00:44:49.663497 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 00:44:49.663582 kernel: BTRFS info (device dm-0): using free space tree Apr 30 00:44:49.775725 kernel: BTRFS info (device dm-0): enabling ssd optimizations Apr 30 00:44:49.790082 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 00:44:49.794155 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 00:44:49.807972 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 00:44:49.815618 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 00:44:49.853314 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ece78588-c2c6-41f3-bdc2-614da63113c1 Apr 30 00:44:49.853402 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:44:49.853437 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 30 00:44:49.861729 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 30 00:44:49.881339 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 30 00:44:49.884850 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem ece78588-c2c6-41f3-bdc2-614da63113c1 Apr 30 00:44:49.895263 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 00:44:49.907072 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 00:44:50.014728 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 00:44:50.028011 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 00:44:50.088228 systemd-networkd[1211]: lo: Link UP Apr 30 00:44:50.088806 systemd-networkd[1211]: lo: Gained carrier Apr 30 00:44:50.092791 systemd-networkd[1211]: Enumeration completed Apr 30 00:44:50.092990 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 00:44:50.094357 systemd-networkd[1211]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:44:50.094364 systemd-networkd[1211]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 00:44:50.101287 systemd[1]: Reached target network.target - Network. Apr 30 00:44:50.108138 systemd-networkd[1211]: eth0: Link UP Apr 30 00:44:50.108145 systemd-networkd[1211]: eth0: Gained carrier Apr 30 00:44:50.108163 systemd-networkd[1211]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:44:50.139790 systemd-networkd[1211]: eth0: DHCPv4 address 172.31.24.174/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 30 00:44:50.329206 ignition[1132]: Ignition 2.19.0 Apr 30 00:44:50.329245 ignition[1132]: Stage: fetch-offline Apr 30 00:44:50.331904 ignition[1132]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:50.331944 ignition[1132]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:50.332444 ignition[1132]: Ignition finished successfully Apr 30 00:44:50.339143 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 00:44:50.353977 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 30 00:44:50.382731 ignition[1220]: Ignition 2.19.0 Apr 30 00:44:50.382763 ignition[1220]: Stage: fetch Apr 30 00:44:50.384485 ignition[1220]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:50.384515 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:50.385308 ignition[1220]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:50.416194 ignition[1220]: PUT result: OK Apr 30 00:44:50.427709 ignition[1220]: parsed url from cmdline: "" Apr 30 00:44:50.427728 ignition[1220]: no config URL provided Apr 30 00:44:50.427746 ignition[1220]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 00:44:50.427775 ignition[1220]: no config at "/usr/lib/ignition/user.ign" Apr 30 00:44:50.427813 ignition[1220]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:50.429744 ignition[1220]: PUT result: OK Apr 30 00:44:50.431852 ignition[1220]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Apr 30 00:44:50.435958 ignition[1220]: GET result: OK Apr 30 00:44:50.436121 ignition[1220]: parsing config with SHA512: ae7d114a28089782b0e869a8ac3df75b1feb29874bdaf0ac69711d4eb382af72a7d5a997ef6ff073fc3c72214b80cfae04df8fcf77270cb60e7156721f572584 Apr 30 00:44:50.446727 unknown[1220]: fetched base config from "system" Apr 30 00:44:50.446803 unknown[1220]: fetched base config from "system" Apr 30 00:44:50.450075 ignition[1220]: fetch: fetch complete Apr 30 00:44:50.446817 unknown[1220]: fetched user config from "aws" Apr 30 00:44:50.450098 ignition[1220]: fetch: fetch passed Apr 30 00:44:50.450226 ignition[1220]: Ignition finished successfully Apr 30 00:44:50.461069 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 30 00:44:50.472027 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 00:44:50.512662 ignition[1227]: Ignition 2.19.0 Apr 30 00:44:50.512728 ignition[1227]: Stage: kargs Apr 30 00:44:50.514584 ignition[1227]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:50.514613 ignition[1227]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:50.515918 ignition[1227]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:50.518488 ignition[1227]: PUT result: OK Apr 30 00:44:50.526505 ignition[1227]: kargs: kargs passed Apr 30 00:44:50.527195 ignition[1227]: Ignition finished successfully Apr 30 00:44:50.531463 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 00:44:50.544115 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 00:44:50.573402 ignition[1233]: Ignition 2.19.0 Apr 30 00:44:50.573423 ignition[1233]: Stage: disks Apr 30 00:44:50.574189 ignition[1233]: no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:50.574220 ignition[1233]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:50.574408 ignition[1233]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:50.578037 ignition[1233]: PUT result: OK Apr 30 00:44:50.588054 ignition[1233]: disks: disks passed Apr 30 00:44:50.588345 ignition[1233]: Ignition finished successfully Apr 30 00:44:50.596786 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 00:44:50.599319 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 00:44:50.601763 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 00:44:50.604162 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 00:44:50.606199 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 00:44:50.609870 systemd[1]: Reached target basic.target - Basic System. Apr 30 00:44:50.627361 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 00:44:50.674052 systemd-fsck[1241]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 30 00:44:50.680722 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 00:44:50.694012 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 00:44:50.774730 kernel: EXT4-fs (nvme0n1p9): mounted filesystem c13301f3-70ec-4948-963a-f1db0e953273 r/w with ordered data mode. Quota mode: none. Apr 30 00:44:50.776264 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 00:44:50.779536 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 00:44:50.801257 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 00:44:50.807961 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 00:44:50.812341 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 30 00:44:50.812432 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 00:44:50.812482 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 00:44:50.838488 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1260) Apr 30 00:44:50.845372 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ece78588-c2c6-41f3-bdc2-614da63113c1 Apr 30 00:44:50.845457 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:44:50.845503 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 30 00:44:50.846303 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 00:44:50.857992 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 00:44:50.867912 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 30 00:44:50.870398 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 00:44:51.197537 initrd-setup-root[1284]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 00:44:51.219273 initrd-setup-root[1291]: cut: /sysroot/etc/group: No such file or directory Apr 30 00:44:51.228920 initrd-setup-root[1298]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 00:44:51.238166 initrd-setup-root[1305]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 00:44:51.541645 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 00:44:51.549867 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 00:44:51.554627 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 00:44:51.585643 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 00:44:51.590706 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem ece78588-c2c6-41f3-bdc2-614da63113c1 Apr 30 00:44:51.620391 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 00:44:51.636988 ignition[1374]: INFO : Ignition 2.19.0 Apr 30 00:44:51.640176 ignition[1374]: INFO : Stage: mount Apr 30 00:44:51.640176 ignition[1374]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:51.640176 ignition[1374]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:51.640176 ignition[1374]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:51.649565 ignition[1374]: INFO : PUT result: OK Apr 30 00:44:51.653172 ignition[1374]: INFO : mount: mount passed Apr 30 00:44:51.653172 ignition[1374]: INFO : Ignition finished successfully Apr 30 00:44:51.658058 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 00:44:51.671887 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 00:44:51.784161 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 00:44:51.815772 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1384) Apr 30 00:44:51.815842 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem ece78588-c2c6-41f3-bdc2-614da63113c1 Apr 30 00:44:51.818993 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 30 00:44:51.819061 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 30 00:44:51.824696 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 30 00:44:51.828773 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 00:44:51.864343 ignition[1401]: INFO : Ignition 2.19.0 Apr 30 00:44:51.864343 ignition[1401]: INFO : Stage: files Apr 30 00:44:51.867793 ignition[1401]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:51.867793 ignition[1401]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:51.867793 ignition[1401]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:51.874890 ignition[1401]: INFO : PUT result: OK Apr 30 00:44:51.879584 ignition[1401]: DEBUG : files: compiled without relabeling support, skipping Apr 30 00:44:51.882177 ignition[1401]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 00:44:51.882177 ignition[1401]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 00:44:51.920289 ignition[1401]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 00:44:51.923053 ignition[1401]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 00:44:51.923053 ignition[1401]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 00:44:51.922219 unknown[1401]: wrote ssh authorized keys file for user: core Apr 30 00:44:51.930718 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Apr 30 00:44:51.930718 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 30 00:44:51.937237 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Apr 30 00:44:51.970828 systemd-networkd[1211]: eth0: Gained IPv6LL Apr 30 00:44:52.299960 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Apr 30 00:44:52.674518 ignition[1401]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 30 00:44:52.674518 ignition[1401]: INFO : files: op(8): [started] processing unit "containerd.service" Apr 30 00:44:52.698872 ignition[1401]: 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" Apr 30 00:44:52.704982 ignition[1401]: 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" Apr 30 00:44:52.704982 ignition[1401]: INFO : files: op(8): [finished] processing unit "containerd.service" Apr 30 00:44:52.704982 ignition[1401]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 00:44:52.704982 ignition[1401]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 00:44:52.704982 ignition[1401]: INFO : files: files passed Apr 30 00:44:52.704982 ignition[1401]: INFO : Ignition finished successfully Apr 30 00:44:52.712729 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 00:44:52.734595 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 00:44:52.739851 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 00:44:52.758437 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 00:44:52.760422 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 00:44:52.783527 initrd-setup-root-after-ignition[1429]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:44:52.783527 initrd-setup-root-after-ignition[1429]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:44:52.792075 initrd-setup-root-after-ignition[1433]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 00:44:52.798178 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 00:44:52.802101 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 00:44:52.822098 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 00:44:52.867946 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 00:44:52.868195 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 00:44:52.874832 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 00:44:52.876968 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 00:44:52.881827 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 00:44:52.897944 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 00:44:52.921767 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 00:44:52.941163 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 00:44:52.962689 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:44:52.963089 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:44:52.963943 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 00:44:52.964546 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 00:44:52.964832 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 00:44:52.966127 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 00:44:52.966969 systemd[1]: Stopped target basic.target - Basic System. Apr 30 00:44:52.967734 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 00:44:52.968415 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 00:44:52.969236 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 00:44:52.969983 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 00:44:52.970724 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 00:44:52.971488 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 00:44:52.973046 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 00:44:52.973744 systemd[1]: Stopped target swap.target - Swaps. Apr 30 00:44:52.974396 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 00:44:52.974649 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 00:44:52.976015 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:44:52.976757 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:44:52.977363 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 00:44:53.014472 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:44:53.017092 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 00:44:53.017351 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 00:44:53.032376 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 00:44:53.033049 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 00:44:53.051445 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 00:44:53.051815 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 00:44:53.079294 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 00:44:53.082318 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 00:44:53.082615 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:44:53.097042 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 00:44:53.100856 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 00:44:53.102262 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:44:53.109482 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 00:44:53.112014 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 00:44:53.128012 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 00:44:53.129979 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 00:44:53.141103 ignition[1453]: INFO : Ignition 2.19.0 Apr 30 00:44:53.141103 ignition[1453]: INFO : Stage: umount Apr 30 00:44:53.144840 ignition[1453]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 00:44:53.144840 ignition[1453]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 30 00:44:53.150594 ignition[1453]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 30 00:44:53.156599 ignition[1453]: INFO : PUT result: OK Apr 30 00:44:53.161193 ignition[1453]: INFO : umount: umount passed Apr 30 00:44:53.163907 ignition[1453]: INFO : Ignition finished successfully Apr 30 00:44:53.166365 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 00:44:53.168713 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 00:44:53.171282 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 00:44:53.171395 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 00:44:53.173868 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 00:44:53.173984 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 00:44:53.177735 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 30 00:44:53.177852 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 30 00:44:53.180121 systemd[1]: Stopped target network.target - Network. Apr 30 00:44:53.184805 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 00:44:53.184937 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 00:44:53.187418 systemd[1]: Stopped target paths.target - Path Units. Apr 30 00:44:53.205249 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 00:44:53.207142 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:44:53.214133 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 00:44:53.215922 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 00:44:53.217923 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 00:44:53.218026 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 00:44:53.220021 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 00:44:53.220116 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 00:44:53.222616 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 00:44:53.222999 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 00:44:53.226483 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 00:44:53.226604 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 00:44:53.230422 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 00:44:53.234000 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 00:44:53.239199 systemd-networkd[1211]: eth0: DHCPv6 lease lost Apr 30 00:44:53.240172 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 00:44:53.241501 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 00:44:53.243926 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 00:44:53.251401 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 00:44:53.252022 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 00:44:53.257490 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 00:44:53.259752 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 00:44:53.277290 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 00:44:53.277410 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:44:53.290213 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 00:44:53.290330 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 00:44:53.306241 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 00:44:53.314060 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 00:44:53.314208 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 00:44:53.317176 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 00:44:53.317291 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:44:53.321020 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 00:44:53.321134 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 00:44:53.323281 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 00:44:53.323391 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:44:53.326311 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:44:53.368377 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 00:44:53.368658 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:44:53.375514 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 00:44:53.375660 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 00:44:53.386401 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 00:44:53.386510 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:44:53.388786 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 00:44:53.388921 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 00:44:53.392038 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 00:44:53.392154 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 00:44:53.404545 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 00:44:53.404718 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 00:44:53.434117 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 00:44:53.436734 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 00:44:53.436882 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:44:53.446295 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 00:44:53.446434 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:44:53.450130 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 00:44:53.450331 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 00:44:53.469806 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 00:44:53.470034 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 00:44:53.476914 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 00:44:53.493601 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 00:44:53.521881 systemd[1]: Switching root. Apr 30 00:44:53.557137 systemd-journald[251]: Journal stopped Apr 30 00:44:56.412271 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Apr 30 00:44:56.412418 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 00:44:56.412468 kernel: SELinux: policy capability open_perms=1 Apr 30 00:44:56.412507 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 00:44:56.412541 kernel: SELinux: policy capability always_check_network=0 Apr 30 00:44:56.412573 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 00:44:56.412612 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 00:44:56.412649 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 00:44:56.412717 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 00:44:56.412754 kernel: audit: type=1403 audit(1745973894.402:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 00:44:56.412795 systemd[1]: Successfully loaded SELinux policy in 88.198ms. Apr 30 00:44:56.412844 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 28.937ms. Apr 30 00:44:56.412881 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Apr 30 00:44:56.412920 systemd[1]: Detected virtualization amazon. Apr 30 00:44:56.412951 systemd[1]: Detected architecture arm64. Apr 30 00:44:56.412983 systemd[1]: Detected first boot. Apr 30 00:44:56.413016 systemd[1]: Initializing machine ID from VM UUID. Apr 30 00:44:56.413048 zram_generator::config[1517]: No configuration found. Apr 30 00:44:56.413084 systemd[1]: Populated /etc with preset unit settings. Apr 30 00:44:56.413118 systemd[1]: Queued start job for default target multi-user.target. Apr 30 00:44:56.413154 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Apr 30 00:44:56.413187 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 00:44:56.413219 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 00:44:56.413250 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 00:44:56.413281 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 00:44:56.413312 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 00:44:56.413347 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 00:44:56.413381 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 00:44:56.413412 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 00:44:56.413468 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 00:44:56.413502 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 00:44:56.413534 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 00:44:56.413566 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 00:44:56.413597 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 00:44:56.413631 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 00:44:56.413663 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 30 00:44:56.418133 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 00:44:56.418182 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 00:44:56.418222 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 00:44:56.418255 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 00:44:56.418287 systemd[1]: Reached target slices.target - Slice Units. Apr 30 00:44:56.418319 systemd[1]: Reached target swap.target - Swaps. Apr 30 00:44:56.418351 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 00:44:56.418386 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 00:44:56.418417 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 00:44:56.418447 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 30 00:44:56.418483 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 00:44:56.418514 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 00:44:56.418546 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 00:44:56.418577 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 00:44:56.418609 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 00:44:56.418641 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 00:44:56.420785 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 00:44:56.420861 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 00:44:56.420895 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 00:44:56.420935 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 00:44:56.420967 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 00:44:56.421002 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:44:56.421032 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 00:44:56.421066 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 00:44:56.421100 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:44:56.421131 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 00:44:56.421166 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:44:56.421196 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 00:44:56.421232 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:44:56.421264 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 00:44:56.421295 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Apr 30 00:44:56.421331 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Apr 30 00:44:56.421362 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 00:44:56.421393 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 00:44:56.421438 kernel: loop: module loaded Apr 30 00:44:56.421477 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 00:44:56.421514 kernel: fuse: init (API version 7.39) Apr 30 00:44:56.421547 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 00:44:56.421577 kernel: ACPI: bus type drm_connector registered Apr 30 00:44:56.421607 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 00:44:56.421640 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 00:44:56.421958 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 00:44:56.422022 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 00:44:56.422054 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 00:44:56.422084 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 00:44:56.422124 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 00:44:56.422155 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 00:44:56.422185 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 00:44:56.422215 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 00:44:56.422245 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:44:56.422274 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:44:56.422307 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 00:44:56.422341 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 00:44:56.422437 systemd-journald[1613]: Collecting audit messages is disabled. Apr 30 00:44:56.422503 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:44:56.422536 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:44:56.422569 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 00:44:56.422611 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 00:44:56.422644 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 00:44:56.424050 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:44:56.424126 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:44:56.424160 systemd-journald[1613]: Journal started Apr 30 00:44:56.424220 systemd-journald[1613]: Runtime Journal (/run/log/journal/ec2501cc4a65a983e670159ccbf09592) is 8.0M, max 75.3M, 67.3M free. Apr 30 00:44:56.427169 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 00:44:56.438751 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 00:44:56.439632 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 00:44:56.443478 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 00:44:56.472763 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 00:44:56.482936 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 00:44:56.495880 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 00:44:56.498940 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 00:44:56.522074 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 00:44:56.535988 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 00:44:56.544842 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 00:44:56.555075 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 00:44:56.557374 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 00:44:56.574967 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 00:44:56.597364 systemd-journald[1613]: Time spent on flushing to /var/log/journal/ec2501cc4a65a983e670159ccbf09592 is 33.646ms for 875 entries. Apr 30 00:44:56.597364 systemd-journald[1613]: System Journal (/var/log/journal/ec2501cc4a65a983e670159ccbf09592) is 8.0M, max 195.6M, 187.6M free. Apr 30 00:44:56.653792 systemd-journald[1613]: Received client request to flush runtime journal. Apr 30 00:44:56.588723 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 00:44:56.601467 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 00:44:56.620033 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 00:44:56.659970 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 00:44:56.683555 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 00:44:56.686252 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 00:44:56.730504 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 00:44:56.747123 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 00:44:56.757357 systemd-tmpfiles[1665]: ACLs are not supported, ignoring. Apr 30 00:44:56.757393 systemd-tmpfiles[1665]: ACLs are not supported, ignoring. Apr 30 00:44:56.760549 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 00:44:56.780371 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 00:44:56.799261 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 00:44:56.823773 udevadm[1678]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 30 00:44:56.878012 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 00:44:56.892158 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 00:44:56.934354 systemd-tmpfiles[1687]: ACLs are not supported, ignoring. Apr 30 00:44:56.935027 systemd-tmpfiles[1687]: ACLs are not supported, ignoring. Apr 30 00:44:56.945570 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 00:44:57.648660 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 00:44:57.660098 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 00:44:57.730020 systemd-udevd[1693]: Using default interface naming scheme 'v255'. Apr 30 00:44:57.770988 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 00:44:57.788541 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 00:44:57.843221 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 00:44:57.954927 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Apr 30 00:44:58.009218 (udev-worker)[1707]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:44:58.019897 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 00:44:58.230756 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (1715) Apr 30 00:44:58.243163 systemd-networkd[1699]: lo: Link UP Apr 30 00:44:58.243894 systemd-networkd[1699]: lo: Gained carrier Apr 30 00:44:58.251827 systemd-networkd[1699]: Enumeration completed Apr 30 00:44:58.252096 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 00:44:58.254823 systemd-networkd[1699]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:44:58.254832 systemd-networkd[1699]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 00:44:58.258941 systemd-networkd[1699]: eth0: Link UP Apr 30 00:44:58.260046 systemd-networkd[1699]: eth0: Gained carrier Apr 30 00:44:58.260250 systemd-networkd[1699]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 00:44:58.264832 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 00:44:58.283794 systemd-networkd[1699]: eth0: DHCPv4 address 172.31.24.174/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 30 00:44:58.395214 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 00:44:58.516353 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 00:44:58.547188 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 30 00:44:58.561115 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 00:44:58.584702 lvm[1819]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 00:44:58.594511 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 00:44:58.627781 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 00:44:58.631533 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 00:44:58.642997 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 00:44:58.655162 lvm[1825]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 00:44:58.695583 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 00:44:58.700280 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 00:44:58.702977 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Apr 30 00:44:58.703200 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 00:44:58.705704 systemd[1]: Reached target machines.target - Containers. Apr 30 00:44:58.709952 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 30 00:44:58.719015 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 00:44:58.729993 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 00:44:58.733098 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:44:58.735634 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 00:44:58.743056 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 30 00:44:58.750244 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 00:44:58.758501 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 00:44:58.800874 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 00:44:58.802465 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 30 00:44:58.809747 kernel: loop0: detected capacity change from 0 to 194096 Apr 30 00:44:58.829605 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 00:44:58.856738 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 00:44:58.893967 kernel: loop1: detected capacity change from 0 to 114328 Apr 30 00:44:59.031475 kernel: loop2: detected capacity change from 0 to 114432 Apr 30 00:44:59.131751 kernel: loop3: detected capacity change from 0 to 52536 Apr 30 00:44:59.176698 kernel: loop4: detected capacity change from 0 to 194096 Apr 30 00:44:59.209958 kernel: loop5: detected capacity change from 0 to 114328 Apr 30 00:44:59.222751 kernel: loop6: detected capacity change from 0 to 114432 Apr 30 00:44:59.234736 kernel: loop7: detected capacity change from 0 to 52536 Apr 30 00:44:59.251585 (sd-merge)[1847]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Apr 30 00:44:59.252788 (sd-merge)[1847]: Merged extensions into '/usr'. Apr 30 00:44:59.263060 systemd[1]: Reloading requested from client PID 1833 ('systemd-sysext') (unit systemd-sysext.service)... Apr 30 00:44:59.263099 systemd[1]: Reloading... Apr 30 00:44:59.387714 zram_generator::config[1872]: No configuration found. Apr 30 00:44:59.695370 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 30 00:44:59.856997 systemd[1]: Reloading finished in 592 ms. Apr 30 00:44:59.881840 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 00:44:59.897091 systemd[1]: Starting ensure-sysext.service... Apr 30 00:44:59.908135 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 00:44:59.927016 systemd[1]: Reloading requested from client PID 1932 ('systemctl') (unit ensure-sysext.service)... Apr 30 00:44:59.927051 systemd[1]: Reloading... Apr 30 00:44:59.955802 systemd-tmpfiles[1933]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 00:44:59.956461 systemd-tmpfiles[1933]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 00:44:59.959040 systemd-tmpfiles[1933]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 00:44:59.960358 systemd-tmpfiles[1933]: ACLs are not supported, ignoring. Apr 30 00:44:59.960811 systemd-tmpfiles[1933]: ACLs are not supported, ignoring. Apr 30 00:44:59.968817 systemd-tmpfiles[1933]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 00:44:59.970025 systemd-tmpfiles[1933]: Skipping /boot Apr 30 00:44:59.998847 systemd-tmpfiles[1933]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 00:44:59.999029 systemd-tmpfiles[1933]: Skipping /boot Apr 30 00:45:00.094924 systemd-networkd[1699]: eth0: Gained IPv6LL Apr 30 00:45:00.114723 zram_generator::config[1970]: No configuration found. Apr 30 00:45:00.207538 ldconfig[1829]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 00:45:00.366212 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 30 00:45:00.532211 systemd[1]: Reloading finished in 604 ms. Apr 30 00:45:00.564469 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 00:45:00.567903 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 00:45:00.576988 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 00:45:00.598021 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Apr 30 00:45:00.604412 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 00:45:00.624100 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 00:45:00.632548 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 00:45:00.640005 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 00:45:00.680167 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:45:00.690343 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 00:45:00.708138 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 00:45:00.721134 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 00:45:00.725039 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:45:00.738070 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 00:45:00.755803 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 00:45:00.758204 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 00:45:00.758994 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 00:45:00.777039 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 00:45:00.787790 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 00:45:00.788182 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 00:45:00.794481 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 00:45:00.797163 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 00:45:00.803073 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 00:45:00.803433 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 00:45:00.814639 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 00:45:00.823482 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 00:45:00.832833 systemd[1]: Finished ensure-sysext.service. Apr 30 00:45:00.851984 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 00:45:00.853524 augenrules[2059]: No rules Apr 30 00:45:00.857543 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Apr 30 00:45:00.882865 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 00:45:00.883199 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 00:45:00.892052 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 00:45:00.927243 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 00:45:00.951838 systemd-resolved[2031]: Positive Trust Anchors: Apr 30 00:45:00.951880 systemd-resolved[2031]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 00:45:00.951945 systemd-resolved[2031]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Apr 30 00:45:00.959427 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 00:45:00.963523 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Apr 30 00:45:00.970395 systemd-resolved[2031]: Defaulting to hostname 'linux'. Apr 30 00:45:00.974016 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 00:45:00.976293 systemd[1]: Reached target network.target - Network. Apr 30 00:45:00.978184 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 00:45:00.980427 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 00:45:00.982797 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 00:45:00.985101 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 00:45:00.987625 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 00:45:00.990456 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 00:45:00.992943 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 00:45:00.995510 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 00:45:00.998033 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 00:45:00.998095 systemd[1]: Reached target paths.target - Path Units. Apr 30 00:45:00.999883 systemd[1]: Reached target timers.target - Timer Units. Apr 30 00:45:01.003622 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 00:45:01.009104 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 00:45:01.013971 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 00:45:01.018656 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 00:45:01.021076 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 00:45:01.023958 systemd[1]: Reached target basic.target - Basic System. Apr 30 00:45:01.027250 systemd[1]: System is tainted: cgroupsv1 Apr 30 00:45:01.027336 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 00:45:01.027391 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 00:45:01.035948 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 00:45:01.043061 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 30 00:45:01.055154 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 00:45:01.061501 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 00:45:01.076969 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 00:45:01.080782 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Apr 30 00:45:01.086767 jq[2085]: false Apr 30 00:45:01.104894 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:45:01.109864 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 00:45:01.132859 systemd[1]: Started ntpd.service - Network Time Service. Apr 30 00:45:01.154044 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 00:45:01.173237 systemd[1]: Starting setup-oem.service - Setup OEM... Apr 30 00:45:01.187085 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 00:45:01.204383 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 00:45:01.208966 dbus-daemon[2084]: [system] SELinux support is enabled Apr 30 00:45:01.220971 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 00:45:01.226850 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 00:45:01.237344 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 00:45:01.247466 extend-filesystems[2086]: Found loop4 Apr 30 00:45:01.247466 extend-filesystems[2086]: Found loop5 Apr 30 00:45:01.247466 extend-filesystems[2086]: Found loop6 Apr 30 00:45:01.247466 extend-filesystems[2086]: Found loop7 Apr 30 00:45:01.247466 extend-filesystems[2086]: Found nvme0n1 Apr 30 00:45:01.247466 extend-filesystems[2086]: Found nvme0n1p1 Apr 30 00:45:01.245334 dbus-daemon[2084]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1699 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Apr 30 00:45:01.247944 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p2 Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p3 Apr 30 00:45:01.290448 extend-filesystems[2086]: Found usr Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p4 Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p6 Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p7 Apr 30 00:45:01.290448 extend-filesystems[2086]: Found nvme0n1p9 Apr 30 00:45:01.290448 extend-filesystems[2086]: Checking size of /dev/nvme0n1p9 Apr 30 00:45:01.255738 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 00:45:01.307572 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 00:45:01.311238 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 00:45:01.326464 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 00:45:01.329177 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 00:45:01.348753 jq[2109]: true Apr 30 00:45:01.355410 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 00:45:01.365018 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 00:45:01.408616 ntpd[2091]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 22:12:34 UTC 2025 (1): Starting Apr 30 00:45:01.408713 ntpd[2091]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 30 00:45:01.449308 extend-filesystems[2086]: Resized partition /dev/nvme0n1p9 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 22:12:34 UTC 2025 (1): Starting Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: ---------------------------------------------------- Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: ntp-4 is maintained by Network Time Foundation, Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: corporation. Support and training for ntp-4 are Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: available at https://www.nwtime.org/support Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: ---------------------------------------------------- Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: proto: precision = 0.096 usec (-23) Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: basedate set to 2025-04-17 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: gps base set to 2025-04-20 (week 2363) Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen and drop on 0 v6wildcard [::]:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen normally on 2 lo 127.0.0.1:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen normally on 3 eth0 172.31.24.174:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen normally on 4 lo [::1]:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listen normally on 5 eth0 [fe80::471:97ff:fe30:5601%2]:123 Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: Listening on routing socket on fd #22 for interface updates Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 00:45:01.466909 ntpd[2091]: 30 Apr 00:45:01 ntpd[2091]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 00:45:01.485283 update_engine[2107]: I20250430 00:45:01.421159 2107 main.cc:92] Flatcar Update Engine starting Apr 30 00:45:01.485283 update_engine[2107]: I20250430 00:45:01.427301 2107 update_check_scheduler.cc:74] Next update check in 11m28s Apr 30 00:45:01.408737 ntpd[2091]: ---------------------------------------------------- Apr 30 00:45:01.493479 extend-filesystems[2131]: resize2fs 1.47.1 (20-May-2024) Apr 30 00:45:01.503877 coreos-metadata[2082]: Apr 30 00:45:01.488 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 30 00:45:01.408757 ntpd[2091]: ntp-4 is maintained by Network Time Foundation, Apr 30 00:45:01.408777 ntpd[2091]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 30 00:45:01.510222 coreos-metadata[2082]: Apr 30 00:45:01.509 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Apr 30 00:45:01.408796 ntpd[2091]: corporation. Support and training for ntp-4 are Apr 30 00:45:01.510493 jq[2124]: true Apr 30 00:45:01.518511 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Apr 30 00:45:01.408816 ntpd[2091]: available at https://www.nwtime.org/support Apr 30 00:45:01.408834 ntpd[2091]: ---------------------------------------------------- Apr 30 00:45:01.524164 coreos-metadata[2082]: Apr 30 00:45:01.521 INFO Fetch successful Apr 30 00:45:01.524164 coreos-metadata[2082]: Apr 30 00:45:01.521 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Apr 30 00:45:01.414065 ntpd[2091]: proto: precision = 0.096 usec (-23) Apr 30 00:45:01.414622 ntpd[2091]: basedate set to 2025-04-17 Apr 30 00:45:01.414663 ntpd[2091]: gps base set to 2025-04-20 (week 2363) Apr 30 00:45:01.422592 ntpd[2091]: Listen and drop on 0 v6wildcard [::]:123 Apr 30 00:45:01.426773 ntpd[2091]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 30 00:45:01.427106 ntpd[2091]: Listen normally on 2 lo 127.0.0.1:123 Apr 30 00:45:01.427182 ntpd[2091]: Listen normally on 3 eth0 172.31.24.174:123 Apr 30 00:45:01.427259 ntpd[2091]: Listen normally on 4 lo [::1]:123 Apr 30 00:45:01.427348 ntpd[2091]: Listen normally on 5 eth0 [fe80::471:97ff:fe30:5601%2]:123 Apr 30 00:45:01.427424 ntpd[2091]: Listening on routing socket on fd #22 for interface updates Apr 30 00:45:01.459757 ntpd[2091]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 00:45:01.459809 ntpd[2091]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 30 00:45:01.533162 dbus-daemon[2084]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 30 00:45:01.544714 coreos-metadata[2082]: Apr 30 00:45:01.534 INFO Fetch successful Apr 30 00:45:01.544714 coreos-metadata[2082]: Apr 30 00:45:01.534 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Apr 30 00:45:01.544714 coreos-metadata[2082]: Apr 30 00:45:01.537 INFO Fetch successful Apr 30 00:45:01.544714 coreos-metadata[2082]: Apr 30 00:45:01.537 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Apr 30 00:45:01.535541 (ntainerd)[2141]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 00:45:01.554158 coreos-metadata[2082]: Apr 30 00:45:01.553 INFO Fetch successful Apr 30 00:45:01.554158 coreos-metadata[2082]: Apr 30 00:45:01.553 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Apr 30 00:45:01.551833 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.561 INFO Fetch failed with 404: resource not found Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.561 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.563 INFO Fetch successful Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.563 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.566 INFO Fetch successful Apr 30 00:45:01.566877 coreos-metadata[2082]: Apr 30 00:45:01.566 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Apr 30 00:45:01.567623 systemd[1]: Started update-engine.service - Update Engine. Apr 30 00:45:01.573884 coreos-metadata[2082]: Apr 30 00:45:01.573 INFO Fetch successful Apr 30 00:45:01.573884 coreos-metadata[2082]: Apr 30 00:45:01.573 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Apr 30 00:45:01.580932 coreos-metadata[2082]: Apr 30 00:45:01.580 INFO Fetch successful Apr 30 00:45:01.580932 coreos-metadata[2082]: Apr 30 00:45:01.580 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Apr 30 00:45:01.586250 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 00:45:01.590987 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Apr 30 00:45:01.591033 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 00:45:01.598402 coreos-metadata[2082]: Apr 30 00:45:01.596 INFO Fetch successful Apr 30 00:45:01.612974 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 30 00:45:01.614989 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Apr 30 00:45:01.615033 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 00:45:01.619316 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 00:45:01.637475 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 00:45:01.652640 systemd-logind[2103]: Watching system buttons on /dev/input/event0 (Power Button) Apr 30 00:45:01.676871 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Apr 30 00:45:01.655579 systemd-logind[2103]: Watching system buttons on /dev/input/event1 (Sleep Button) Apr 30 00:45:01.656457 systemd[1]: Finished setup-oem.service - Setup OEM. Apr 30 00:45:01.661395 systemd-logind[2103]: New seat seat0. Apr 30 00:45:01.694470 extend-filesystems[2131]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Apr 30 00:45:01.694470 extend-filesystems[2131]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 30 00:45:01.694470 extend-filesystems[2131]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Apr 30 00:45:01.689537 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 00:45:01.703855 extend-filesystems[2086]: Resized filesystem in /dev/nvme0n1p9 Apr 30 00:45:01.747751 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 00:45:01.749844 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 00:45:01.799275 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Apr 30 00:45:01.859524 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 30 00:45:01.866664 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 00:45:01.904130 bash[2192]: Updated "/home/core/.ssh/authorized_keys" Apr 30 00:45:01.911547 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 00:45:01.924711 amazon-ssm-agent[2187]: Initializing new seelog logger Apr 30 00:45:01.935224 amazon-ssm-agent[2187]: New Seelog Logger Creation Complete Apr 30 00:45:01.935224 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.935224 amazon-ssm-agent[2187]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.936179 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 processing appconfig overrides Apr 30 00:45:01.938851 systemd[1]: Starting sshkeys.service... Apr 30 00:45:01.940310 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.940462 amazon-ssm-agent[2187]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.942312 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 processing appconfig overrides Apr 30 00:45:01.942312 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.942312 amazon-ssm-agent[2187]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.942312 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 processing appconfig overrides Apr 30 00:45:01.942798 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO Proxy environment variables: Apr 30 00:45:01.952236 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.952446 amazon-ssm-agent[2187]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 30 00:45:01.952874 amazon-ssm-agent[2187]: 2025/04/30 00:45:01 processing appconfig overrides Apr 30 00:45:02.039481 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 30 00:45:02.043058 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO http_proxy: Apr 30 00:45:02.090583 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 30 00:45:02.136101 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (2169) Apr 30 00:45:02.155712 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO no_proxy: Apr 30 00:45:02.203443 locksmithd[2159]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 00:45:02.263730 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO https_proxy: Apr 30 00:45:02.345361 dbus-daemon[2084]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 30 00:45:02.346302 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 30 00:45:02.353997 dbus-daemon[2084]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=2158 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Apr 30 00:45:02.357836 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO Checking if agent identity type OnPrem can be assumed Apr 30 00:45:02.387101 systemd[1]: Starting polkit.service - Authorization Manager... Apr 30 00:45:02.418256 polkitd[2267]: Started polkitd version 121 Apr 30 00:45:02.434623 polkitd[2267]: Loading rules from directory /etc/polkit-1/rules.d Apr 30 00:45:02.434957 polkitd[2267]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 30 00:45:02.438043 polkitd[2267]: Finished loading, compiling and executing 2 rules Apr 30 00:45:02.439062 dbus-daemon[2084]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 30 00:45:02.439391 systemd[1]: Started polkit.service - Authorization Manager. Apr 30 00:45:02.443352 polkitd[2267]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 30 00:45:02.461455 amazon-ssm-agent[2187]: 2025-04-30 00:45:01 INFO Checking if agent identity type EC2 can be assumed Apr 30 00:45:02.510146 systemd-hostnamed[2158]: Hostname set to (transient) Apr 30 00:45:02.510777 systemd-resolved[2031]: System hostname changed to 'ip-172-31-24-174'. Apr 30 00:45:02.566705 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO Agent will take identity from EC2 Apr 30 00:45:02.580269 coreos-metadata[2212]: Apr 30 00:45:02.579 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 30 00:45:02.588265 coreos-metadata[2212]: Apr 30 00:45:02.584 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Apr 30 00:45:02.588816 coreos-metadata[2212]: Apr 30 00:45:02.588 INFO Fetch successful Apr 30 00:45:02.588816 coreos-metadata[2212]: Apr 30 00:45:02.588 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 30 00:45:02.594101 coreos-metadata[2212]: Apr 30 00:45:02.593 INFO Fetch successful Apr 30 00:45:02.603294 unknown[2212]: wrote ssh authorized keys file for user: core Apr 30 00:45:02.649230 containerd[2141]: time="2025-04-30T00:45:02.638630053Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Apr 30 00:45:02.672786 update-ssh-keys[2311]: Updated "/home/core/.ssh/authorized_keys" Apr 30 00:45:02.675870 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 30 00:45:02.689323 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 30 00:45:02.697483 systemd[1]: Finished sshkeys.service. Apr 30 00:45:02.791646 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 30 00:45:02.871726 containerd[2141]: time="2025-04-30T00:45:02.870067286Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.878332 containerd[2141]: time="2025-04-30T00:45:02.878239274Z" 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.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:45:02.878332 containerd[2141]: time="2025-04-30T00:45:02.878319794Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 00:45:02.878532 containerd[2141]: time="2025-04-30T00:45:02.878360174Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 00:45:02.883924 containerd[2141]: time="2025-04-30T00:45:02.883858778Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 00:45:02.884033 containerd[2141]: time="2025-04-30T00:45:02.883927754Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.884316 containerd[2141]: time="2025-04-30T00:45:02.884107226Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:45:02.884316 containerd[2141]: time="2025-04-30T00:45:02.884148434Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.884635 containerd[2141]: time="2025-04-30T00:45:02.884568218Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:45:02.884635 containerd[2141]: time="2025-04-30T00:45:02.884622458Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.887737 containerd[2141]: time="2025-04-30T00:45:02.884657546Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:45:02.887737 containerd[2141]: time="2025-04-30T00:45:02.886777370Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.887737 containerd[2141]: time="2025-04-30T00:45:02.887040590Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.887737 containerd[2141]: time="2025-04-30T00:45:02.887501042Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 00:45:02.889802 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 30 00:45:02.892709 containerd[2141]: time="2025-04-30T00:45:02.890065130Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 00:45:02.892709 containerd[2141]: time="2025-04-30T00:45:02.890130362Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 00:45:02.892709 containerd[2141]: time="2025-04-30T00:45:02.890377598Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 00:45:02.892709 containerd[2141]: time="2025-04-30T00:45:02.890498126Z" level=info msg="metadata content store policy set" policy=shared Apr 30 00:45:02.898134 containerd[2141]: time="2025-04-30T00:45:02.898037234Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.899169566Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.899248886Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.899298434Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.899347298Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.899732522Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 00:45:02.901729 containerd[2141]: time="2025-04-30T00:45:02.900520610Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 00:45:02.907120 containerd[2141]: time="2025-04-30T00:45:02.907058966Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 00:45:02.908433 containerd[2141]: time="2025-04-30T00:45:02.908379290Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 00:45:02.908749 containerd[2141]: time="2025-04-30T00:45:02.908709866Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.908955590Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909001058Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909079862Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909115454Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909150650Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909183470Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909216422Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909246230Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909288494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909323342Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909355022Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909413234Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909445370Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.911657 containerd[2141]: time="2025-04-30T00:45:02.909477098Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909506462Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909541106Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909575066Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909614582Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909644642Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909715154Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909753506Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909812366Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909863618Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909903242Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.909941006Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.911807894Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.911933006Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Apr 30 00:45:02.912422 containerd[2141]: time="2025-04-30T00:45:02.911964686Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 00:45:02.914979 containerd[2141]: time="2025-04-30T00:45:02.911998310Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 00:45:02.914979 containerd[2141]: time="2025-04-30T00:45:02.912024986Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.914979 containerd[2141]: time="2025-04-30T00:45:02.912069902Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 00:45:02.914979 containerd[2141]: time="2025-04-30T00:45:02.912097574Z" level=info msg="NRI interface is disabled by configuration." Apr 30 00:45:02.914979 containerd[2141]: time="2025-04-30T00:45:02.912126494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 00:45:02.915263 containerd[2141]: time="2025-04-30T00:45:02.913184042Z" 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}" Apr 30 00:45:02.915263 containerd[2141]: time="2025-04-30T00:45:02.913318538Z" level=info msg="Connect containerd service" Apr 30 00:45:02.915263 containerd[2141]: time="2025-04-30T00:45:02.913402994Z" level=info msg="using legacy CRI server" Apr 30 00:45:02.915263 containerd[2141]: time="2025-04-30T00:45:02.913426286Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 00:45:02.915263 containerd[2141]: time="2025-04-30T00:45:02.913838114Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915328022Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915574010Z" level=info msg="Start subscribing containerd event" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915660470Z" level=info msg="Start recovering state" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915827750Z" level=info msg="Start event monitor" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915854534Z" level=info msg="Start snapshots syncer" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915875690Z" level=info msg="Start cni network conf syncer for default" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.915908066Z" level=info msg="Start streaming server" Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.917263010Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.917403542Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 00:45:02.918398 containerd[2141]: time="2025-04-30T00:45:02.917519222Z" level=info msg="containerd successfully booted in 0.287069s" Apr 30 00:45:02.917725 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 00:45:02.989114 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Apr 30 00:45:03.090404 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Apr 30 00:45:03.191190 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] Starting Core Agent Apr 30 00:45:03.293714 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [amazon-ssm-agent] registrar detected. Attempting registration Apr 30 00:45:03.392048 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [Registrar] Starting registrar module Apr 30 00:45:03.492377 amazon-ssm-agent[2187]: 2025-04-30 00:45:02 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Apr 30 00:45:03.795137 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:45:03.812493 (kubelet)[2341]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 30 00:45:03.815077 sshd_keygen[2128]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 00:45:03.869280 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 00:45:03.883266 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 00:45:03.897180 systemd[1]: Started sshd@0-172.31.24.174:22-147.75.109.163:35670.service - OpenSSH per-connection server daemon (147.75.109.163:35670). Apr 30 00:45:03.901773 amazon-ssm-agent[2187]: 2025-04-30 00:45:03 INFO [EC2Identity] EC2 registration was successful. Apr 30 00:45:03.929537 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 00:45:03.931094 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 00:45:03.943505 amazon-ssm-agent[2187]: 2025-04-30 00:45:03 INFO [CredentialRefresher] credentialRefresher has started Apr 30 00:45:03.943505 amazon-ssm-agent[2187]: 2025-04-30 00:45:03 INFO [CredentialRefresher] Starting credentials refresher loop Apr 30 00:45:03.950271 amazon-ssm-agent[2187]: 2025-04-30 00:45:03 INFO EC2RoleProvider Successfully connected with instance profile role credentials Apr 30 00:45:03.947691 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 00:45:03.988133 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 00:45:04.002300 amazon-ssm-agent[2187]: 2025-04-30 00:45:03 INFO [CredentialRefresher] Next credential rotation will be in 31.933269175733333 minutes Apr 30 00:45:04.003150 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 00:45:04.014301 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 30 00:45:04.019545 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 00:45:04.023026 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 00:45:04.026050 systemd[1]: Startup finished in 9.743s (kernel) + 9.707s (userspace) = 19.450s. Apr 30 00:45:04.213085 sshd[2352]: Accepted publickey for core from 147.75.109.163 port 35670 ssh2: RSA SHA256:jA4E/E4F85fdbuY20NmIGoEsn2jbc3vfN6P5NfpO3KQ Apr 30 00:45:04.216960 sshd[2352]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:45:04.238005 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 00:45:04.248781 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 00:45:04.259652 systemd-logind[2103]: New session 1 of user core. Apr 30 00:45:04.284179 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 00:45:04.303435 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 00:45:04.325544 (systemd)[2375]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 00:45:04.566404 systemd[2375]: Queued start job for default target default.target. Apr 30 00:45:04.567121 systemd[2375]: Created slice app.slice - User Application Slice. Apr 30 00:45:04.567175 systemd[2375]: Reached target paths.target - Paths. Apr 30 00:45:04.567206 systemd[2375]: Reached target timers.target - Timers. Apr 30 00:45:04.574864 systemd[2375]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 00:45:04.609251 systemd[2375]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 00:45:04.609387 systemd[2375]: Reached target sockets.target - Sockets. Apr 30 00:45:04.609422 systemd[2375]: Reached target basic.target - Basic System. Apr 30 00:45:04.609506 systemd[2375]: Reached target default.target - Main User Target. Apr 30 00:45:04.609563 systemd[2375]: Startup finished in 271ms. Apr 30 00:45:04.609806 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 00:45:04.622567 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 00:45:04.835345 systemd[1]: Started sshd@1-172.31.24.174:22-147.75.109.163:35678.service - OpenSSH per-connection server daemon (147.75.109.163:35678). Apr 30 00:45:04.902513 kubelet[2341]: E0430 00:45:04.902432 2341 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Apr 30 00:45:04.907861 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 30 00:45:04.908370 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 30 00:45:04.973157 amazon-ssm-agent[2187]: 2025-04-30 00:45:04 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Apr 30 00:45:05.073808 amazon-ssm-agent[2187]: 2025-04-30 00:45:04 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2396) started Apr 30 00:45:05.101261 sshd[2390]: Accepted publickey for core from 147.75.109.163 port 35678 ssh2: RSA SHA256:jA4E/E4F85fdbuY20NmIGoEsn2jbc3vfN6P5NfpO3KQ Apr 30 00:45:05.106081 sshd[2390]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:45:05.116147 systemd-logind[2103]: New session 2 of user core. Apr 30 00:45:05.122407 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 00:45:05.174996 amazon-ssm-agent[2187]: 2025-04-30 00:45:04 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Apr 30 00:45:05.300061 sshd[2390]: pam_unix(sshd:session): session closed for user core Apr 30 00:45:05.305247 systemd-logind[2103]: Session 2 logged out. Waiting for processes to exit. Apr 30 00:45:05.307665 systemd[1]: sshd@1-172.31.24.174:22-147.75.109.163:35678.service: Deactivated successfully. Apr 30 00:45:05.314351 systemd[1]: session-2.scope: Deactivated successfully. Apr 30 00:45:05.316113 systemd-logind[2103]: Removed session 2. Apr 30 00:45:05.349105 systemd[1]: Started sshd@2-172.31.24.174:22-147.75.109.163:35692.service - OpenSSH per-connection server daemon (147.75.109.163:35692). Apr 30 00:45:05.601175 sshd[2412]: Accepted publickey for core from 147.75.109.163 port 35692 ssh2: RSA SHA256:jA4E/E4F85fdbuY20NmIGoEsn2jbc3vfN6P5NfpO3KQ Apr 30 00:45:05.603142 sshd[2412]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:45:05.610710 systemd-logind[2103]: New session 3 of user core. Apr 30 00:45:05.623577 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 00:45:05.790500 sshd[2412]: pam_unix(sshd:session): session closed for user core Apr 30 00:45:05.797515 systemd[1]: sshd@2-172.31.24.174:22-147.75.109.163:35692.service: Deactivated successfully. Apr 30 00:45:05.802564 systemd-logind[2103]: Session 3 logged out. Waiting for processes to exit. Apr 30 00:45:05.803289 systemd[1]: session-3.scope: Deactivated successfully. Apr 30 00:45:05.806295 systemd-logind[2103]: Removed session 3. Apr 30 00:45:05.836149 systemd[1]: Started sshd@3-172.31.24.174:22-147.75.109.163:35700.service - OpenSSH per-connection server daemon (147.75.109.163:35700). Apr 30 00:45:06.104063 sshd[2420]: Accepted publickey for core from 147.75.109.163 port 35700 ssh2: RSA SHA256:jA4E/E4F85fdbuY20NmIGoEsn2jbc3vfN6P5NfpO3KQ Apr 30 00:45:06.106642 sshd[2420]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:45:06.115200 systemd-logind[2103]: New session 4 of user core. Apr 30 00:45:06.126164 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 00:45:06.304301 sshd[2420]: pam_unix(sshd:session): session closed for user core Apr 30 00:45:06.311651 systemd[1]: sshd@3-172.31.24.174:22-147.75.109.163:35700.service: Deactivated successfully. Apr 30 00:45:06.311752 systemd-logind[2103]: Session 4 logged out. Waiting for processes to exit. Apr 30 00:45:06.317992 systemd[1]: session-4.scope: Deactivated successfully. Apr 30 00:45:06.319733 systemd-logind[2103]: Removed session 4. Apr 30 00:45:06.350228 systemd[1]: Started sshd@4-172.31.24.174:22-147.75.109.163:35714.service - OpenSSH per-connection server daemon (147.75.109.163:35714). Apr 30 00:45:06.607269 sshd[2428]: Accepted publickey for core from 147.75.109.163 port 35714 ssh2: RSA SHA256:jA4E/E4F85fdbuY20NmIGoEsn2jbc3vfN6P5NfpO3KQ Apr 30 00:45:06.609806 sshd[2428]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 00:45:06.617626 systemd-logind[2103]: New session 5 of user core. Apr 30 00:45:06.626129 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 00:45:06.806217 sudo[2432]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 30 00:45:06.806918 sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 00:45:07.950218 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:45:07.962156 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:45:08.000752 systemd[1]: Reloading requested from client PID 2470 ('systemctl') (unit session-5.scope)... Apr 30 00:45:08.000931 systemd[1]: Reloading... Apr 30 00:45:08.219606 zram_generator::config[2516]: No configuration found. Apr 30 00:45:08.615668 systemd-resolved[2031]: Clock change detected. Flushing caches. Apr 30 00:45:08.682747 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Apr 30 00:45:08.853464 systemd[1]: Reloading finished in 645 ms. Apr 30 00:45:08.931999 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Apr 30 00:45:08.932254 systemd[1]: kubelet.service: Failed with result 'signal'. Apr 30 00:45:08.932998 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:45:08.942660 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 00:45:09.245225 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 00:45:09.266508 (kubelet)[2583]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 30 00:45:09.338114 kubelet[2583]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Apr 30 00:45:09.338114 kubelet[2583]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Apr 30 00:45:09.338114 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. Apr 30 00:45:09.340314 kubelet[2583]: I0430 00:45:09.340232 2583 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Apr 30 00:45:12.962455 kubelet[2583]: I0430 00:45:12.962375 2583 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Apr 30 00:45:12.962455 kubelet[2583]: I0430 00:45:12.962435 2583 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 30 00:45:12.963189 kubelet[2583]: I0430 00:45:12.962841 2583 server.go:927] "Client rotation is on, will bootstrap in background" Apr 30 00:45:12.989117 kubelet[2583]: I0430 00:45:12.988858 2583 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 30 00:45:13.006101 kubelet[2583]: I0430 00:45:13.005754 2583 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 30 00:45:13.009310 kubelet[2583]: I0430 00:45:13.009246 2583 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 30 00:45:13.009728 kubelet[2583]: I0430 00:45:13.009460 2583 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.24.174","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":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Apr 30 00:45:13.010539 kubelet[2583]: I0430 00:45:13.010017 2583 topology_manager.go:138] "Creating topology manager with none policy" Apr 30 00:45:13.010539 kubelet[2583]: I0430 00:45:13.010047 2583 container_manager_linux.go:301] "Creating device plugin manager" Apr 30 00:45:13.010539 kubelet[2583]: I0430 00:45:13.010287 2583 state_mem.go:36] "Initialized new in-memory state store" Apr 30 00:45:13.011786 kubelet[2583]: I0430 00:45:13.011760 2583 kubelet.go:400] "Attempting to sync node with API server" Apr 30 00:45:13.011967 kubelet[2583]: I0430 00:45:13.011943 2583 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 30 00:45:13.012172 kubelet[2583]: I0430 00:45:13.012153 2583 kubelet.go:312] "Adding apiserver pod source" Apr 30 00:45:13.012314 kubelet[2583]: I0430 00:45:13.012292 2583 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 30 00:45:13.012481 kubelet[2583]: E0430 00:45:13.012366 2583 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:13.012546 kubelet[2583]: E0430 00:45:13.012529 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:13.013967 kubelet[2583]: I0430 00:45:13.013678 2583 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Apr 30 00:45:13.014121 kubelet[2583]: I0430 00:45:13.014091 2583 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Apr 30 00:45:13.014182 kubelet[2583]: W0430 00:45:13.014157 2583 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 30 00:45:13.015719 kubelet[2583]: I0430 00:45:13.015667 2583 server.go:1264] "Started kubelet" Apr 30 00:45:13.018866 kubelet[2583]: I0430 00:45:13.018831 2583 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 30 00:45:13.030716 kubelet[2583]: E0430 00:45:13.030117 2583 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.24.174.183af205f8615f7d default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.24.174,UID:172.31.24.174,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.24.174,},FirstTimestamp:2025-04-30 00:45:13.015377789 +0000 UTC m=+3.742786327,LastTimestamp:2025-04-30 00:45:13.015377789 +0000 UTC m=+3.742786327,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.24.174,}" Apr 30 00:45:13.031930 kubelet[2583]: E0430 00:45:13.031089 2583 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 30 00:45:13.034079 kubelet[2583]: I0430 00:45:13.033865 2583 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Apr 30 00:45:13.035120 kubelet[2583]: I0430 00:45:13.035008 2583 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 30 00:45:13.039122 kubelet[2583]: I0430 00:45:13.039089 2583 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 30 00:45:13.039398 kubelet[2583]: I0430 00:45:13.037529 2583 volume_manager.go:291] "Starting Kubelet Volume Manager" Apr 30 00:45:13.039817 kubelet[2583]: I0430 00:45:13.037565 2583 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Apr 30 00:45:13.040617 kubelet[2583]: I0430 00:45:13.037436 2583 server.go:455] "Adding debug handlers to kubelet server" Apr 30 00:45:13.042922 kubelet[2583]: I0430 00:45:13.041800 2583 reconciler.go:26] "Reconciler: start to sync state" Apr 30 00:45:13.043449 kubelet[2583]: I0430 00:45:13.043401 2583 factory.go:221] Registration of the systemd container factory successfully Apr 30 00:45:13.043748 kubelet[2583]: I0430 00:45:13.043677 2583 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Apr 30 00:45:13.047407 kubelet[2583]: I0430 00:45:13.047340 2583 factory.go:221] Registration of the containerd container factory successfully Apr 30 00:45:13.057530 kubelet[2583]: E0430 00:45:13.057468 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.24.174\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Apr 30 00:45:13.058551 kubelet[2583]: W0430 00:45:13.057993 2583 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 30 00:45:13.061009 kubelet[2583]: E0430 00:45:13.060974 2583 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 30 00:45:13.063332 kubelet[2583]: W0430 00:45:13.058360 2583 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "172.31.24.174" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 30 00:45:13.063473 kubelet[2583]: W0430 00:45:13.058469 2583 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Apr 30 00:45:13.063622 kubelet[2583]: E0430 00:45:13.063599 2583 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Apr 30 00:45:13.067135 kubelet[2583]: E0430 00:45:13.058063 2583 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.24.174.183af205f950cc4d default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.24.174,UID:172.31.24.174,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:172.31.24.174,},FirstTimestamp:2025-04-30 00:45:13.031068749 +0000 UTC m=+3.758477323,LastTimestamp:2025-04-30 00:45:13.031068749 +0000 UTC m=+3.758477323,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.24.174,}" Apr 30 00:45:13.067607 kubelet[2583]: E0430 00:45:13.067391 2583 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.24.174" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 30 00:45:13.096856 kubelet[2583]: I0430 00:45:13.096441 2583 cpu_manager.go:214] "Starting CPU manager" policy="none" Apr 30 00:45:13.096856 kubelet[2583]: I0430 00:45:13.096476 2583 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Apr 30 00:45:13.096856 kubelet[2583]: I0430 00:45:13.096510 2583 state_mem.go:36] "Initialized new in-memory state store" Apr 30 00:45:13.101554 kubelet[2583]: I0430 00:45:13.101521 2583 policy_none.go:49] "None policy: Start" Apr 30 00:45:13.106567 kubelet[2583]: I0430 00:45:13.106043 2583 memory_manager.go:170] "Starting memorymanager" policy="None" Apr 30 00:45:13.106567 kubelet[2583]: I0430 00:45:13.106091 2583 state_mem.go:35] "Initializing new in-memory state store" Apr 30 00:45:13.122780 kubelet[2583]: I0430 00:45:13.120402 2583 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 30 00:45:13.122780 kubelet[2583]: I0430 00:45:13.120703 2583 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 30 00:45:13.122780 kubelet[2583]: I0430 00:45:13.120865 2583 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 30 00:45:13.128217 kubelet[2583]: E0430 00:45:13.128160 2583 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.24.174\" not found" Apr 30 00:45:13.139221 kubelet[2583]: I0430 00:45:13.138880 2583 kubelet_node_status.go:73] "Attempting to register node" node="172.31.24.174" Apr 30 00:45:13.145474 kubelet[2583]: I0430 00:45:13.145417 2583 kubelet_node_status.go:76] "Successfully registered node" node="172.31.24.174" Apr 30 00:45:13.161358 kubelet[2583]: E0430 00:45:13.161309 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.210926 kubelet[2583]: I0430 00:45:13.210828 2583 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Apr 30 00:45:13.213333 kubelet[2583]: I0430 00:45:13.213161 2583 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Apr 30 00:45:13.213333 kubelet[2583]: I0430 00:45:13.213244 2583 status_manager.go:217] "Starting to sync pod status with apiserver" Apr 30 00:45:13.213333 kubelet[2583]: I0430 00:45:13.213277 2583 kubelet.go:2337] "Starting kubelet main sync loop" Apr 30 00:45:13.215730 kubelet[2583]: E0430 00:45:13.215473 2583 kubelet.go:2361] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Apr 30 00:45:13.262102 kubelet[2583]: E0430 00:45:13.262023 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.292731 sudo[2432]: pam_unix(sudo:session): session closed for user root Apr 30 00:45:13.331510 sshd[2428]: pam_unix(sshd:session): session closed for user core Apr 30 00:45:13.337515 systemd-logind[2103]: Session 5 logged out. Waiting for processes to exit. Apr 30 00:45:13.337872 systemd[1]: sshd@4-172.31.24.174:22-147.75.109.163:35714.service: Deactivated successfully. Apr 30 00:45:13.347844 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 00:45:13.350699 systemd-logind[2103]: Removed session 5. Apr 30 00:45:13.362393 kubelet[2583]: E0430 00:45:13.362333 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.463182 kubelet[2583]: E0430 00:45:13.463118 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.564968 kubelet[2583]: E0430 00:45:13.564264 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.665198 kubelet[2583]: E0430 00:45:13.665139 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.766147 kubelet[2583]: E0430 00:45:13.766083 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.867067 kubelet[2583]: E0430 00:45:13.866923 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:13.965802 kubelet[2583]: I0430 00:45:13.965733 2583 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 30 00:45:13.966531 kubelet[2583]: W0430 00:45:13.966009 2583 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Apr 30 00:45:13.967994 kubelet[2583]: E0430 00:45:13.967950 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:14.013533 kubelet[2583]: E0430 00:45:14.013472 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:14.068657 kubelet[2583]: E0430 00:45:14.068594 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:14.169237 kubelet[2583]: E0430 00:45:14.169107 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:14.269999 kubelet[2583]: E0430 00:45:14.269940 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:14.371037 kubelet[2583]: E0430 00:45:14.370974 2583 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.24.174\" not found" Apr 30 00:45:14.472561 kubelet[2583]: I0430 00:45:14.472426 2583 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 30 00:45:14.473144 containerd[2141]: time="2025-04-30T00:45:14.473077005Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 30 00:45:14.473704 kubelet[2583]: I0430 00:45:14.473388 2583 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 30 00:45:15.014010 kubelet[2583]: E0430 00:45:15.013924 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:15.014010 kubelet[2583]: I0430 00:45:15.014007 2583 apiserver.go:52] "Watching apiserver" Apr 30 00:45:15.022808 kubelet[2583]: I0430 00:45:15.022684 2583 topology_manager.go:215] "Topology Admit Handler" podUID="2cbd0752-a8dd-4be0-9375-c1a34562a94b" podNamespace="kube-flannel" podName="kube-flannel-ds-t97hf" Apr 30 00:45:15.023088 kubelet[2583]: I0430 00:45:15.022871 2583 topology_manager.go:215] "Topology Admit Handler" podUID="6994eb46-c84b-4f73-aaed-569ccfa353b7" podNamespace="kube-system" podName="kube-proxy-wwtrd" Apr 30 00:45:15.041321 kubelet[2583]: I0430 00:45:15.041230 2583 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Apr 30 00:45:15.055353 kubelet[2583]: I0430 00:45:15.055293 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/2cbd0752-a8dd-4be0-9375-c1a34562a94b-run\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055494 kubelet[2583]: I0430 00:45:15.055357 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/2cbd0752-a8dd-4be0-9375-c1a34562a94b-cni\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055494 kubelet[2583]: I0430 00:45:15.055404 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/2cbd0752-a8dd-4be0-9375-c1a34562a94b-flannel-cfg\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055494 kubelet[2583]: I0430 00:45:15.055440 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2cbd0752-a8dd-4be0-9375-c1a34562a94b-xtables-lock\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055691 kubelet[2583]: I0430 00:45:15.055497 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/6994eb46-c84b-4f73-aaed-569ccfa353b7-lib-modules\") pod \"kube-proxy-wwtrd\" (UID: \"6994eb46-c84b-4f73-aaed-569ccfa353b7\") " pod="kube-system/kube-proxy-wwtrd" Apr 30 00:45:15.055691 kubelet[2583]: I0430 00:45:15.055534 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/2cbd0752-a8dd-4be0-9375-c1a34562a94b-cni-plugin\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055691 kubelet[2583]: I0430 00:45:15.055568 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ff9nw\" (UniqueName: \"kubernetes.io/projected/2cbd0752-a8dd-4be0-9375-c1a34562a94b-kube-api-access-ff9nw\") pod \"kube-flannel-ds-t97hf\" (UID: \"2cbd0752-a8dd-4be0-9375-c1a34562a94b\") " pod="kube-flannel/kube-flannel-ds-t97hf" Apr 30 00:45:15.055691 kubelet[2583]: I0430 00:45:15.055601 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/6994eb46-c84b-4f73-aaed-569ccfa353b7-kube-proxy\") pod \"kube-proxy-wwtrd\" (UID: \"6994eb46-c84b-4f73-aaed-569ccfa353b7\") " pod="kube-system/kube-proxy-wwtrd" Apr 30 00:45:15.055691 kubelet[2583]: I0430 00:45:15.055636 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6994eb46-c84b-4f73-aaed-569ccfa353b7-xtables-lock\") pod \"kube-proxy-wwtrd\" (UID: \"6994eb46-c84b-4f73-aaed-569ccfa353b7\") " pod="kube-system/kube-proxy-wwtrd" Apr 30 00:45:15.055954 kubelet[2583]: I0430 00:45:15.055670 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-p5rzv\" (UniqueName: \"kubernetes.io/projected/6994eb46-c84b-4f73-aaed-569ccfa353b7-kube-api-access-p5rzv\") pod \"kube-proxy-wwtrd\" (UID: \"6994eb46-c84b-4f73-aaed-569ccfa353b7\") " pod="kube-system/kube-proxy-wwtrd" Apr 30 00:45:15.329768 containerd[2141]: time="2025-04-30T00:45:15.329582373Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wwtrd,Uid:6994eb46-c84b-4f73-aaed-569ccfa353b7,Namespace:kube-system,Attempt:0,}" Apr 30 00:45:15.333567 containerd[2141]: time="2025-04-30T00:45:15.333424965Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-t97hf,Uid:2cbd0752-a8dd-4be0-9375-c1a34562a94b,Namespace:kube-flannel,Attempt:0,}" Apr 30 00:45:15.894121 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3014037395.mount: Deactivated successfully. Apr 30 00:45:15.904028 containerd[2141]: time="2025-04-30T00:45:15.903853020Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:45:15.906178 containerd[2141]: time="2025-04-30T00:45:15.905859456Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:45:15.908776 containerd[2141]: time="2025-04-30T00:45:15.908565372Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Apr 30 00:45:15.909527 containerd[2141]: time="2025-04-30T00:45:15.909464592Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 30 00:45:15.912733 containerd[2141]: time="2025-04-30T00:45:15.912673368Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:45:15.919931 containerd[2141]: time="2025-04-30T00:45:15.918774576Z" 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 589.085979ms" Apr 30 00:45:15.920993 containerd[2141]: time="2025-04-30T00:45:15.920931852Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 00:45:15.922934 containerd[2141]: time="2025-04-30T00:45:15.922848288Z" 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 589.295955ms" Apr 30 00:45:16.014779 kubelet[2583]: E0430 00:45:16.014606 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:16.206451 containerd[2141]: time="2025-04-30T00:45:16.205469121Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:45:16.206451 containerd[2141]: time="2025-04-30T00:45:16.205595937Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:45:16.206451 containerd[2141]: time="2025-04-30T00:45:16.205624641Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:16.206451 containerd[2141]: time="2025-04-30T00:45:16.205800897Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:16.213068 containerd[2141]: time="2025-04-30T00:45:16.212423109Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:45:16.213068 containerd[2141]: time="2025-04-30T00:45:16.212515545Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:45:16.213068 containerd[2141]: time="2025-04-30T00:45:16.212542317Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:16.213068 containerd[2141]: time="2025-04-30T00:45:16.212688945Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:16.458384 containerd[2141]: time="2025-04-30T00:45:16.457659910Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wwtrd,Uid:6994eb46-c84b-4f73-aaed-569ccfa353b7,Namespace:kube-system,Attempt:0,} returns sandbox id \"5c0f036e3ef56e9ad92ced5134189fab45fffa5d8b91cc5ca1d9464b0983bc17\"" Apr 30 00:45:16.464441 containerd[2141]: time="2025-04-30T00:45:16.463460518Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\"" Apr 30 00:45:16.489316 containerd[2141]: time="2025-04-30T00:45:16.489237935Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-t97hf,Uid:2cbd0752-a8dd-4be0-9375-c1a34562a94b,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\"" Apr 30 00:45:17.015115 kubelet[2583]: E0430 00:45:17.015058 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:17.722022 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3123213445.mount: Deactivated successfully. Apr 30 00:45:18.015764 kubelet[2583]: E0430 00:45:18.015254 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:18.234741 containerd[2141]: time="2025-04-30T00:45:18.234457679Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:18.235929 containerd[2141]: time="2025-04-30T00:45:18.235859063Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.12: active requests=0, bytes read=25775705" Apr 30 00:45:18.238029 containerd[2141]: time="2025-04-30T00:45:18.237939383Z" level=info msg="ImageCreate event name:\"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:18.241762 containerd[2141]: time="2025-04-30T00:45:18.241666091Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:18.244279 containerd[2141]: time="2025-04-30T00:45:18.243994007Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.12\" with image id \"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\", repo tag \"registry.k8s.io/kube-proxy:v1.30.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15\", size \"25774724\" in 1.780466493s" Apr 30 00:45:18.244279 containerd[2141]: time="2025-04-30T00:45:18.244057259Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\" returns image reference \"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\"" Apr 30 00:45:18.246792 containerd[2141]: time="2025-04-30T00:45:18.246238355Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 30 00:45:18.250378 containerd[2141]: time="2025-04-30T00:45:18.250060715Z" level=info msg="CreateContainer within sandbox \"5c0f036e3ef56e9ad92ced5134189fab45fffa5d8b91cc5ca1d9464b0983bc17\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 30 00:45:18.275310 containerd[2141]: time="2025-04-30T00:45:18.274529087Z" level=info msg="CreateContainer within sandbox \"5c0f036e3ef56e9ad92ced5134189fab45fffa5d8b91cc5ca1d9464b0983bc17\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b2ac2b22acae1bc9d43bae4a30233139debe4d4f6100ace64306e5bc135d6cc6\"" Apr 30 00:45:18.276933 containerd[2141]: time="2025-04-30T00:45:18.276617891Z" level=info msg="StartContainer for \"b2ac2b22acae1bc9d43bae4a30233139debe4d4f6100ace64306e5bc135d6cc6\"" Apr 30 00:45:18.381191 containerd[2141]: time="2025-04-30T00:45:18.381049236Z" level=info msg="StartContainer for \"b2ac2b22acae1bc9d43bae4a30233139debe4d4f6100ace64306e5bc135d6cc6\" returns successfully" Apr 30 00:45:19.015589 kubelet[2583]: E0430 00:45:19.015533 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:20.016122 kubelet[2583]: E0430 00:45:20.015973 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:20.016160 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3237050344.mount: Deactivated successfully. Apr 30 00:45:20.072778 containerd[2141]: time="2025-04-30T00:45:20.072713676Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:20.074382 containerd[2141]: time="2025-04-30T00:45:20.074330664Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Apr 30 00:45:20.075405 containerd[2141]: time="2025-04-30T00:45:20.075320328Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:20.081086 containerd[2141]: time="2025-04-30T00:45:20.080987160Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:20.082812 containerd[2141]: time="2025-04-30T00:45:20.082608432Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.836313065s" Apr 30 00:45:20.082812 containerd[2141]: time="2025-04-30T00:45:20.082666704Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Apr 30 00:45:20.086931 containerd[2141]: time="2025-04-30T00:45:20.086782284Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 30 00:45:20.104157 containerd[2141]: time="2025-04-30T00:45:20.104063053Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6\"" Apr 30 00:45:20.105246 containerd[2141]: time="2025-04-30T00:45:20.105126061Z" level=info msg="StartContainer for \"c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6\"" Apr 30 00:45:20.193635 containerd[2141]: time="2025-04-30T00:45:20.193554913Z" level=info msg="StartContainer for \"c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6\" returns successfully" Apr 30 00:45:20.270341 kubelet[2583]: I0430 00:45:20.270145 2583 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-wwtrd" podStartSLOduration=5.486249516 podStartE2EDuration="7.270122017s" podCreationTimestamp="2025-04-30 00:45:13 +0000 UTC" firstStartedPulling="2025-04-30 00:45:16.46184713 +0000 UTC m=+7.189255668" lastFinishedPulling="2025-04-30 00:45:18.245719619 +0000 UTC m=+8.973128169" observedRunningTime="2025-04-30 00:45:19.262507224 +0000 UTC m=+9.989915774" watchObservedRunningTime="2025-04-30 00:45:20.270122017 +0000 UTC m=+10.997530579" Apr 30 00:45:20.547036 containerd[2141]: time="2025-04-30T00:45:20.545532819Z" level=info msg="shim disconnected" id=c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6 namespace=k8s.io Apr 30 00:45:20.547036 containerd[2141]: time="2025-04-30T00:45:20.545610063Z" level=warning msg="cleaning up after shim disconnected" id=c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6 namespace=k8s.io Apr 30 00:45:20.547036 containerd[2141]: time="2025-04-30T00:45:20.545633991Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 00:45:20.880560 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c575612950994b0e8e955d4dc9f041d4016a9a97449b19d06af6d9c6673e8ca6-rootfs.mount: Deactivated successfully. Apr 30 00:45:21.017072 kubelet[2583]: E0430 00:45:21.017005 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:21.259075 containerd[2141]: time="2025-04-30T00:45:21.258493946Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 30 00:45:22.017415 kubelet[2583]: E0430 00:45:22.017366 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:23.018280 kubelet[2583]: E0430 00:45:23.018235 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:23.086763 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1622696372.mount: Deactivated successfully. Apr 30 00:45:24.019659 kubelet[2583]: E0430 00:45:24.019589 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:24.141950 containerd[2141]: time="2025-04-30T00:45:24.141632849Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:24.143828 containerd[2141]: time="2025-04-30T00:45:24.143761637Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Apr 30 00:45:24.144706 containerd[2141]: time="2025-04-30T00:45:24.144124109Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:24.149857 containerd[2141]: time="2025-04-30T00:45:24.149779493Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:24.152701 containerd[2141]: time="2025-04-30T00:45:24.152227109Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 2.893670055s" Apr 30 00:45:24.152701 containerd[2141]: time="2025-04-30T00:45:24.152285105Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Apr 30 00:45:24.156521 containerd[2141]: time="2025-04-30T00:45:24.156457589Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 30 00:45:24.174545 containerd[2141]: time="2025-04-30T00:45:24.174462917Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3\"" Apr 30 00:45:24.175461 containerd[2141]: time="2025-04-30T00:45:24.175412165Z" level=info msg="StartContainer for \"d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3\"" Apr 30 00:45:24.270023 containerd[2141]: time="2025-04-30T00:45:24.268608833Z" level=info msg="StartContainer for \"d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3\" returns successfully" Apr 30 00:45:24.270158 kubelet[2583]: I0430 00:45:24.269369 2583 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Apr 30 00:45:24.304770 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3-rootfs.mount: Deactivated successfully. Apr 30 00:45:24.517256 containerd[2141]: time="2025-04-30T00:45:24.517132782Z" level=info msg="shim disconnected" id=d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3 namespace=k8s.io Apr 30 00:45:24.517256 containerd[2141]: time="2025-04-30T00:45:24.517204026Z" level=warning msg="cleaning up after shim disconnected" id=d5bcb7cd884f32ab1b1cc319c6805d7d1097581c652ee3eb07494b6ae3294bc3 namespace=k8s.io Apr 30 00:45:24.517256 containerd[2141]: time="2025-04-30T00:45:24.517224042Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 00:45:24.537778 containerd[2141]: time="2025-04-30T00:45:24.537530995Z" level=warning msg="cleanup warnings time=\"2025-04-30T00:45:24Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Apr 30 00:45:25.020733 kubelet[2583]: E0430 00:45:25.020571 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:25.286413 containerd[2141]: time="2025-04-30T00:45:25.286260390Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 30 00:45:25.302817 containerd[2141]: time="2025-04-30T00:45:25.302637810Z" level=info msg="CreateContainer within sandbox \"1c7df81af21f2889939a35bf9fb5e7938b3dd4d6768770962aecaa07c3e6fba9\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"3d411ffeb3c139ba17c5f63d4d53cdfea49389a69e17ba1e6c0cc208fe185d44\"" Apr 30 00:45:25.305675 containerd[2141]: time="2025-04-30T00:45:25.304849650Z" level=info msg="StartContainer for \"3d411ffeb3c139ba17c5f63d4d53cdfea49389a69e17ba1e6c0cc208fe185d44\"" Apr 30 00:45:25.399762 containerd[2141]: time="2025-04-30T00:45:25.399685999Z" level=info msg="StartContainer for \"3d411ffeb3c139ba17c5f63d4d53cdfea49389a69e17ba1e6c0cc208fe185d44\" returns successfully" Apr 30 00:45:25.888501 kubelet[2583]: I0430 00:45:25.888400 2583 topology_manager.go:215] "Topology Admit Handler" podUID="e700b3c6-658b-4879-8b33-83598c28d2ce" podNamespace="default" podName="nginx-deployment-85f456d6dd-r5dfs" Apr 30 00:45:25.925202 kubelet[2583]: I0430 00:45:25.925126 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2pdgr\" (UniqueName: \"kubernetes.io/projected/e700b3c6-658b-4879-8b33-83598c28d2ce-kube-api-access-2pdgr\") pod \"nginx-deployment-85f456d6dd-r5dfs\" (UID: \"e700b3c6-658b-4879-8b33-83598c28d2ce\") " pod="default/nginx-deployment-85f456d6dd-r5dfs" Apr 30 00:45:26.020990 kubelet[2583]: E0430 00:45:26.020928 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:26.193853 containerd[2141]: time="2025-04-30T00:45:26.193666099Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-r5dfs,Uid:e700b3c6-658b-4879-8b33-83598c28d2ce,Namespace:default,Attempt:0,}" Apr 30 00:45:26.236254 containerd[2141]: time="2025-04-30T00:45:26.235317259Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-r5dfs,Uid:e700b3c6-658b-4879-8b33-83598c28d2ce,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"cf3a084544fbc468402208eae08305bd43f099c59785d891eb857aec033d3639\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Apr 30 00:45:26.236472 kubelet[2583]: E0430 00:45:26.235722 2583 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"cf3a084544fbc468402208eae08305bd43f099c59785d891eb857aec033d3639\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Apr 30 00:45:26.236472 kubelet[2583]: E0430 00:45:26.235811 2583 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"cf3a084544fbc468402208eae08305bd43f099c59785d891eb857aec033d3639\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-85f456d6dd-r5dfs" Apr 30 00:45:26.236472 kubelet[2583]: E0430 00:45:26.235845 2583 kuberuntime_manager.go:1166] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"cf3a084544fbc468402208eae08305bd43f099c59785d891eb857aec033d3639\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-85f456d6dd-r5dfs" Apr 30 00:45:26.236472 kubelet[2583]: E0430 00:45:26.235934 2583 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-85f456d6dd-r5dfs_default(e700b3c6-658b-4879-8b33-83598c28d2ce)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-85f456d6dd-r5dfs_default(e700b3c6-658b-4879-8b33-83598c28d2ce)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"cf3a084544fbc468402208eae08305bd43f099c59785d891eb857aec033d3639\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-85f456d6dd-r5dfs" podUID="e700b3c6-658b-4879-8b33-83598c28d2ce" Apr 30 00:45:26.461463 (udev-worker)[3101]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:45:26.474865 systemd-networkd[1699]: flannel.1: Link UP Apr 30 00:45:26.474880 systemd-networkd[1699]: flannel.1: Gained carrier Apr 30 00:45:27.021525 kubelet[2583]: E0430 00:45:27.021467 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:28.022607 kubelet[2583]: E0430 00:45:28.022538 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:28.397205 systemd-networkd[1699]: flannel.1: Gained IPv6LL Apr 30 00:45:29.023280 kubelet[2583]: E0430 00:45:29.023218 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:30.023806 kubelet[2583]: E0430 00:45:30.023746 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:30.615646 ntpd[2091]: Listen normally on 6 flannel.1 192.168.1.0:123 Apr 30 00:45:30.615782 ntpd[2091]: Listen normally on 7 flannel.1 [fe80::9cad:9ff:fe01:91b2%3]:123 Apr 30 00:45:30.616313 ntpd[2091]: 30 Apr 00:45:30 ntpd[2091]: Listen normally on 6 flannel.1 192.168.1.0:123 Apr 30 00:45:30.616313 ntpd[2091]: 30 Apr 00:45:30 ntpd[2091]: Listen normally on 7 flannel.1 [fe80::9cad:9ff:fe01:91b2%3]:123 Apr 30 00:45:31.024232 kubelet[2583]: E0430 00:45:31.024080 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:32.025046 kubelet[2583]: E0430 00:45:32.024972 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:32.752135 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 30 00:45:33.012862 kubelet[2583]: E0430 00:45:33.012684 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:33.026062 kubelet[2583]: E0430 00:45:33.026021 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:34.027108 kubelet[2583]: E0430 00:45:34.027054 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:35.027269 kubelet[2583]: E0430 00:45:35.027204 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:36.028089 kubelet[2583]: E0430 00:45:36.027996 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:37.028795 kubelet[2583]: E0430 00:45:37.028730 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:38.029102 kubelet[2583]: E0430 00:45:38.029041 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:39.030179 kubelet[2583]: E0430 00:45:39.030105 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:40.031099 kubelet[2583]: E0430 00:45:40.031033 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:41.031593 kubelet[2583]: E0430 00:45:41.031518 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:41.215337 containerd[2141]: time="2025-04-30T00:45:41.215158629Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-r5dfs,Uid:e700b3c6-658b-4879-8b33-83598c28d2ce,Namespace:default,Attempt:0,}" Apr 30 00:45:41.245144 systemd-networkd[1699]: cni0: Link UP Apr 30 00:45:41.245175 systemd-networkd[1699]: cni0: Gained carrier Apr 30 00:45:41.251649 (udev-worker)[3242]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:45:41.252023 systemd-networkd[1699]: cni0: Lost carrier Apr 30 00:45:41.261426 kernel: cni0: port 1(veth16d61472) entered blocking state Apr 30 00:45:41.261552 kernel: cni0: port 1(veth16d61472) entered disabled state Apr 30 00:45:41.260878 systemd-networkd[1699]: veth16d61472: Link UP Apr 30 00:45:41.264980 kernel: veth16d61472: entered allmulticast mode Apr 30 00:45:41.265098 kernel: veth16d61472: entered promiscuous mode Apr 30 00:45:41.265142 kernel: cni0: port 1(veth16d61472) entered blocking state Apr 30 00:45:41.265828 kernel: cni0: port 1(veth16d61472) entered forwarding state Apr 30 00:45:41.268644 kernel: cni0: port 1(veth16d61472) entered disabled state Apr 30 00:45:41.273501 (udev-worker)[3247]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:45:41.277828 kernel: cni0: port 1(veth16d61472) entered blocking state Apr 30 00:45:41.278027 kernel: cni0: port 1(veth16d61472) entered forwarding state Apr 30 00:45:41.279267 systemd-networkd[1699]: veth16d61472: Gained carrier Apr 30 00:45:41.279751 systemd-networkd[1699]: cni0: Gained carrier Apr 30 00:45:41.285422 containerd[2141]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Apr 30 00:45:41.285422 containerd[2141]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:45:41.327740 containerd[2141]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-04-30T00:45:41.327558706Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:45:41.327740 containerd[2141]: time="2025-04-30T00:45:41.327674530Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:45:41.328671 containerd[2141]: time="2025-04-30T00:45:41.328235806Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:41.328671 containerd[2141]: time="2025-04-30T00:45:41.328448674Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:41.417582 containerd[2141]: time="2025-04-30T00:45:41.417511618Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-r5dfs,Uid:e700b3c6-658b-4879-8b33-83598c28d2ce,Namespace:default,Attempt:0,} returns sandbox id \"2ba9fcf5c2d2f879b1d8703f1d7ea39777db998d48fd63cf98562146e9bde860\"" Apr 30 00:45:41.420864 containerd[2141]: time="2025-04-30T00:45:41.420539830Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 00:45:42.032008 kubelet[2583]: E0430 00:45:42.031944 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:42.989289 systemd-networkd[1699]: cni0: Gained IPv6LL Apr 30 00:45:43.032621 kubelet[2583]: E0430 00:45:43.032508 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:43.117215 systemd-networkd[1699]: veth16d61472: Gained IPv6LL Apr 30 00:45:44.032957 kubelet[2583]: E0430 00:45:44.032867 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:44.759002 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount912543191.mount: Deactivated successfully. Apr 30 00:45:45.033793 kubelet[2583]: E0430 00:45:45.033610 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:45.615584 ntpd[2091]: Listen normally on 8 cni0 192.168.1.1:123 Apr 30 00:45:45.615734 ntpd[2091]: Listen normally on 9 cni0 [fe80::6867:24ff:fe37:babe%4]:123 Apr 30 00:45:45.616257 ntpd[2091]: 30 Apr 00:45:45 ntpd[2091]: Listen normally on 8 cni0 192.168.1.1:123 Apr 30 00:45:45.616257 ntpd[2091]: 30 Apr 00:45:45 ntpd[2091]: Listen normally on 9 cni0 [fe80::6867:24ff:fe37:babe%4]:123 Apr 30 00:45:45.616257 ntpd[2091]: 30 Apr 00:45:45 ntpd[2091]: Listen normally on 10 veth16d61472 [fe80::e4bf:a6ff:feed:7c2c%5]:123 Apr 30 00:45:45.615816 ntpd[2091]: Listen normally on 10 veth16d61472 [fe80::e4bf:a6ff:feed:7c2c%5]:123 Apr 30 00:45:46.035580 kubelet[2583]: E0430 00:45:46.034972 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:46.154945 containerd[2141]: time="2025-04-30T00:45:46.154241546Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:46.156421 containerd[2141]: time="2025-04-30T00:45:46.156365798Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948638" Apr 30 00:45:46.157454 containerd[2141]: time="2025-04-30T00:45:46.157370786Z" level=info msg="ImageCreate event name:\"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:46.162874 containerd[2141]: time="2025-04-30T00:45:46.162775742Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:46.165130 containerd[2141]: time="2025-04-30T00:45:46.164943902Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"69948516\" in 4.744333572s" Apr 30 00:45:46.165130 containerd[2141]: time="2025-04-30T00:45:46.164999606Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 30 00:45:46.169926 containerd[2141]: time="2025-04-30T00:45:46.169791590Z" level=info msg="CreateContainer within sandbox \"2ba9fcf5c2d2f879b1d8703f1d7ea39777db998d48fd63cf98562146e9bde860\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 30 00:45:46.188107 containerd[2141]: time="2025-04-30T00:45:46.187877954Z" level=info msg="CreateContainer within sandbox \"2ba9fcf5c2d2f879b1d8703f1d7ea39777db998d48fd63cf98562146e9bde860\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"dfcb8482380dbe9be362d140054bbf48562e5ce59442dfa1c99b44651603b15b\"" Apr 30 00:45:46.189585 containerd[2141]: time="2025-04-30T00:45:46.188867570Z" level=info msg="StartContainer for \"dfcb8482380dbe9be362d140054bbf48562e5ce59442dfa1c99b44651603b15b\"" Apr 30 00:45:46.281513 containerd[2141]: time="2025-04-30T00:45:46.281396235Z" level=info msg="StartContainer for \"dfcb8482380dbe9be362d140054bbf48562e5ce59442dfa1c99b44651603b15b\" returns successfully" Apr 30 00:45:46.349918 kubelet[2583]: I0430 00:45:46.349127 2583 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-t97hf" podStartSLOduration=25.687113241 podStartE2EDuration="33.349070415s" podCreationTimestamp="2025-04-30 00:45:13 +0000 UTC" firstStartedPulling="2025-04-30 00:45:16.492305099 +0000 UTC m=+7.219713649" lastFinishedPulling="2025-04-30 00:45:24.154262237 +0000 UTC m=+14.881670823" observedRunningTime="2025-04-30 00:45:26.308742907 +0000 UTC m=+17.036151481" watchObservedRunningTime="2025-04-30 00:45:46.349070415 +0000 UTC m=+37.076478965" Apr 30 00:45:46.349918 kubelet[2583]: I0430 00:45:46.349449 2583 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-r5dfs" podStartSLOduration=16.601812419 podStartE2EDuration="21.349435911s" podCreationTimestamp="2025-04-30 00:45:25 +0000 UTC" firstStartedPulling="2025-04-30 00:45:41.419708098 +0000 UTC m=+32.147116660" lastFinishedPulling="2025-04-30 00:45:46.167331602 +0000 UTC m=+36.894740152" observedRunningTime="2025-04-30 00:45:46.349243851 +0000 UTC m=+37.076652425" watchObservedRunningTime="2025-04-30 00:45:46.349435911 +0000 UTC m=+37.076844461" Apr 30 00:45:46.788191 update_engine[2107]: I20250430 00:45:46.787230 2107 update_attempter.cc:509] Updating boot flags... Apr 30 00:45:46.861940 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (3436) Apr 30 00:45:47.036100 kubelet[2583]: E0430 00:45:47.036017 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:47.088951 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (3438) Apr 30 00:45:47.335935 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (3438) Apr 30 00:45:48.037054 kubelet[2583]: E0430 00:45:48.036993 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:49.037361 kubelet[2583]: E0430 00:45:49.037304 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:50.038074 kubelet[2583]: E0430 00:45:50.038013 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:51.038250 kubelet[2583]: E0430 00:45:51.038162 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:52.038705 kubelet[2583]: E0430 00:45:52.038640 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:52.525846 kubelet[2583]: I0430 00:45:52.525765 2583 topology_manager.go:215] "Topology Admit Handler" podUID="b3baec63-889e-45bf-960d-34626b68c59f" podNamespace="default" podName="nfs-server-provisioner-0" Apr 30 00:45:52.591300 kubelet[2583]: I0430 00:45:52.591167 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b3baec63-889e-45bf-960d-34626b68c59f-data\") pod \"nfs-server-provisioner-0\" (UID: \"b3baec63-889e-45bf-960d-34626b68c59f\") " pod="default/nfs-server-provisioner-0" Apr 30 00:45:52.591300 kubelet[2583]: I0430 00:45:52.591249 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gm57q\" (UniqueName: \"kubernetes.io/projected/b3baec63-889e-45bf-960d-34626b68c59f-kube-api-access-gm57q\") pod \"nfs-server-provisioner-0\" (UID: \"b3baec63-889e-45bf-960d-34626b68c59f\") " pod="default/nfs-server-provisioner-0" Apr 30 00:45:52.832080 containerd[2141]: time="2025-04-30T00:45:52.831650159Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b3baec63-889e-45bf-960d-34626b68c59f,Namespace:default,Attempt:0,}" Apr 30 00:45:52.869596 (udev-worker)[3715]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:45:52.874570 kernel: cni0: port 2(veth8331fb30) entered blocking state Apr 30 00:45:52.875736 kernel: cni0: port 2(veth8331fb30) entered disabled state Apr 30 00:45:52.875785 kernel: veth8331fb30: entered allmulticast mode Apr 30 00:45:52.873076 systemd-networkd[1699]: veth8331fb30: Link UP Apr 30 00:45:52.877752 kernel: veth8331fb30: entered promiscuous mode Apr 30 00:45:52.890418 kernel: cni0: port 2(veth8331fb30) entered blocking state Apr 30 00:45:52.890540 kernel: cni0: port 2(veth8331fb30) entered forwarding state Apr 30 00:45:52.890375 systemd-networkd[1699]: veth8331fb30: Gained carrier Apr 30 00:45:52.894637 containerd[2141]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Apr 30 00:45:52.894637 containerd[2141]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:45:52.922516 containerd[2141]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-04-30T00:45:52.922372308Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:45:52.922683 containerd[2141]: time="2025-04-30T00:45:52.922577772Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:45:52.922903 containerd[2141]: time="2025-04-30T00:45:52.922716576Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:52.923367 containerd[2141]: time="2025-04-30T00:45:52.923268000Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:45:53.007511 containerd[2141]: time="2025-04-30T00:45:53.007450808Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b3baec63-889e-45bf-960d-34626b68c59f,Namespace:default,Attempt:0,} returns sandbox id \"dee73996bd0aab32c2b9d04f82b9638b19f2b14cbf16987f6f30064565a78d6f\"" Apr 30 00:45:53.010575 containerd[2141]: time="2025-04-30T00:45:53.010234580Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 30 00:45:53.013161 kubelet[2583]: E0430 00:45:53.013109 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:53.039315 kubelet[2583]: E0430 00:45:53.039251 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:54.040305 kubelet[2583]: E0430 00:45:54.040226 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:54.189173 systemd-networkd[1699]: veth8331fb30: Gained IPv6LL Apr 30 00:45:55.041033 kubelet[2583]: E0430 00:45:55.040986 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:55.449940 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2508646494.mount: Deactivated successfully. Apr 30 00:45:56.042493 kubelet[2583]: E0430 00:45:56.042446 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:57.044225 kubelet[2583]: E0430 00:45:57.044180 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:58.045501 kubelet[2583]: E0430 00:45:58.045295 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:58.292335 containerd[2141]: time="2025-04-30T00:45:58.292254290Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:58.294244 containerd[2141]: time="2025-04-30T00:45:58.294172166Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Apr 30 00:45:58.296997 containerd[2141]: time="2025-04-30T00:45:58.296821862Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:58.309681 containerd[2141]: time="2025-04-30T00:45:58.309569906Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:45:58.312152 containerd[2141]: time="2025-04-30T00:45:58.311310074Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 5.301010574s" Apr 30 00:45:58.312152 containerd[2141]: time="2025-04-30T00:45:58.311377766Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 30 00:45:58.316144 containerd[2141]: time="2025-04-30T00:45:58.316093910Z" level=info msg="CreateContainer within sandbox \"dee73996bd0aab32c2b9d04f82b9638b19f2b14cbf16987f6f30064565a78d6f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 30 00:45:58.347379 containerd[2141]: time="2025-04-30T00:45:58.347317431Z" level=info msg="CreateContainer within sandbox \"dee73996bd0aab32c2b9d04f82b9638b19f2b14cbf16987f6f30064565a78d6f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8f0800163ae797e114acba21a70417cd83191b70615ee3fe8a2c13c47a05ae07\"" Apr 30 00:45:58.349265 containerd[2141]: time="2025-04-30T00:45:58.348207099Z" level=info msg="StartContainer for \"8f0800163ae797e114acba21a70417cd83191b70615ee3fe8a2c13c47a05ae07\"" Apr 30 00:45:58.444617 containerd[2141]: time="2025-04-30T00:45:58.443907855Z" level=info msg="StartContainer for \"8f0800163ae797e114acba21a70417cd83191b70615ee3fe8a2c13c47a05ae07\" returns successfully" Apr 30 00:45:58.615677 ntpd[2091]: Listen normally on 11 veth8331fb30 [fe80::281f:c9ff:fef6:7260%6]:123 Apr 30 00:45:58.617734 ntpd[2091]: 30 Apr 00:45:58 ntpd[2091]: Listen normally on 11 veth8331fb30 [fe80::281f:c9ff:fef6:7260%6]:123 Apr 30 00:45:59.045911 kubelet[2583]: E0430 00:45:59.045842 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:45:59.400457 kubelet[2583]: I0430 00:45:59.400264 2583 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.096190474 podStartE2EDuration="7.400240972s" podCreationTimestamp="2025-04-30 00:45:52 +0000 UTC" firstStartedPulling="2025-04-30 00:45:53.009404756 +0000 UTC m=+43.736813306" lastFinishedPulling="2025-04-30 00:45:58.313455254 +0000 UTC m=+49.040863804" observedRunningTime="2025-04-30 00:45:59.400063384 +0000 UTC m=+50.127471958" watchObservedRunningTime="2025-04-30 00:45:59.400240972 +0000 UTC m=+50.127649522" Apr 30 00:46:00.047083 kubelet[2583]: E0430 00:46:00.046980 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:01.047676 kubelet[2583]: E0430 00:46:01.047602 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:02.048423 kubelet[2583]: E0430 00:46:02.048359 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:03.048857 kubelet[2583]: E0430 00:46:03.048798 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:04.049222 kubelet[2583]: E0430 00:46:04.049164 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:05.050042 kubelet[2583]: E0430 00:46:05.049987 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:06.050858 kubelet[2583]: E0430 00:46:06.050787 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:07.051349 kubelet[2583]: E0430 00:46:07.051282 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:08.052226 kubelet[2583]: E0430 00:46:08.052155 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:08.680199 kubelet[2583]: I0430 00:46:08.679459 2583 topology_manager.go:215] "Topology Admit Handler" podUID="051eb76e-c033-4c45-af86-f26747378341" podNamespace="default" podName="test-pod-1" Apr 30 00:46:08.793226 kubelet[2583]: I0430 00:46:08.793080 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-bd9cbaf1-6cad-4b8c-923d-ec69902eff37\" (UniqueName: \"kubernetes.io/nfs/051eb76e-c033-4c45-af86-f26747378341-pvc-bd9cbaf1-6cad-4b8c-923d-ec69902eff37\") pod \"test-pod-1\" (UID: \"051eb76e-c033-4c45-af86-f26747378341\") " pod="default/test-pod-1" Apr 30 00:46:08.793226 kubelet[2583]: I0430 00:46:08.793163 2583 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pfbj6\" (UniqueName: \"kubernetes.io/projected/051eb76e-c033-4c45-af86-f26747378341-kube-api-access-pfbj6\") pod \"test-pod-1\" (UID: \"051eb76e-c033-4c45-af86-f26747378341\") " pod="default/test-pod-1" Apr 30 00:46:08.932025 kernel: FS-Cache: Loaded Apr 30 00:46:08.974348 kernel: RPC: Registered named UNIX socket transport module. Apr 30 00:46:08.974485 kernel: RPC: Registered udp transport module. Apr 30 00:46:08.974532 kernel: RPC: Registered tcp transport module. Apr 30 00:46:08.975246 kernel: RPC: Registered tcp-with-tls transport module. Apr 30 00:46:08.976310 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 30 00:46:09.052494 kubelet[2583]: E0430 00:46:09.052439 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:09.309999 kernel: NFS: Registering the id_resolver key type Apr 30 00:46:09.310184 kernel: Key type id_resolver registered Apr 30 00:46:09.310228 kernel: Key type id_legacy registered Apr 30 00:46:09.347442 nfsidmap[3964]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 30 00:46:09.353442 nfsidmap[3965]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 30 00:46:09.586120 containerd[2141]: time="2025-04-30T00:46:09.585930686Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:051eb76e-c033-4c45-af86-f26747378341,Namespace:default,Attempt:0,}" Apr 30 00:46:09.624061 systemd-networkd[1699]: vethfff0258d: Link UP Apr 30 00:46:09.624385 (udev-worker)[3957]: Network interface NamePolicy= disabled on kernel command line. Apr 30 00:46:09.628356 kernel: cni0: port 3(vethfff0258d) entered blocking state Apr 30 00:46:09.628398 kernel: cni0: port 3(vethfff0258d) entered disabled state Apr 30 00:46:09.628440 kernel: vethfff0258d: entered allmulticast mode Apr 30 00:46:09.630597 kernel: vethfff0258d: entered promiscuous mode Apr 30 00:46:09.641045 kernel: cni0: port 3(vethfff0258d) entered blocking state Apr 30 00:46:09.641150 kernel: cni0: port 3(vethfff0258d) entered forwarding state Apr 30 00:46:09.640066 systemd-networkd[1699]: vethfff0258d: Gained carrier Apr 30 00:46:09.647491 containerd[2141]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018938), "name":"cbr0", "type":"bridge"} Apr 30 00:46:09.647491 containerd[2141]: delegateAdd: netconf sent to delegate plugin: Apr 30 00:46:09.675584 containerd[2141]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-04-30T00:46:09.674620131Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 00:46:09.675584 containerd[2141]: time="2025-04-30T00:46:09.674714199Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 00:46:09.675584 containerd[2141]: time="2025-04-30T00:46:09.674762859Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:46:09.675584 containerd[2141]: time="2025-04-30T00:46:09.675023259Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 00:46:09.754182 containerd[2141]: time="2025-04-30T00:46:09.754120779Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:051eb76e-c033-4c45-af86-f26747378341,Namespace:default,Attempt:0,} returns sandbox id \"de1518caeb9a78e0eff2c897ee3e3e33d8bb7246907e2103af26e1ee58980c9b\"" Apr 30 00:46:09.757194 containerd[2141]: time="2025-04-30T00:46:09.757129899Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 00:46:10.053319 containerd[2141]: time="2025-04-30T00:46:10.053251057Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 00:46:10.054064 kubelet[2583]: E0430 00:46:10.053999 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:10.055176 containerd[2141]: time="2025-04-30T00:46:10.055115761Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 30 00:46:10.060748 containerd[2141]: time="2025-04-30T00:46:10.060683797Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"69948516\" in 303.490442ms" Apr 30 00:46:10.061015 containerd[2141]: time="2025-04-30T00:46:10.060748441Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 30 00:46:10.064202 containerd[2141]: time="2025-04-30T00:46:10.063970381Z" level=info msg="CreateContainer within sandbox \"de1518caeb9a78e0eff2c897ee3e3e33d8bb7246907e2103af26e1ee58980c9b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 30 00:46:10.082550 containerd[2141]: time="2025-04-30T00:46:10.082410889Z" level=info msg="CreateContainer within sandbox \"de1518caeb9a78e0eff2c897ee3e3e33d8bb7246907e2103af26e1ee58980c9b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"685155daa40d7524881c19404a77d2fb3021ab35d248cab6d5075e9d7bdc7fe9\"" Apr 30 00:46:10.084681 containerd[2141]: time="2025-04-30T00:46:10.084298489Z" level=info msg="StartContainer for \"685155daa40d7524881c19404a77d2fb3021ab35d248cab6d5075e9d7bdc7fe9\"" Apr 30 00:46:10.185569 containerd[2141]: time="2025-04-30T00:46:10.185511865Z" level=info msg="StartContainer for \"685155daa40d7524881c19404a77d2fb3021ab35d248cab6d5075e9d7bdc7fe9\" returns successfully" Apr 30 00:46:10.422685 kubelet[2583]: I0430 00:46:10.422451 2583 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.116945304 podStartE2EDuration="17.422427662s" podCreationTimestamp="2025-04-30 00:45:53 +0000 UTC" firstStartedPulling="2025-04-30 00:46:09.756383919 +0000 UTC m=+60.483792469" lastFinishedPulling="2025-04-30 00:46:10.061866277 +0000 UTC m=+60.789274827" observedRunningTime="2025-04-30 00:46:10.422239766 +0000 UTC m=+61.149648328" watchObservedRunningTime="2025-04-30 00:46:10.422427662 +0000 UTC m=+61.149836212" Apr 30 00:46:10.914143 systemd[1]: run-containerd-runc-k8s.io-685155daa40d7524881c19404a77d2fb3021ab35d248cab6d5075e9d7bdc7fe9-runc.lY11l1.mount: Deactivated successfully. Apr 30 00:46:11.055098 kubelet[2583]: E0430 00:46:11.055021 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:11.149114 systemd-networkd[1699]: vethfff0258d: Gained IPv6LL Apr 30 00:46:12.055846 kubelet[2583]: E0430 00:46:12.055775 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:13.013047 kubelet[2583]: E0430 00:46:13.012971 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:13.056969 kubelet[2583]: E0430 00:46:13.056903 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:13.615757 ntpd[2091]: Listen normally on 12 vethfff0258d [fe80::6017:26ff:feec:74b3%7]:123 Apr 30 00:46:13.616656 ntpd[2091]: 30 Apr 00:46:13 ntpd[2091]: Listen normally on 12 vethfff0258d [fe80::6017:26ff:feec:74b3%7]:123 Apr 30 00:46:14.057422 kubelet[2583]: E0430 00:46:14.057353 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:15.058047 kubelet[2583]: E0430 00:46:15.057940 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:16.058416 kubelet[2583]: E0430 00:46:16.058350 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:17.059132 kubelet[2583]: E0430 00:46:17.059065 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:18.060069 kubelet[2583]: E0430 00:46:18.060011 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:19.060516 kubelet[2583]: E0430 00:46:19.060450 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:20.061441 kubelet[2583]: E0430 00:46:20.061383 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:21.062251 kubelet[2583]: E0430 00:46:21.062182 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:22.063012 kubelet[2583]: E0430 00:46:22.062947 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:23.063822 kubelet[2583]: E0430 00:46:23.063745 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:24.064833 kubelet[2583]: E0430 00:46:24.064761 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:24.327955 kubelet[2583]: E0430 00:46:24.327034 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?resourceVersion=0&timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.327955 kubelet[2583]: E0430 00:46:24.327586 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.328144 kubelet[2583]: E0430 00:46:24.328053 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.328515 kubelet[2583]: E0430 00:46:24.328481 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.328923 kubelet[2583]: E0430 00:46:24.328842 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.328923 kubelet[2583]: E0430 00:46:24.328872 2583 kubelet_node_status.go:531] "Unable to update node status" err="update node status exceeds retry count" Apr 30 00:46:24.415669 kubelet[2583]: E0430 00:46:24.415503 2583 controller.go:195] "Failed to update lease" err="Put \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.416231 kubelet[2583]: E0430 00:46:24.415989 2583 controller.go:195] "Failed to update lease" err="Put \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.416570 kubelet[2583]: E0430 00:46:24.416505 2583 controller.go:195] "Failed to update lease" err="Put \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.417108 kubelet[2583]: E0430 00:46:24.417071 2583 controller.go:195] "Failed to update lease" err="Put \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.417719 kubelet[2583]: E0430 00:46:24.417672 2583 controller.go:195] "Failed to update lease" err="Put \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" Apr 30 00:46:24.417839 kubelet[2583]: I0430 00:46:24.417722 2583 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Apr 30 00:46:24.418135 kubelet[2583]: E0430 00:46:24.418085 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" interval="200ms" Apr 30 00:46:24.620536 kubelet[2583]: E0430 00:46:24.619775 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" interval="400ms" Apr 30 00:46:25.020616 kubelet[2583]: E0430 00:46:25.020551 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" interval="800ms" Apr 30 00:46:25.065837 kubelet[2583]: E0430 00:46:25.065775 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:25.822367 kubelet[2583]: E0430 00:46:25.822285 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" interval="1.6s" Apr 30 00:46:26.066627 kubelet[2583]: E0430 00:46:26.066549 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:27.067117 kubelet[2583]: E0430 00:46:27.066993 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:27.424098 kubelet[2583]: E0430 00:46:27.423937 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": dial tcp 172.31.23.80:6443: connect: connection refused" interval="3.2s" Apr 30 00:46:28.068108 kubelet[2583]: E0430 00:46:28.068043 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:29.069125 kubelet[2583]: E0430 00:46:29.069031 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:30.070348 kubelet[2583]: E0430 00:46:30.070270 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:31.071208 kubelet[2583]: E0430 00:46:31.071132 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:32.072071 kubelet[2583]: E0430 00:46:32.072001 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:33.012806 kubelet[2583]: E0430 00:46:33.012732 2583 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:33.072507 kubelet[2583]: E0430 00:46:33.072415 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:34.072781 kubelet[2583]: E0430 00:46:34.072715 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:35.073719 kubelet[2583]: E0430 00:46:35.073660 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:36.074794 kubelet[2583]: E0430 00:46:36.074734 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:37.075066 kubelet[2583]: E0430 00:46:37.075006 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:38.075998 kubelet[2583]: E0430 00:46:38.075936 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:39.076493 kubelet[2583]: E0430 00:46:39.076417 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:40.077176 kubelet[2583]: E0430 00:46:40.077120 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:40.626122 kubelet[2583]: E0430 00:46:40.626040 2583 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.80:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.24.174?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="6.4s" Apr 30 00:46:41.077841 kubelet[2583]: E0430 00:46:41.077788 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:42.077990 kubelet[2583]: E0430 00:46:42.077923 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:43.079036 kubelet[2583]: E0430 00:46:43.078995 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:44.080157 kubelet[2583]: E0430 00:46:44.080084 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:44.603945 kubelet[2583]: E0430 00:46:44.603826 2583 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.24.174\": Get \"https://172.31.23.80:6443/api/v1/nodes/172.31.24.174?resourceVersion=0&timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" Apr 30 00:46:45.081219 kubelet[2583]: E0430 00:46:45.081162 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:46:46.082225 kubelet[2583]: E0430 00:46:46.082167 2583 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"