Apr 29 23:57:56.202852 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Apr 29 23:57:56.202901 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Apr 29 22:24:03 -00 2025 Apr 29 23:57:56.202946 kernel: KASLR disabled due to lack of seed Apr 29 23:57:56.202968 kernel: efi: EFI v2.7 by EDK II Apr 29 23:57:56.202985 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x78503d98 Apr 29 23:57:56.203001 kernel: secureboot: Secure boot disabled Apr 29 23:57:56.203019 kernel: ACPI: Early table checksum verification disabled Apr 29 23:57:56.203035 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Apr 29 23:57:56.203051 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Apr 29 23:57:56.203067 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Apr 29 23:57:56.203089 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Apr 29 23:57:56.203105 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Apr 29 23:57:56.203121 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Apr 29 23:57:56.203137 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Apr 29 23:57:56.203155 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Apr 29 23:57:56.203176 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Apr 29 23:57:56.203194 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Apr 29 23:57:56.203210 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Apr 29 23:57:56.203226 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Apr 29 23:57:56.203243 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Apr 29 23:57:56.203259 kernel: printk: bootconsole [uart0] enabled Apr 29 23:57:56.203275 kernel: NUMA: Failed to initialise from firmware Apr 29 23:57:56.203292 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Apr 29 23:57:56.203308 kernel: NUMA: NODE_DATA [mem 0x4b5840800-0x4b5845fff] Apr 29 23:57:56.203325 kernel: Zone ranges: Apr 29 23:57:56.203342 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Apr 29 23:57:56.203362 kernel: DMA32 empty Apr 29 23:57:56.203379 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Apr 29 23:57:56.203396 kernel: Movable zone start for each node Apr 29 23:57:56.203412 kernel: Early memory node ranges Apr 29 23:57:56.203429 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Apr 29 23:57:56.203446 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Apr 29 23:57:56.203462 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Apr 29 23:57:56.203478 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Apr 29 23:57:56.203494 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Apr 29 23:57:56.203511 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Apr 29 23:57:56.203527 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Apr 29 23:57:56.203543 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Apr 29 23:57:56.203564 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Apr 29 23:57:56.203581 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Apr 29 23:57:56.203605 kernel: psci: probing for conduit method from ACPI. Apr 29 23:57:56.203622 kernel: psci: PSCIv1.0 detected in firmware. Apr 29 23:57:56.203640 kernel: psci: Using standard PSCI v0.2 function IDs Apr 29 23:57:56.203661 kernel: psci: Trusted OS migration not required Apr 29 23:57:56.203679 kernel: psci: SMC Calling Convention v1.1 Apr 29 23:57:56.203696 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Apr 29 23:57:56.203713 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Apr 29 23:57:56.203731 kernel: pcpu-alloc: [0] 0 [0] 1 Apr 29 23:57:56.203748 kernel: Detected PIPT I-cache on CPU0 Apr 29 23:57:56.203766 kernel: CPU features: detected: GIC system register CPU interface Apr 29 23:57:56.203783 kernel: CPU features: detected: Spectre-v2 Apr 29 23:57:56.203801 kernel: CPU features: detected: Spectre-v3a Apr 29 23:57:56.203818 kernel: CPU features: detected: Spectre-BHB Apr 29 23:57:56.203836 kernel: CPU features: detected: ARM erratum 1742098 Apr 29 23:57:56.203853 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Apr 29 23:57:56.203875 kernel: alternatives: applying boot alternatives Apr 29 23:57:56.203895 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=6e9bced8073e517a5f5178e5412663c3084f53d67852b3dfe0380ce71e6d0edd Apr 29 23:57:56.203913 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 29 23:57:56.206003 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 29 23:57:56.206043 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 29 23:57:56.206061 kernel: Fallback order for Node 0: 0 Apr 29 23:57:56.206079 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Apr 29 23:57:56.206097 kernel: Policy zone: Normal Apr 29 23:57:56.206114 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 29 23:57:56.206132 kernel: software IO TLB: area num 2. Apr 29 23:57:56.206160 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Apr 29 23:57:56.206180 kernel: Memory: 3819836K/4030464K available (10304K kernel code, 2186K rwdata, 8104K rodata, 39744K init, 897K bss, 210628K reserved, 0K cma-reserved) Apr 29 23:57:56.206198 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 29 23:57:56.206215 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 29 23:57:56.206234 kernel: rcu: RCU event tracing is enabled. Apr 29 23:57:56.206252 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 29 23:57:56.206270 kernel: Trampoline variant of Tasks RCU enabled. Apr 29 23:57:56.206287 kernel: Tracing variant of Tasks RCU enabled. Apr 29 23:57:56.206305 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 29 23:57:56.206343 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 29 23:57:56.206362 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 29 23:57:56.206385 kernel: GICv3: 96 SPIs implemented Apr 29 23:57:56.206403 kernel: GICv3: 0 Extended SPIs implemented Apr 29 23:57:56.206421 kernel: Root IRQ handler: gic_handle_irq Apr 29 23:57:56.206438 kernel: GICv3: GICv3 features: 16 PPIs Apr 29 23:57:56.206455 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Apr 29 23:57:56.206472 kernel: ITS [mem 0x10080000-0x1009ffff] Apr 29 23:57:56.206490 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Apr 29 23:57:56.206508 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Apr 29 23:57:56.206525 kernel: GICv3: using LPI property table @0x00000004000d0000 Apr 29 23:57:56.206542 kernel: ITS: Using hypervisor restricted LPI range [128] Apr 29 23:57:56.206560 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Apr 29 23:57:56.206577 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 29 23:57:56.206599 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Apr 29 23:57:56.206617 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Apr 29 23:57:56.206635 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Apr 29 23:57:56.206652 kernel: Console: colour dummy device 80x25 Apr 29 23:57:56.206671 kernel: printk: console [tty1] enabled Apr 29 23:57:56.206688 kernel: ACPI: Core revision 20230628 Apr 29 23:57:56.206707 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Apr 29 23:57:56.206725 kernel: pid_max: default: 32768 minimum: 301 Apr 29 23:57:56.206743 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 29 23:57:56.206761 kernel: landlock: Up and running. Apr 29 23:57:56.206783 kernel: SELinux: Initializing. Apr 29 23:57:56.206801 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 29 23:57:56.206819 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 29 23:57:56.206836 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 29 23:57:56.206855 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 29 23:57:56.206872 kernel: rcu: Hierarchical SRCU implementation. Apr 29 23:57:56.206891 kernel: rcu: Max phase no-delay instances is 400. Apr 29 23:57:56.206908 kernel: Platform MSI: ITS@0x10080000 domain created Apr 29 23:57:56.206961 kernel: PCI/MSI: ITS@0x10080000 domain created Apr 29 23:57:56.206983 kernel: Remapping and enabling EFI services. Apr 29 23:57:56.207006 kernel: smp: Bringing up secondary CPUs ... Apr 29 23:57:56.207024 kernel: Detected PIPT I-cache on CPU1 Apr 29 23:57:56.207042 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Apr 29 23:57:56.207060 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Apr 29 23:57:56.207078 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Apr 29 23:57:56.207096 kernel: smp: Brought up 1 node, 2 CPUs Apr 29 23:57:56.207113 kernel: SMP: Total of 2 processors activated. Apr 29 23:57:56.207131 kernel: CPU features: detected: 32-bit EL0 Support Apr 29 23:57:56.207156 kernel: CPU features: detected: 32-bit EL1 Support Apr 29 23:57:56.207174 kernel: CPU features: detected: CRC32 instructions Apr 29 23:57:56.207203 kernel: CPU: All CPU(s) started at EL1 Apr 29 23:57:56.207226 kernel: alternatives: applying system-wide alternatives Apr 29 23:57:56.207245 kernel: devtmpfs: initialized Apr 29 23:57:56.207263 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 29 23:57:56.207282 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 29 23:57:56.207300 kernel: pinctrl core: initialized pinctrl subsystem Apr 29 23:57:56.207319 kernel: SMBIOS 3.0.0 present. Apr 29 23:57:56.207343 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Apr 29 23:57:56.207361 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 29 23:57:56.207380 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 29 23:57:56.207399 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 29 23:57:56.207418 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 29 23:57:56.207436 kernel: audit: initializing netlink subsys (disabled) Apr 29 23:57:56.207455 kernel: audit: type=2000 audit(0.220:1): state=initialized audit_enabled=0 res=1 Apr 29 23:57:56.207478 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 29 23:57:56.207497 kernel: cpuidle: using governor menu Apr 29 23:57:56.207515 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 29 23:57:56.207534 kernel: ASID allocator initialised with 65536 entries Apr 29 23:57:56.207553 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 29 23:57:56.207571 kernel: Serial: AMBA PL011 UART driver Apr 29 23:57:56.207590 kernel: Modules: 17408 pages in range for non-PLT usage Apr 29 23:57:56.207609 kernel: Modules: 508928 pages in range for PLT usage Apr 29 23:57:56.207627 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 29 23:57:56.207650 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 29 23:57:56.207669 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 29 23:57:56.207687 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 29 23:57:56.207706 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 29 23:57:56.207725 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 29 23:57:56.207743 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 29 23:57:56.207762 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 29 23:57:56.207780 kernel: ACPI: Added _OSI(Module Device) Apr 29 23:57:56.207799 kernel: ACPI: Added _OSI(Processor Device) Apr 29 23:57:56.207821 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 29 23:57:56.207840 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 29 23:57:56.207859 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 29 23:57:56.207877 kernel: ACPI: Interpreter enabled Apr 29 23:57:56.207896 kernel: ACPI: Using GIC for interrupt routing Apr 29 23:57:56.207915 kernel: ACPI: MCFG table detected, 1 entries Apr 29 23:57:56.209961 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Apr 29 23:57:56.210265 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 29 23:57:56.210513 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 29 23:57:56.210717 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 29 23:57:56.210918 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Apr 29 23:57:56.213175 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Apr 29 23:57:56.213202 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Apr 29 23:57:56.213222 kernel: acpiphp: Slot [1] registered Apr 29 23:57:56.213241 kernel: acpiphp: Slot [2] registered Apr 29 23:57:56.213260 kernel: acpiphp: Slot [3] registered Apr 29 23:57:56.213286 kernel: acpiphp: Slot [4] registered Apr 29 23:57:56.213305 kernel: acpiphp: Slot [5] registered Apr 29 23:57:56.213324 kernel: acpiphp: Slot [6] registered Apr 29 23:57:56.213342 kernel: acpiphp: Slot [7] registered Apr 29 23:57:56.213360 kernel: acpiphp: Slot [8] registered Apr 29 23:57:56.213379 kernel: acpiphp: Slot [9] registered Apr 29 23:57:56.213397 kernel: acpiphp: Slot [10] registered Apr 29 23:57:56.213415 kernel: acpiphp: Slot [11] registered Apr 29 23:57:56.213434 kernel: acpiphp: Slot [12] registered Apr 29 23:57:56.213453 kernel: acpiphp: Slot [13] registered Apr 29 23:57:56.213477 kernel: acpiphp: Slot [14] registered Apr 29 23:57:56.213496 kernel: acpiphp: Slot [15] registered Apr 29 23:57:56.213514 kernel: acpiphp: Slot [16] registered Apr 29 23:57:56.213533 kernel: acpiphp: Slot [17] registered Apr 29 23:57:56.213552 kernel: acpiphp: Slot [18] registered Apr 29 23:57:56.213570 kernel: acpiphp: Slot [19] registered Apr 29 23:57:56.213588 kernel: acpiphp: Slot [20] registered Apr 29 23:57:56.213607 kernel: acpiphp: Slot [21] registered Apr 29 23:57:56.213626 kernel: acpiphp: Slot [22] registered Apr 29 23:57:56.213648 kernel: acpiphp: Slot [23] registered Apr 29 23:57:56.213667 kernel: acpiphp: Slot [24] registered Apr 29 23:57:56.213686 kernel: acpiphp: Slot [25] registered Apr 29 23:57:56.213705 kernel: acpiphp: Slot [26] registered Apr 29 23:57:56.213724 kernel: acpiphp: Slot [27] registered Apr 29 23:57:56.213743 kernel: acpiphp: Slot [28] registered Apr 29 23:57:56.213761 kernel: acpiphp: Slot [29] registered Apr 29 23:57:56.213780 kernel: acpiphp: Slot [30] registered Apr 29 23:57:56.213799 kernel: acpiphp: Slot [31] registered Apr 29 23:57:56.213817 kernel: PCI host bridge to bus 0000:00 Apr 29 23:57:56.214097 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Apr 29 23:57:56.214296 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 29 23:57:56.214514 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Apr 29 23:57:56.214701 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Apr 29 23:57:56.215004 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Apr 29 23:57:56.215253 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Apr 29 23:57:56.215479 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Apr 29 23:57:56.215709 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Apr 29 23:57:56.215918 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Apr 29 23:57:56.216416 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 29 23:57:56.216644 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Apr 29 23:57:56.216850 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Apr 29 23:57:56.223320 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Apr 29 23:57:56.223564 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Apr 29 23:57:56.223771 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 29 23:57:56.226501 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Apr 29 23:57:56.226733 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Apr 29 23:57:56.226964 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Apr 29 23:57:56.228356 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Apr 29 23:57:56.228609 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Apr 29 23:57:56.228831 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Apr 29 23:57:56.229518 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 29 23:57:56.229710 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Apr 29 23:57:56.229737 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 29 23:57:56.229757 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 29 23:57:56.229777 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 29 23:57:56.229796 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 29 23:57:56.229815 kernel: iommu: Default domain type: Translated Apr 29 23:57:56.229847 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 29 23:57:56.229865 kernel: efivars: Registered efivars operations Apr 29 23:57:56.229884 kernel: vgaarb: loaded Apr 29 23:57:56.229903 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 29 23:57:56.229921 kernel: VFS: Disk quotas dquot_6.6.0 Apr 29 23:57:56.229965 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 29 23:57:56.229986 kernel: pnp: PnP ACPI init Apr 29 23:57:56.230221 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Apr 29 23:57:56.230261 kernel: pnp: PnP ACPI: found 1 devices Apr 29 23:57:56.230280 kernel: NET: Registered PF_INET protocol family Apr 29 23:57:56.230300 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 29 23:57:56.230339 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 29 23:57:56.230361 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 29 23:57:56.230382 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 29 23:57:56.230402 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 29 23:57:56.230422 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 29 23:57:56.230442 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 29 23:57:56.230470 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 29 23:57:56.230489 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 29 23:57:56.230508 kernel: PCI: CLS 0 bytes, default 64 Apr 29 23:57:56.230527 kernel: kvm [1]: HYP mode not available Apr 29 23:57:56.230545 kernel: Initialise system trusted keyrings Apr 29 23:57:56.230564 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 29 23:57:56.230583 kernel: Key type asymmetric registered Apr 29 23:57:56.230602 kernel: Asymmetric key parser 'x509' registered Apr 29 23:57:56.230621 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 29 23:57:56.230645 kernel: io scheduler mq-deadline registered Apr 29 23:57:56.230665 kernel: io scheduler kyber registered Apr 29 23:57:56.230684 kernel: io scheduler bfq registered Apr 29 23:57:56.233179 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Apr 29 23:57:56.233230 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 29 23:57:56.233251 kernel: ACPI: button: Power Button [PWRB] Apr 29 23:57:56.233271 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Apr 29 23:57:56.233290 kernel: ACPI: button: Sleep Button [SLPB] Apr 29 23:57:56.233319 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 29 23:57:56.233340 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Apr 29 23:57:56.233572 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Apr 29 23:57:56.233599 kernel: printk: console [ttyS0] disabled Apr 29 23:57:56.233619 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Apr 29 23:57:56.233638 kernel: printk: console [ttyS0] enabled Apr 29 23:57:56.233656 kernel: printk: bootconsole [uart0] disabled Apr 29 23:57:56.233675 kernel: thunder_xcv, ver 1.0 Apr 29 23:57:56.233693 kernel: thunder_bgx, ver 1.0 Apr 29 23:57:56.233712 kernel: nicpf, ver 1.0 Apr 29 23:57:56.233736 kernel: nicvf, ver 1.0 Apr 29 23:57:56.233973 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 29 23:57:56.234178 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-04-29T23:57:55 UTC (1745971075) Apr 29 23:57:56.234205 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 29 23:57:56.234225 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Apr 29 23:57:56.234247 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 29 23:57:56.234266 kernel: watchdog: Hard watchdog permanently disabled Apr 29 23:57:56.234292 kernel: NET: Registered PF_INET6 protocol family Apr 29 23:57:56.234329 kernel: Segment Routing with IPv6 Apr 29 23:57:56.234353 kernel: In-situ OAM (IOAM) with IPv6 Apr 29 23:57:56.234372 kernel: NET: Registered PF_PACKET protocol family Apr 29 23:57:56.234390 kernel: Key type dns_resolver registered Apr 29 23:57:56.234409 kernel: registered taskstats version 1 Apr 29 23:57:56.234428 kernel: Loading compiled-in X.509 certificates Apr 29 23:57:56.234447 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: bbef389676bd9584646af24e9e264c7789f8630f' Apr 29 23:57:56.234466 kernel: Key type .fscrypt registered Apr 29 23:57:56.234484 kernel: Key type fscrypt-provisioning registered Apr 29 23:57:56.234509 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 29 23:57:56.234528 kernel: ima: Allocated hash algorithm: sha1 Apr 29 23:57:56.234546 kernel: ima: No architecture policies found Apr 29 23:57:56.234565 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 29 23:57:56.234583 kernel: clk: Disabling unused clocks Apr 29 23:57:56.234603 kernel: Freeing unused kernel memory: 39744K Apr 29 23:57:56.234621 kernel: Run /init as init process Apr 29 23:57:56.234640 kernel: with arguments: Apr 29 23:57:56.234660 kernel: /init Apr 29 23:57:56.234684 kernel: with environment: Apr 29 23:57:56.234703 kernel: HOME=/ Apr 29 23:57:56.234722 kernel: TERM=linux Apr 29 23:57:56.234740 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 29 23:57:56.234764 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 29 23:57:56.234789 systemd[1]: Detected virtualization amazon. Apr 29 23:57:56.234810 systemd[1]: Detected architecture arm64. Apr 29 23:57:56.234835 systemd[1]: Running in initrd. Apr 29 23:57:56.234855 systemd[1]: No hostname configured, using default hostname. Apr 29 23:57:56.234875 systemd[1]: Hostname set to . Apr 29 23:57:56.234896 systemd[1]: Initializing machine ID from VM UUID. Apr 29 23:57:56.234917 systemd[1]: Queued start job for default target initrd.target. Apr 29 23:57:56.237045 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:57:56.237079 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:57:56.237103 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 29 23:57:56.237134 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 29 23:57:56.237156 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 29 23:57:56.237178 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 29 23:57:56.237202 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 29 23:57:56.237224 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 29 23:57:56.237245 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:57:56.237266 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:57:56.237292 systemd[1]: Reached target paths.target - Path Units. Apr 29 23:57:56.237313 systemd[1]: Reached target slices.target - Slice Units. Apr 29 23:57:56.237334 systemd[1]: Reached target swap.target - Swaps. Apr 29 23:57:56.237355 systemd[1]: Reached target timers.target - Timer Units. Apr 29 23:57:56.237375 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 29 23:57:56.237396 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 29 23:57:56.237417 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 29 23:57:56.237439 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 29 23:57:56.237460 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:57:56.237487 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 29 23:57:56.237508 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:57:56.237529 systemd[1]: Reached target sockets.target - Socket Units. Apr 29 23:57:56.237550 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 29 23:57:56.237571 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 29 23:57:56.237592 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 29 23:57:56.237612 systemd[1]: Starting systemd-fsck-usr.service... Apr 29 23:57:56.237633 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 29 23:57:56.237658 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 29 23:57:56.237680 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:57:56.237700 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 29 23:57:56.237721 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:57:56.237742 systemd[1]: Finished systemd-fsck-usr.service. Apr 29 23:57:56.237805 systemd-journald[252]: Collecting audit messages is disabled. Apr 29 23:57:56.237854 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 29 23:57:56.237876 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:57:56.237898 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 29 23:57:56.237924 systemd-journald[252]: Journal started Apr 29 23:57:56.240386 systemd-journald[252]: Runtime Journal (/run/log/journal/ec2bd30801e98318349c04da8bf62e51) is 8.0M, max 75.3M, 67.3M free. Apr 29 23:57:56.240496 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:57:56.195545 systemd-modules-load[253]: Inserted module 'overlay' Apr 29 23:57:56.245266 kernel: Bridge firewalling registered Apr 29 23:57:56.244128 systemd-modules-load[253]: Inserted module 'br_netfilter' Apr 29 23:57:56.255180 systemd[1]: Started systemd-journald.service - Journal Service. Apr 29 23:57:56.258803 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 29 23:57:56.269700 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:57:56.286304 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 29 23:57:56.298361 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 29 23:57:56.318548 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 29 23:57:56.342478 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:57:56.343699 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:57:56.359602 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 29 23:57:56.366041 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:57:56.377445 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:57:56.380241 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 29 23:57:56.406438 dracut-cmdline[284]: dracut-dracut-053 Apr 29 23:57:56.414600 dracut-cmdline[284]: 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=6e9bced8073e517a5f5178e5412663c3084f53d67852b3dfe0380ce71e6d0edd Apr 29 23:57:56.477462 systemd-resolved[290]: Positive Trust Anchors: Apr 29 23:57:56.477514 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 29 23:57:56.477575 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 29 23:57:56.587997 kernel: SCSI subsystem initialized Apr 29 23:57:56.595978 kernel: Loading iSCSI transport class v2.0-870. Apr 29 23:57:56.607968 kernel: iscsi: registered transport (tcp) Apr 29 23:57:56.630228 kernel: iscsi: registered transport (qla4xxx) Apr 29 23:57:56.630332 kernel: QLogic iSCSI HBA Driver Apr 29 23:57:56.699970 kernel: random: crng init done Apr 29 23:57:56.700408 systemd-resolved[290]: Defaulting to hostname 'linux'. Apr 29 23:57:56.704018 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 29 23:57:56.705169 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:57:56.732410 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 29 23:57:56.744246 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 29 23:57:56.787855 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 29 23:57:56.787982 kernel: device-mapper: uevent: version 1.0.3 Apr 29 23:57:56.788014 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 29 23:57:56.854983 kernel: raid6: neonx8 gen() 6811 MB/s Apr 29 23:57:56.871968 kernel: raid6: neonx4 gen() 6607 MB/s Apr 29 23:57:56.888966 kernel: raid6: neonx2 gen() 5481 MB/s Apr 29 23:57:56.905965 kernel: raid6: neonx1 gen() 3963 MB/s Apr 29 23:57:56.922968 kernel: raid6: int64x8 gen() 3829 MB/s Apr 29 23:57:56.939965 kernel: raid6: int64x4 gen() 3732 MB/s Apr 29 23:57:56.956967 kernel: raid6: int64x2 gen() 3597 MB/s Apr 29 23:57:56.974812 kernel: raid6: int64x1 gen() 2683 MB/s Apr 29 23:57:56.974858 kernel: raid6: using algorithm neonx8 gen() 6811 MB/s Apr 29 23:57:56.992795 kernel: raid6: .... xor() 4749 MB/s, rmw enabled Apr 29 23:57:56.992845 kernel: raid6: using neon recovery algorithm Apr 29 23:57:57.001444 kernel: xor: measuring software checksum speed Apr 29 23:57:57.001503 kernel: 8regs : 10961 MB/sec Apr 29 23:57:57.002644 kernel: 32regs : 11687 MB/sec Apr 29 23:57:57.004901 kernel: arm64_neon : 8615 MB/sec Apr 29 23:57:57.004961 kernel: xor: using function: 32regs (11687 MB/sec) Apr 29 23:57:57.089976 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 29 23:57:57.110742 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 29 23:57:57.121288 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:57:57.168332 systemd-udevd[472]: Using default interface naming scheme 'v255'. Apr 29 23:57:57.177982 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:57:57.194362 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 29 23:57:57.227003 dracut-pre-trigger[477]: rd.md=0: removing MD RAID activation Apr 29 23:57:57.283745 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 29 23:57:57.293481 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 29 23:57:57.411243 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:57:57.425269 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 29 23:57:57.478115 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 29 23:57:57.487992 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 29 23:57:57.493041 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:57:57.506534 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 29 23:57:57.533605 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 29 23:57:57.581071 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 29 23:57:57.623960 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 29 23:57:57.624090 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:57:57.641547 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 29 23:57:57.641976 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Apr 29 23:57:57.666590 kernel: ena 0000:00:05.0: ENA device version: 0.10 Apr 29 23:57:57.666863 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Apr 29 23:57:57.667143 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:c5:ac:50:8b:21 Apr 29 23:57:57.642377 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:57:57.644561 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 29 23:57:57.644678 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:57:57.648807 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:57:57.662479 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:57:57.679290 (udev-worker)[518]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:57:57.704194 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:57:57.715398 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Apr 29 23:57:57.716312 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:57:57.725069 kernel: nvme nvme0: pci function 0000:00:04.0 Apr 29 23:57:57.733145 kernel: nvme nvme0: 2/0/0 default/read/poll queues Apr 29 23:57:57.740977 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 29 23:57:57.741068 kernel: GPT:9289727 != 16777215 Apr 29 23:57:57.741094 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 29 23:57:57.743630 kernel: GPT:9289727 != 16777215 Apr 29 23:57:57.743693 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 29 23:57:57.744568 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:57:57.754730 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:57:57.859368 kernel: BTRFS: device fsid 9647859b-527c-478f-8aa1-9dfa3fa871e3 devid 1 transid 43 /dev/nvme0n1p3 scanned by (udev-worker) (520) Apr 29 23:57:57.874101 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by (udev-worker) (533) Apr 29 23:57:57.918297 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Apr 29 23:57:57.952219 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Apr 29 23:57:57.980859 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 29 23:57:57.996546 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Apr 29 23:57:57.999092 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Apr 29 23:57:58.019209 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 29 23:57:58.033774 disk-uuid[664]: Primary Header is updated. Apr 29 23:57:58.033774 disk-uuid[664]: Secondary Entries is updated. Apr 29 23:57:58.033774 disk-uuid[664]: Secondary Header is updated. Apr 29 23:57:58.042958 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:57:59.056974 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:57:59.059875 disk-uuid[665]: The operation has completed successfully. Apr 29 23:57:59.236511 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 29 23:57:59.236728 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 29 23:57:59.293364 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 29 23:57:59.312177 sh[925]: Success Apr 29 23:57:59.331052 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 29 23:57:59.431675 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 29 23:57:59.447200 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 29 23:57:59.457043 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 29 23:57:59.493859 kernel: BTRFS info (device dm-0): first mount of filesystem 9647859b-527c-478f-8aa1-9dfa3fa871e3 Apr 29 23:57:59.493923 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:57:59.495716 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 29 23:57:59.497047 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 29 23:57:59.498135 kernel: BTRFS info (device dm-0): using free space tree Apr 29 23:57:59.583950 kernel: BTRFS info (device dm-0): enabling ssd optimizations Apr 29 23:57:59.616471 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 29 23:57:59.620608 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 29 23:57:59.633176 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 29 23:57:59.640611 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 29 23:57:59.666909 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:57:59.667006 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:57:59.667047 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:57:59.675363 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:57:59.693053 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 29 23:57:59.697707 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:57:59.705871 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 29 23:57:59.717481 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 29 23:57:59.837009 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 29 23:57:59.851275 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 29 23:57:59.906024 systemd-networkd[1117]: lo: Link UP Apr 29 23:57:59.906047 systemd-networkd[1117]: lo: Gained carrier Apr 29 23:57:59.909823 systemd-networkd[1117]: Enumeration completed Apr 29 23:57:59.911297 systemd-networkd[1117]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:57:59.911304 systemd-networkd[1117]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 29 23:57:59.912101 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 29 23:57:59.923380 systemd[1]: Reached target network.target - Network. Apr 29 23:57:59.923978 systemd-networkd[1117]: eth0: Link UP Apr 29 23:57:59.923986 systemd-networkd[1117]: eth0: Gained carrier Apr 29 23:57:59.924004 systemd-networkd[1117]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:57:59.961014 systemd-networkd[1117]: eth0: DHCPv4 address 172.31.29.161/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 29 23:58:00.095232 ignition[1020]: Ignition 2.20.0 Apr 29 23:58:00.095758 ignition[1020]: Stage: fetch-offline Apr 29 23:58:00.096349 ignition[1020]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:00.096379 ignition[1020]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:00.097391 ignition[1020]: Ignition finished successfully Apr 29 23:58:00.106414 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 29 23:58:00.119553 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 29 23:58:00.142861 ignition[1127]: Ignition 2.20.0 Apr 29 23:58:00.142891 ignition[1127]: Stage: fetch Apr 29 23:58:00.144552 ignition[1127]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:00.144578 ignition[1127]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:00.145673 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:00.167415 ignition[1127]: PUT result: OK Apr 29 23:58:00.170322 ignition[1127]: parsed url from cmdline: "" Apr 29 23:58:00.170343 ignition[1127]: no config URL provided Apr 29 23:58:00.170366 ignition[1127]: reading system config file "/usr/lib/ignition/user.ign" Apr 29 23:58:00.170398 ignition[1127]: no config at "/usr/lib/ignition/user.ign" Apr 29 23:58:00.170444 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:00.174439 ignition[1127]: PUT result: OK Apr 29 23:58:00.176204 ignition[1127]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Apr 29 23:58:00.182014 ignition[1127]: GET result: OK Apr 29 23:58:00.183215 ignition[1127]: parsing config with SHA512: 2d8ab80a7160ad815d872b23497f76a873ca5832a207ab5ea700dfb49ef89566863e99f5039ee4d7364286e4af2529e1e5cbcca1c89f5a3f0fbbfdbd3619628d Apr 29 23:58:00.189394 unknown[1127]: fetched base config from "system" Apr 29 23:58:00.189422 unknown[1127]: fetched base config from "system" Apr 29 23:58:00.189437 unknown[1127]: fetched user config from "aws" Apr 29 23:58:00.191250 ignition[1127]: fetch: fetch complete Apr 29 23:58:00.191262 ignition[1127]: fetch: fetch passed Apr 29 23:58:00.192059 ignition[1127]: Ignition finished successfully Apr 29 23:58:00.200604 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 29 23:58:00.210312 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 29 23:58:00.242391 ignition[1133]: Ignition 2.20.0 Apr 29 23:58:00.242421 ignition[1133]: Stage: kargs Apr 29 23:58:00.243466 ignition[1133]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:00.243492 ignition[1133]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:00.243643 ignition[1133]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:00.245444 ignition[1133]: PUT result: OK Apr 29 23:58:00.251587 ignition[1133]: kargs: kargs passed Apr 29 23:58:00.251672 ignition[1133]: Ignition finished successfully Apr 29 23:58:00.259317 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 29 23:58:00.268256 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 29 23:58:00.300476 ignition[1140]: Ignition 2.20.0 Apr 29 23:58:00.300507 ignition[1140]: Stage: disks Apr 29 23:58:00.302362 ignition[1140]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:00.302390 ignition[1140]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:00.303021 ignition[1140]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:00.307735 ignition[1140]: PUT result: OK Apr 29 23:58:00.313447 ignition[1140]: disks: disks passed Apr 29 23:58:00.313730 ignition[1140]: Ignition finished successfully Apr 29 23:58:00.328966 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 29 23:58:00.332379 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 29 23:58:00.335529 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 29 23:58:00.344169 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 29 23:58:00.346182 systemd[1]: Reached target sysinit.target - System Initialization. Apr 29 23:58:00.348202 systemd[1]: Reached target basic.target - Basic System. Apr 29 23:58:00.367271 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 29 23:58:00.411966 systemd-fsck[1148]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 29 23:58:00.416556 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 29 23:58:00.431323 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 29 23:58:00.507963 kernel: EXT4-fs (nvme0n1p9): mounted filesystem cd2ccabc-5b27-4350-bc86-21c9a8411827 r/w with ordered data mode. Quota mode: none. Apr 29 23:58:00.509241 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 29 23:58:00.513145 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 29 23:58:00.529205 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 29 23:58:00.542151 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 29 23:58:00.547458 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 29 23:58:00.547547 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 29 23:58:00.547594 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 29 23:58:00.565962 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 29 23:58:00.574354 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1167) Apr 29 23:58:00.574430 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:00.576880 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:00.576966 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:58:00.583238 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 29 23:58:00.592997 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:58:00.595681 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 29 23:58:00.903526 initrd-setup-root[1191]: cut: /sysroot/etc/passwd: No such file or directory Apr 29 23:58:00.920703 initrd-setup-root[1198]: cut: /sysroot/etc/group: No such file or directory Apr 29 23:58:00.929304 initrd-setup-root[1205]: cut: /sysroot/etc/shadow: No such file or directory Apr 29 23:58:00.937786 initrd-setup-root[1212]: cut: /sysroot/etc/gshadow: No such file or directory Apr 29 23:58:01.199151 systemd-networkd[1117]: eth0: Gained IPv6LL Apr 29 23:58:01.260321 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 29 23:58:01.270173 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 29 23:58:01.286245 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 29 23:58:01.301491 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 29 23:58:01.304971 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:01.336022 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 29 23:58:01.350597 ignition[1281]: INFO : Ignition 2.20.0 Apr 29 23:58:01.352465 ignition[1281]: INFO : Stage: mount Apr 29 23:58:01.354420 ignition[1281]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:01.354420 ignition[1281]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:01.358734 ignition[1281]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:01.361990 ignition[1281]: INFO : PUT result: OK Apr 29 23:58:01.369101 ignition[1281]: INFO : mount: mount passed Apr 29 23:58:01.370860 ignition[1281]: INFO : Ignition finished successfully Apr 29 23:58:01.374169 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 29 23:58:01.388301 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 29 23:58:01.515443 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 29 23:58:01.551976 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/nvme0n1p6 scanned by mount (1291) Apr 29 23:58:01.552053 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:01.555752 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:01.555796 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:58:01.561962 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:58:01.566085 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 29 23:58:01.604862 ignition[1308]: INFO : Ignition 2.20.0 Apr 29 23:58:01.604862 ignition[1308]: INFO : Stage: files Apr 29 23:58:01.608203 ignition[1308]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:01.608203 ignition[1308]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:01.612458 ignition[1308]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:01.615289 ignition[1308]: INFO : PUT result: OK Apr 29 23:58:01.619599 ignition[1308]: DEBUG : files: compiled without relabeling support, skipping Apr 29 23:58:01.630567 ignition[1308]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 29 23:58:01.630567 ignition[1308]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 29 23:58:01.681112 ignition[1308]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 29 23:58:01.683895 ignition[1308]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 29 23:58:01.686902 unknown[1308]: wrote ssh authorized keys file for user: core Apr 29 23:58:01.690757 ignition[1308]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 29 23:58:01.695205 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 29 23:58:01.698572 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 29 23:58:01.698572 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 29 23:58:01.698572 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 29 23:58:01.708504 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 29 23:58:01.708504 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 29 23:58:01.708504 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 29 23:58:01.708504 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-arm64.raw: attempt #1 Apr 29 23:58:02.085542 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 29 23:58:02.455858 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-arm64.raw" Apr 29 23:58:02.460369 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 29 23:58:02.460369 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 29 23:58:02.460369 ignition[1308]: INFO : files: files passed Apr 29 23:58:02.460369 ignition[1308]: INFO : Ignition finished successfully Apr 29 23:58:02.472535 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 29 23:58:02.485307 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 29 23:58:02.492497 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 29 23:58:02.510906 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 29 23:58:02.513761 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 29 23:58:02.527266 initrd-setup-root-after-ignition[1340]: grep: Apr 29 23:58:02.527266 initrd-setup-root-after-ignition[1336]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:02.527266 initrd-setup-root-after-ignition[1336]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:02.535818 initrd-setup-root-after-ignition[1340]: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:02.540304 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 29 23:58:02.543015 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 29 23:58:02.564385 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 29 23:58:02.616477 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 29 23:58:02.616672 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 29 23:58:02.620733 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 29 23:58:02.623646 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 29 23:58:02.626170 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 29 23:58:02.641262 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 29 23:58:02.677996 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 29 23:58:02.688317 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 29 23:58:02.722207 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:58:02.722685 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:58:02.725811 systemd[1]: Stopped target timers.target - Timer Units. Apr 29 23:58:02.727249 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 29 23:58:02.727567 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 29 23:58:02.728741 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 29 23:58:02.729180 systemd[1]: Stopped target basic.target - Basic System. Apr 29 23:58:02.729760 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 29 23:58:02.730384 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 29 23:58:02.731019 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 29 23:58:02.731530 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 29 23:58:02.731828 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 29 23:58:02.732565 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 29 23:58:02.733205 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 29 23:58:02.734109 systemd[1]: Stopped target swap.target - Swaps. Apr 29 23:58:02.734336 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 29 23:58:02.734615 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 29 23:58:02.735573 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:58:02.735917 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:58:02.736111 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 29 23:58:02.754331 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:58:02.754540 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 29 23:58:02.754743 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 29 23:58:02.755814 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 29 23:58:02.756392 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 29 23:58:02.765400 systemd[1]: ignition-files.service: Deactivated successfully. Apr 29 23:58:02.765705 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 29 23:58:02.820148 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 29 23:58:02.831086 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 29 23:58:02.838642 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 29 23:58:02.839353 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:58:02.858582 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 29 23:58:02.861257 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 29 23:58:02.873629 ignition[1360]: INFO : Ignition 2.20.0 Apr 29 23:58:02.873629 ignition[1360]: INFO : Stage: umount Apr 29 23:58:02.873629 ignition[1360]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:02.873629 ignition[1360]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:02.873629 ignition[1360]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:02.873629 ignition[1360]: INFO : PUT result: OK Apr 29 23:58:02.893144 ignition[1360]: INFO : umount: umount passed Apr 29 23:58:02.893144 ignition[1360]: INFO : Ignition finished successfully Apr 29 23:58:02.889250 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 29 23:58:02.890996 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 29 23:58:02.899351 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 29 23:58:02.899594 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 29 23:58:02.908088 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 29 23:58:02.908894 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 29 23:58:02.910546 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 29 23:58:02.910641 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 29 23:58:02.910838 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 29 23:58:02.910913 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 29 23:58:02.911523 systemd[1]: Stopped target network.target - Network. Apr 29 23:58:02.911799 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 29 23:58:02.911880 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 29 23:58:02.914345 systemd[1]: Stopped target paths.target - Path Units. Apr 29 23:58:02.914599 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 29 23:58:02.942376 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:58:02.945858 systemd[1]: Stopped target slices.target - Slice Units. Apr 29 23:58:02.952031 systemd[1]: Stopped target sockets.target - Socket Units. Apr 29 23:58:02.955589 systemd[1]: iscsid.socket: Deactivated successfully. Apr 29 23:58:02.955675 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 29 23:58:02.957575 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 29 23:58:02.957639 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 29 23:58:02.964901 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 29 23:58:02.965062 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 29 23:58:02.971412 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 29 23:58:02.971531 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 29 23:58:02.975463 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 29 23:58:02.979618 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 29 23:58:02.981183 systemd-networkd[1117]: eth0: DHCPv6 lease lost Apr 29 23:58:02.994163 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 29 23:58:02.996870 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 29 23:58:02.999021 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 29 23:58:03.005424 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 29 23:58:03.007874 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 29 23:58:03.013858 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 29 23:58:03.014518 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:58:03.027149 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 29 23:58:03.029919 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 29 23:58:03.030117 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 29 23:58:03.035565 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 29 23:58:03.035696 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:58:03.048085 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 29 23:58:03.048201 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 29 23:58:03.052209 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 29 23:58:03.052324 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:58:03.055093 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:58:03.093950 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 29 23:58:03.096171 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:58:03.100491 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 29 23:58:03.100590 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 29 23:58:03.104399 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 29 23:58:03.104519 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:58:03.107304 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 29 23:58:03.107410 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 29 23:58:03.111459 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 29 23:58:03.111550 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 29 23:58:03.114191 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 29 23:58:03.114293 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:58:03.118252 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 29 23:58:03.141635 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 29 23:58:03.144226 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:58:03.149972 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Apr 29 23:58:03.150074 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:58:03.152468 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 29 23:58:03.152548 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:58:03.154886 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 29 23:58:03.154981 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:03.172114 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 29 23:58:03.172315 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 29 23:58:03.174698 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 29 23:58:03.174876 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 29 23:58:03.178585 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 29 23:58:03.178711 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 29 23:58:03.194038 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 29 23:58:03.195072 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 29 23:58:03.204758 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 29 23:58:03.219236 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 29 23:58:03.240822 systemd[1]: Switching root. Apr 29 23:58:03.282480 systemd-journald[252]: Journal stopped Apr 29 23:58:05.734214 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Apr 29 23:58:05.734379 kernel: SELinux: policy capability network_peer_controls=1 Apr 29 23:58:05.734433 kernel: SELinux: policy capability open_perms=1 Apr 29 23:58:05.734464 kernel: SELinux: policy capability extended_socket_class=1 Apr 29 23:58:05.734501 kernel: SELinux: policy capability always_check_network=0 Apr 29 23:58:05.734530 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 29 23:58:05.734560 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 29 23:58:05.734590 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 29 23:58:05.734619 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 29 23:58:05.734649 kernel: audit: type=1403 audit(1745971083.747:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 29 23:58:05.734683 systemd[1]: Successfully loaded SELinux policy in 83.057ms. Apr 29 23:58:05.734728 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.875ms. Apr 29 23:58:05.734763 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 29 23:58:05.734794 systemd[1]: Detected virtualization amazon. Apr 29 23:58:05.734825 systemd[1]: Detected architecture arm64. Apr 29 23:58:05.734861 systemd[1]: Detected first boot. Apr 29 23:58:05.734892 systemd[1]: Initializing machine ID from VM UUID. Apr 29 23:58:05.734923 zram_generator::config[1402]: No configuration found. Apr 29 23:58:05.734986 systemd[1]: Populated /etc with preset unit settings. Apr 29 23:58:05.735020 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 29 23:58:05.735052 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 29 23:58:05.735084 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 29 23:58:05.735116 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 29 23:58:05.735150 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 29 23:58:05.735183 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 29 23:58:05.735213 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 29 23:58:05.735242 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 29 23:58:05.735274 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 29 23:58:05.735306 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 29 23:58:05.735335 systemd[1]: Created slice user.slice - User and Session Slice. Apr 29 23:58:05.735365 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:58:05.735396 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:58:05.735429 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 29 23:58:05.735460 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 29 23:58:05.735490 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 29 23:58:05.735523 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 29 23:58:05.735555 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 29 23:58:05.735588 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:58:05.735617 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 29 23:58:05.735646 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 29 23:58:05.735676 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 29 23:58:05.735712 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 29 23:58:05.735741 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:58:05.735773 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 29 23:58:05.735802 systemd[1]: Reached target slices.target - Slice Units. Apr 29 23:58:05.735833 systemd[1]: Reached target swap.target - Swaps. Apr 29 23:58:05.735862 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 29 23:58:05.735893 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 29 23:58:05.740971 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:58:05.741065 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 29 23:58:05.741100 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:58:05.741132 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 29 23:58:05.741164 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 29 23:58:05.741196 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 29 23:58:05.741227 systemd[1]: Mounting media.mount - External Media Directory... Apr 29 23:58:05.741258 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 29 23:58:05.741300 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 29 23:58:05.741329 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 29 23:58:05.741364 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 29 23:58:05.741405 systemd[1]: Reached target machines.target - Containers. Apr 29 23:58:05.741435 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 29 23:58:05.741464 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 29 23:58:05.741494 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 29 23:58:05.741525 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 29 23:58:05.741557 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 29 23:58:05.743818 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 29 23:58:05.743858 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 29 23:58:05.743890 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 29 23:58:05.743922 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 29 23:58:05.743981 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 29 23:58:05.744012 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 29 23:58:05.744041 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 29 23:58:05.744073 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 29 23:58:05.744102 systemd[1]: Stopped systemd-fsck-usr.service. Apr 29 23:58:05.744133 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 29 23:58:05.744167 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 29 23:58:05.744196 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 29 23:58:05.744226 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 29 23:58:05.744255 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 29 23:58:05.744286 systemd[1]: verity-setup.service: Deactivated successfully. Apr 29 23:58:05.744316 systemd[1]: Stopped verity-setup.service. Apr 29 23:58:05.744344 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 29 23:58:05.744372 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 29 23:58:05.744400 systemd[1]: Mounted media.mount - External Media Directory. Apr 29 23:58:05.744433 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 29 23:58:05.744462 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 29 23:58:05.744491 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 29 23:58:05.744520 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:58:05.744549 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 29 23:58:05.744582 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 29 23:58:05.744612 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 29 23:58:05.744643 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 29 23:58:05.744672 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 29 23:58:05.744703 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 29 23:58:05.744732 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 29 23:58:05.744765 kernel: loop: module loaded Apr 29 23:58:05.744795 kernel: fuse: init (API version 7.39) Apr 29 23:58:05.744828 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 29 23:58:05.744859 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 29 23:58:05.744888 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 29 23:58:05.744923 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 29 23:58:05.745493 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 29 23:58:05.745525 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 29 23:58:05.745613 systemd-journald[1484]: Collecting audit messages is disabled. Apr 29 23:58:05.745663 kernel: ACPI: bus type drm_connector registered Apr 29 23:58:05.745695 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 29 23:58:05.745725 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 29 23:58:05.745756 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 29 23:58:05.745790 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 29 23:58:05.748416 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 29 23:58:05.748466 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 29 23:58:05.748502 systemd-journald[1484]: Journal started Apr 29 23:58:05.748554 systemd-journald[1484]: Runtime Journal (/run/log/journal/ec2bd30801e98318349c04da8bf62e51) is 8.0M, max 75.3M, 67.3M free. Apr 29 23:58:05.104654 systemd[1]: Queued start job for default target multi-user.target. Apr 29 23:58:05.145175 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Apr 29 23:58:05.145963 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 29 23:58:05.768152 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 29 23:58:05.768294 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 29 23:58:05.774594 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 29 23:58:05.788038 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 29 23:58:05.804005 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 29 23:58:05.825224 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 29 23:58:05.825312 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 29 23:58:05.840529 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 29 23:58:05.840629 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 29 23:58:05.844479 systemd-tmpfiles[1497]: ACLs are not supported, ignoring. Apr 29 23:58:05.844504 systemd-tmpfiles[1497]: ACLs are not supported, ignoring. Apr 29 23:58:05.858408 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 29 23:58:05.864110 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 29 23:58:05.873982 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 29 23:58:05.889612 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 29 23:58:05.898952 systemd[1]: Started systemd-journald.service - Journal Service. Apr 29 23:58:05.903129 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 29 23:58:05.908560 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:58:05.911447 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 29 23:58:05.914798 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 29 23:58:05.971063 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 29 23:58:05.988094 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 29 23:58:06.000268 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 29 23:58:06.012175 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 29 23:58:06.024222 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 29 23:58:06.039040 kernel: loop0: detected capacity change from 0 to 113536 Apr 29 23:58:06.043756 systemd-journald[1484]: Time spent on flushing to /var/log/journal/ec2bd30801e98318349c04da8bf62e51 is 137.029ms for 900 entries. Apr 29 23:58:06.043756 systemd-journald[1484]: System Journal (/var/log/journal/ec2bd30801e98318349c04da8bf62e51) is 8.0M, max 195.6M, 187.6M free. Apr 29 23:58:06.199402 systemd-journald[1484]: Received client request to flush runtime journal. Apr 29 23:58:06.199516 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 29 23:58:06.050119 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:58:06.107164 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:58:06.121289 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 29 23:58:06.181876 udevadm[1546]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 29 23:58:06.207256 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 29 23:58:06.229032 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 29 23:58:06.233221 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 29 23:58:06.241221 kernel: loop1: detected capacity change from 0 to 53784 Apr 29 23:58:06.282982 kernel: loop2: detected capacity change from 0 to 194096 Apr 29 23:58:06.301635 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 29 23:58:06.314449 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 29 23:58:06.399846 systemd-tmpfiles[1555]: ACLs are not supported, ignoring. Apr 29 23:58:06.399888 systemd-tmpfiles[1555]: ACLs are not supported, ignoring. Apr 29 23:58:06.414495 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:58:06.422992 kernel: loop3: detected capacity change from 0 to 116808 Apr 29 23:58:06.532990 kernel: loop4: detected capacity change from 0 to 113536 Apr 29 23:58:06.559979 kernel: loop5: detected capacity change from 0 to 53784 Apr 29 23:58:06.584979 kernel: loop6: detected capacity change from 0 to 194096 Apr 29 23:58:06.617972 kernel: loop7: detected capacity change from 0 to 116808 Apr 29 23:58:06.626785 (sd-merge)[1560]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Apr 29 23:58:06.628567 (sd-merge)[1560]: Merged extensions into '/usr'. Apr 29 23:58:06.643052 systemd[1]: Reloading requested from client PID 1515 ('systemd-sysext') (unit systemd-sysext.service)... Apr 29 23:58:06.643089 systemd[1]: Reloading... Apr 29 23:58:06.819976 zram_generator::config[1582]: No configuration found. Apr 29 23:58:07.207581 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 29 23:58:07.321105 systemd[1]: Reloading finished in 677 ms. Apr 29 23:58:07.365019 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 29 23:58:07.369144 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 29 23:58:07.385392 systemd[1]: Starting ensure-sysext.service... Apr 29 23:58:07.401067 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 29 23:58:07.407388 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:58:07.431411 systemd[1]: Reloading requested from client PID 1639 ('systemctl') (unit ensure-sysext.service)... Apr 29 23:58:07.431442 systemd[1]: Reloading... Apr 29 23:58:07.472325 systemd-tmpfiles[1640]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 29 23:58:07.473019 systemd-tmpfiles[1640]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 29 23:58:07.479783 systemd-tmpfiles[1640]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 29 23:58:07.483619 systemd-tmpfiles[1640]: ACLs are not supported, ignoring. Apr 29 23:58:07.483793 systemd-tmpfiles[1640]: ACLs are not supported, ignoring. Apr 29 23:58:07.491112 systemd-udevd[1641]: Using default interface naming scheme 'v255'. Apr 29 23:58:07.499710 systemd-tmpfiles[1640]: Detected autofs mount point /boot during canonicalization of boot. Apr 29 23:58:07.499738 systemd-tmpfiles[1640]: Skipping /boot Apr 29 23:58:07.528290 ldconfig[1508]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 29 23:58:07.548823 systemd-tmpfiles[1640]: Detected autofs mount point /boot during canonicalization of boot. Apr 29 23:58:07.551628 systemd-tmpfiles[1640]: Skipping /boot Apr 29 23:58:07.673077 zram_generator::config[1685]: No configuration found. Apr 29 23:58:07.836793 (udev-worker)[1678]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:08.038386 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 29 23:58:08.097131 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (1668) Apr 29 23:58:08.231395 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 29 23:58:08.231888 systemd[1]: Reloading finished in 798 ms. Apr 29 23:58:08.265324 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:58:08.271005 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 29 23:58:08.273773 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:58:08.346892 systemd[1]: Finished ensure-sysext.service. Apr 29 23:58:08.355230 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 29 23:58:08.383102 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 29 23:58:08.398370 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 29 23:58:08.410388 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 29 23:58:08.413524 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 29 23:58:08.417360 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 29 23:58:08.431347 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 29 23:58:08.436297 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 29 23:58:08.440785 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 29 23:58:08.445625 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 29 23:58:08.449401 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 29 23:58:08.455343 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 29 23:58:08.464536 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 29 23:58:08.482372 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 29 23:58:08.509819 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 29 23:58:08.511922 systemd[1]: Reached target time-set.target - System Time Set. Apr 29 23:58:08.518047 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 29 23:58:08.526297 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:58:08.537227 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 29 23:58:08.544959 lvm[1839]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 29 23:58:08.572607 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 29 23:58:08.577860 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 29 23:58:08.582028 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 29 23:58:08.582750 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 29 23:58:08.585102 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 29 23:58:08.592621 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 29 23:58:08.593052 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 29 23:58:08.614852 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 29 23:58:08.616115 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 29 23:58:08.618873 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 29 23:58:08.640322 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 29 23:58:08.653051 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 29 23:58:08.685593 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 29 23:58:08.689756 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:58:08.702326 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 29 23:58:08.706070 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 29 23:58:08.718404 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 29 23:58:08.746997 lvm[1877]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 29 23:58:08.759974 augenrules[1883]: No rules Apr 29 23:58:08.761636 systemd[1]: audit-rules.service: Deactivated successfully. Apr 29 23:58:08.763174 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 29 23:58:08.773136 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 29 23:58:08.798602 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 29 23:58:08.829473 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 29 23:58:08.835393 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 29 23:58:08.836614 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 29 23:58:08.914637 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:08.929120 systemd-networkd[1850]: lo: Link UP Apr 29 23:58:08.929135 systemd-networkd[1850]: lo: Gained carrier Apr 29 23:58:08.932817 systemd-networkd[1850]: Enumeration completed Apr 29 23:58:08.933392 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 29 23:58:08.939172 systemd-networkd[1850]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:08.939331 systemd-networkd[1850]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 29 23:58:08.942500 systemd-networkd[1850]: eth0: Link UP Apr 29 23:58:08.943236 systemd-networkd[1850]: eth0: Gained carrier Apr 29 23:58:08.943279 systemd-networkd[1850]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:08.950061 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 29 23:58:08.956116 systemd-resolved[1853]: Positive Trust Anchors: Apr 29 23:58:08.956181 systemd-resolved[1853]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 29 23:58:08.956244 systemd-resolved[1853]: 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 29 23:58:08.957049 systemd-networkd[1850]: eth0: DHCPv4 address 172.31.29.161/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 29 23:58:08.965763 systemd-resolved[1853]: Defaulting to hostname 'linux'. Apr 29 23:58:08.969010 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 29 23:58:08.971508 systemd[1]: Reached target network.target - Network. Apr 29 23:58:08.973387 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:58:08.975676 systemd[1]: Reached target sysinit.target - System Initialization. Apr 29 23:58:08.977890 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 29 23:58:08.980440 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 29 23:58:08.983210 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 29 23:58:08.985474 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 29 23:58:08.987874 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 29 23:58:08.990273 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 29 23:58:08.990324 systemd[1]: Reached target paths.target - Path Units. Apr 29 23:58:08.992124 systemd[1]: Reached target timers.target - Timer Units. Apr 29 23:58:09.004901 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 29 23:58:09.010038 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 29 23:58:09.024256 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 29 23:58:09.027435 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 29 23:58:09.029764 systemd[1]: Reached target sockets.target - Socket Units. Apr 29 23:58:09.031659 systemd[1]: Reached target basic.target - Basic System. Apr 29 23:58:09.033561 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 29 23:58:09.033621 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 29 23:58:09.044089 systemd[1]: Starting containerd.service - containerd container runtime... Apr 29 23:58:09.049158 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 29 23:58:09.067284 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 29 23:58:09.073181 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 29 23:58:09.085736 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 29 23:58:09.087900 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 29 23:58:09.091372 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 29 23:58:09.100468 systemd[1]: Started ntpd.service - Network Time Service. Apr 29 23:58:09.110687 jq[1908]: false Apr 29 23:58:09.113253 systemd[1]: Starting setup-oem.service - Setup OEM... Apr 29 23:58:09.120280 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 29 23:58:09.136243 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 29 23:58:09.148248 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 29 23:58:09.151169 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 29 23:58:09.152111 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 29 23:58:09.161436 systemd[1]: Starting update-engine.service - Update Engine... Apr 29 23:58:09.167340 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 29 23:58:09.178159 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 29 23:58:09.178535 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 29 23:58:09.211187 dbus-daemon[1907]: [system] SELinux support is enabled Apr 29 23:58:09.217678 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 29 23:58:09.223523 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 29 23:58:09.223571 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 29 23:58:09.226111 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 29 23:58:09.226150 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 29 23:58:09.241624 dbus-daemon[1907]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1850 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Apr 29 23:58:09.232117 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 29 23:58:09.232832 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 29 23:58:09.253295 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 29 23:58:09.303662 jq[1917]: true Apr 29 23:58:09.307479 extend-filesystems[1909]: Found loop4 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found loop5 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found loop6 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found loop7 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1p1 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1p4 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1p6 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1p7 Apr 29 23:58:09.311193 extend-filesystems[1909]: Found nvme0n1p9 Apr 29 23:58:09.311193 extend-filesystems[1909]: Checking size of /dev/nvme0n1p9 Apr 29 23:58:09.355209 update_engine[1916]: I20250429 23:58:09.355068 1916 main.cc:92] Flatcar Update Engine starting Apr 29 23:58:09.359106 systemd[1]: Started update-engine.service - Update Engine. Apr 29 23:58:09.361891 update_engine[1916]: I20250429 23:58:09.361266 1916 update_check_scheduler.cc:74] Next update check in 2m3s Apr 29 23:58:09.374702 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 29 23:58:09.403558 systemd[1]: motdgen.service: Deactivated successfully. Apr 29 23:58:09.405227 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 29 23:58:09.414208 (ntainerd)[1937]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 29 23:58:09.416913 ntpd[1911]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 21:35:04 UTC 2025 (1): Starting Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 21:35:04 UTC 2025 (1): Starting Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: ---------------------------------------------------- Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: ntp-4 is maintained by Network Time Foundation, Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: corporation. Support and training for ntp-4 are Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: available at https://www.nwtime.org/support Apr 29 23:58:09.419079 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: ---------------------------------------------------- Apr 29 23:58:09.417008 ntpd[1911]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 29 23:58:09.417028 ntpd[1911]: ---------------------------------------------------- Apr 29 23:58:09.417046 ntpd[1911]: ntp-4 is maintained by Network Time Foundation, Apr 29 23:58:09.417065 ntpd[1911]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 29 23:58:09.417083 ntpd[1911]: corporation. Support and training for ntp-4 are Apr 29 23:58:09.417102 ntpd[1911]: available at https://www.nwtime.org/support Apr 29 23:58:09.417120 ntpd[1911]: ---------------------------------------------------- Apr 29 23:58:09.423693 ntpd[1911]: proto: precision = 0.096 usec (-23) Apr 29 23:58:09.426162 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: proto: precision = 0.096 usec (-23) Apr 29 23:58:09.426162 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: basedate set to 2025-04-17 Apr 29 23:58:09.426162 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: gps base set to 2025-04-20 (week 2363) Apr 29 23:58:09.424189 ntpd[1911]: basedate set to 2025-04-17 Apr 29 23:58:09.424214 ntpd[1911]: gps base set to 2025-04-20 (week 2363) Apr 29 23:58:09.435128 systemd[1]: Finished setup-oem.service - Setup OEM. Apr 29 23:58:09.441500 ntpd[1911]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listen normally on 3 eth0 172.31.29.161:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listen normally on 4 lo [::1]:123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: bind(21) AF_INET6 fe80::4c5:acff:fe50:8b21%2#123 flags 0x11 failed: Cannot assign requested address Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: unable to create socket on eth0 (5) for fe80::4c5:acff:fe50:8b21%2#123 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: failed to init interface for address fe80::4c5:acff:fe50:8b21%2 Apr 29 23:58:09.445023 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: Listening on routing socket on fd #21 for interface updates Apr 29 23:58:09.443607 ntpd[1911]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 23:58:09.443863 ntpd[1911]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 23:58:09.443924 ntpd[1911]: Listen normally on 3 eth0 172.31.29.161:123 Apr 29 23:58:09.444032 ntpd[1911]: Listen normally on 4 lo [::1]:123 Apr 29 23:58:09.444117 ntpd[1911]: bind(21) AF_INET6 fe80::4c5:acff:fe50:8b21%2#123 flags 0x11 failed: Cannot assign requested address Apr 29 23:58:09.444155 ntpd[1911]: unable to create socket on eth0 (5) for fe80::4c5:acff:fe50:8b21%2#123 Apr 29 23:58:09.444185 ntpd[1911]: failed to init interface for address fe80::4c5:acff:fe50:8b21%2 Apr 29 23:58:09.444239 ntpd[1911]: Listening on routing socket on fd #21 for interface updates Apr 29 23:58:09.448971 jq[1942]: true Apr 29 23:58:09.450852 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:09.457979 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:09.457979 ntpd[1911]: 29 Apr 23:58:09 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:09.455127 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:09.469663 extend-filesystems[1909]: Resized partition /dev/nvme0n1p9 Apr 29 23:58:09.478356 extend-filesystems[1956]: resize2fs 1.47.1 (20-May-2024) Apr 29 23:58:09.491134 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Apr 29 23:58:09.489921 systemd-logind[1915]: Watching system buttons on /dev/input/event0 (Power Button) Apr 29 23:58:09.491856 systemd-logind[1915]: Watching system buttons on /dev/input/event1 (Sleep Button) Apr 29 23:58:09.495433 systemd-logind[1915]: New seat seat0. Apr 29 23:58:09.499801 systemd[1]: Started systemd-logind.service - User Login Management. Apr 29 23:58:09.615013 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Apr 29 23:58:09.643228 extend-filesystems[1956]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Apr 29 23:58:09.643228 extend-filesystems[1956]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 29 23:58:09.643228 extend-filesystems[1956]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Apr 29 23:58:09.666750 extend-filesystems[1909]: Resized filesystem in /dev/nvme0n1p9 Apr 29 23:58:09.666750 extend-filesystems[1909]: Found nvme0n1p2 Apr 29 23:58:09.666750 extend-filesystems[1909]: Found nvme0n1p3 Apr 29 23:58:09.666750 extend-filesystems[1909]: Found usr Apr 29 23:58:09.656615 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 29 23:58:09.657041 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 29 23:58:09.698094 coreos-metadata[1906]: Apr 29 23:58:09.694 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 29 23:58:09.711126 coreos-metadata[1906]: Apr 29 23:58:09.708 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Apr 29 23:58:09.711126 coreos-metadata[1906]: Apr 29 23:58:09.708 INFO Fetch successful Apr 29 23:58:09.711126 coreos-metadata[1906]: Apr 29 23:58:09.709 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Apr 29 23:58:09.711126 coreos-metadata[1906]: Apr 29 23:58:09.709 INFO Fetch successful Apr 29 23:58:09.711126 coreos-metadata[1906]: Apr 29 23:58:09.709 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Apr 29 23:58:09.712177 coreos-metadata[1906]: Apr 29 23:58:09.712 INFO Fetch successful Apr 29 23:58:09.715962 coreos-metadata[1906]: Apr 29 23:58:09.712 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Apr 29 23:58:09.715962 coreos-metadata[1906]: Apr 29 23:58:09.713 INFO Fetch successful Apr 29 23:58:09.715962 coreos-metadata[1906]: Apr 29 23:58:09.714 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Apr 29 23:58:09.716767 coreos-metadata[1906]: Apr 29 23:58:09.716 INFO Fetch failed with 404: resource not found Apr 29 23:58:09.716767 coreos-metadata[1906]: Apr 29 23:58:09.716 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Apr 29 23:58:09.719343 coreos-metadata[1906]: Apr 29 23:58:09.719 INFO Fetch successful Apr 29 23:58:09.719343 coreos-metadata[1906]: Apr 29 23:58:09.719 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Apr 29 23:58:09.720399 bash[1981]: Updated "/home/core/.ssh/authorized_keys" Apr 29 23:58:09.725658 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 29 23:58:09.735305 coreos-metadata[1906]: Apr 29 23:58:09.733 INFO Fetch successful Apr 29 23:58:09.735305 coreos-metadata[1906]: Apr 29 23:58:09.733 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Apr 29 23:58:09.736064 coreos-metadata[1906]: Apr 29 23:58:09.736 INFO Fetch successful Apr 29 23:58:09.736180 coreos-metadata[1906]: Apr 29 23:58:09.736 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Apr 29 23:58:09.742879 coreos-metadata[1906]: Apr 29 23:58:09.741 INFO Fetch successful Apr 29 23:58:09.742879 coreos-metadata[1906]: Apr 29 23:58:09.742 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Apr 29 23:58:09.750297 coreos-metadata[1906]: Apr 29 23:58:09.744 INFO Fetch successful Apr 29 23:58:09.746399 systemd[1]: Starting sshkeys.service... Apr 29 23:58:09.784064 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (1680) Apr 29 23:58:09.843497 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 29 23:58:09.862572 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 29 23:58:09.888886 dbus-daemon[1907]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 29 23:58:09.889198 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 29 23:58:09.898371 dbus-daemon[1907]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1930 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Apr 29 23:58:09.932952 containerd[1937]: time="2025-04-29T23:58:09.930566147Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 29 23:58:09.953203 systemd[1]: Starting polkit.service - Authorization Manager... Apr 29 23:58:09.970175 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 29 23:58:09.973490 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 29 23:58:09.989023 polkitd[2027]: Started polkitd version 121 Apr 29 23:58:10.008831 polkitd[2027]: Loading rules from directory /etc/polkit-1/rules.d Apr 29 23:58:10.009024 polkitd[2027]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 29 23:58:10.013323 polkitd[2027]: Finished loading, compiling and executing 2 rules Apr 29 23:58:10.023365 dbus-daemon[1907]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 29 23:58:10.024000 systemd[1]: Started polkit.service - Authorization Manager. Apr 29 23:58:10.026306 polkitd[2027]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 29 23:58:10.092790 systemd-hostnamed[1930]: Hostname set to (transient) Apr 29 23:58:10.093722 systemd-resolved[1853]: System hostname changed to 'ip-172-31-29-161'. Apr 29 23:58:10.097031 locksmithd[1945]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 29 23:58:10.101398 containerd[1937]: time="2025-04-29T23:58:10.098843732Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.103796708Z" 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 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.103863008Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.103898768Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104217740Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104255684Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104372324Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104398952Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104678192Z" 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 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104706956Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104738708Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 29 23:58:10.110991 containerd[1937]: time="2025-04-29T23:58:10.104763260Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.104919944Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.105338504Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.105551468Z" 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 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.105580652Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.105737636Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 29 23:58:10.111512 containerd[1937]: time="2025-04-29T23:58:10.105835556Z" level=info msg="metadata content store policy set" policy=shared Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114064640Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114190964Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114246176Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114299864Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114333380Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.114595580Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115036196Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115226108Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115258280Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115290944Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115321940Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115352408Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.115380908Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.124965 containerd[1937]: time="2025-04-29T23:58:10.121384460Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121588892Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121638104Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121682924Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121723544Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121780424Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121825472Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.125610 containerd[1937]: time="2025-04-29T23:58:10.121868336Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.121911716Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129127196Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129184268Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129218984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129254240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129290444Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129337100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129370856Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129403268Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129439724Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129475052Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129531572Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129566828Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132229 containerd[1937]: time="2025-04-29T23:58:10.129595184Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129793124Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129844148Z" 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 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129870524Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129898868Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129921980Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.129998396Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.130024652Z" level=info msg="NRI interface is disabled by configuration." Apr 29 23:58:10.132867 containerd[1937]: time="2025-04-29T23:58:10.130050320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 29 23:58:10.133245 containerd[1937]: time="2025-04-29T23:58:10.130581440Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Apr 29 23:58:10.133245 containerd[1937]: time="2025-04-29T23:58:10.130668836Z" level=info msg="Connect containerd service" Apr 29 23:58:10.133245 containerd[1937]: time="2025-04-29T23:58:10.130733192Z" level=info msg="using legacy CRI server" Apr 29 23:58:10.133245 containerd[1937]: time="2025-04-29T23:58:10.130750616Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 29 23:58:10.139026 containerd[1937]: time="2025-04-29T23:58:10.136277060Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 29 23:58:10.142104 containerd[1937]: time="2025-04-29T23:58:10.141767960Z" 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 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143095508Z" level=info msg="Start subscribing containerd event" Apr 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143202068Z" level=info msg="Start recovering state" Apr 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143333492Z" level=info msg="Start event monitor" Apr 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143356892Z" level=info msg="Start snapshots syncer" Apr 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143380136Z" level=info msg="Start cni network conf syncer for default" Apr 29 23:58:10.143410 containerd[1937]: time="2025-04-29T23:58:10.143398736Z" level=info msg="Start streaming server" Apr 29 23:58:10.148658 containerd[1937]: time="2025-04-29T23:58:10.148473392Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 29 23:58:10.148765 containerd[1937]: time="2025-04-29T23:58:10.148688120Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 29 23:58:10.152024 containerd[1937]: time="2025-04-29T23:58:10.148838324Z" level=info msg="containerd successfully booted in 0.220801s" Apr 29 23:58:10.148991 systemd[1]: Started containerd.service - containerd container runtime. Apr 29 23:58:10.217288 coreos-metadata[2003]: Apr 29 23:58:10.216 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 29 23:58:10.220422 coreos-metadata[2003]: Apr 29 23:58:10.220 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Apr 29 23:58:10.221375 coreos-metadata[2003]: Apr 29 23:58:10.221 INFO Fetch successful Apr 29 23:58:10.221375 coreos-metadata[2003]: Apr 29 23:58:10.221 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 29 23:58:10.223319 coreos-metadata[2003]: Apr 29 23:58:10.222 INFO Fetch successful Apr 29 23:58:10.225129 unknown[2003]: wrote ssh authorized keys file for user: core Apr 29 23:58:10.269219 update-ssh-keys[2094]: Updated "/home/core/.ssh/authorized_keys" Apr 29 23:58:10.272004 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 29 23:58:10.285578 systemd[1]: Finished sshkeys.service. Apr 29 23:58:10.351167 systemd-networkd[1850]: eth0: Gained IPv6LL Apr 29 23:58:10.359598 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 29 23:58:10.363508 systemd[1]: Reached target network-online.target - Network is Online. Apr 29 23:58:10.375370 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Apr 29 23:58:10.391316 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:58:10.399378 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 29 23:58:10.482027 amazon-ssm-agent[2108]: Initializing new seelog logger Apr 29 23:58:10.482027 amazon-ssm-agent[2108]: New Seelog Logger Creation Complete Apr 29 23:58:10.482027 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.482027 amazon-ssm-agent[2108]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.485082 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 processing appconfig overrides Apr 29 23:58:10.485082 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.485082 amazon-ssm-agent[2108]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.488970 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO Proxy environment variables: Apr 29 23:58:10.488970 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 processing appconfig overrides Apr 29 23:58:10.488970 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.488970 amazon-ssm-agent[2108]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.493172 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 processing appconfig overrides Apr 29 23:58:10.500472 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.500472 amazon-ssm-agent[2108]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:10.500657 amazon-ssm-agent[2108]: 2025/04/29 23:58:10 processing appconfig overrides Apr 29 23:58:10.505056 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 29 23:58:10.587510 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO https_proxy: Apr 29 23:58:10.688013 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO http_proxy: Apr 29 23:58:10.715483 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 29 23:58:10.784074 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO no_proxy: Apr 29 23:58:10.847020 sshd_keygen[1933]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 29 23:58:10.885963 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO Checking if agent identity type OnPrem can be assumed Apr 29 23:58:10.899050 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 29 23:58:10.909468 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 29 23:58:10.927376 systemd[1]: Started sshd@0-172.31.29.161:22-139.178.89.65:50548.service - OpenSSH per-connection server daemon (139.178.89.65:50548). Apr 29 23:58:10.961396 systemd[1]: issuegen.service: Deactivated successfully. Apr 29 23:58:10.963659 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 29 23:58:10.982583 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 29 23:58:10.988203 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO Checking if agent identity type EC2 can be assumed Apr 29 23:58:11.040891 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 29 23:58:11.053528 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 29 23:58:11.062883 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 29 23:58:11.065521 systemd[1]: Reached target getty.target - Login Prompts. Apr 29 23:58:11.087451 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO Agent will take identity from EC2 Apr 29 23:58:11.187037 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:11.230742 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:11.230742 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:11.230742 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Apr 29 23:58:11.230742 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Apr 29 23:58:11.230742 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] Starting Core Agent Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [amazon-ssm-agent] registrar detected. Attempting registration Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [Registrar] Starting registrar module Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:10 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:11 INFO [EC2Identity] EC2 registration was successful. Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:11 INFO [CredentialRefresher] credentialRefresher has started Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:11 INFO [CredentialRefresher] Starting credentials refresher loop Apr 29 23:58:11.231095 amazon-ssm-agent[2108]: 2025-04-29 23:58:11 INFO EC2RoleProvider Successfully connected with instance profile role credentials Apr 29 23:58:11.264092 sshd[2136]: Accepted publickey for core from 139.178.89.65 port 50548 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:11.267469 sshd-session[2136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:11.283784 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 29 23:58:11.288988 amazon-ssm-agent[2108]: 2025-04-29 23:58:11 INFO [CredentialRefresher] Next credential rotation will be in 31.7749894612 minutes Apr 29 23:58:11.293592 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 29 23:58:11.302971 systemd-logind[1915]: New session 1 of user core. Apr 29 23:58:11.328548 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 29 23:58:11.341474 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 29 23:58:11.359509 (systemd)[2147]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 29 23:58:11.597800 systemd[2147]: Queued start job for default target default.target. Apr 29 23:58:11.605666 systemd[2147]: Created slice app.slice - User Application Slice. Apr 29 23:58:11.605732 systemd[2147]: Reached target paths.target - Paths. Apr 29 23:58:11.605765 systemd[2147]: Reached target timers.target - Timers. Apr 29 23:58:11.611197 systemd[2147]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 29 23:58:11.632554 systemd[2147]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 29 23:58:11.632791 systemd[2147]: Reached target sockets.target - Sockets. Apr 29 23:58:11.632824 systemd[2147]: Reached target basic.target - Basic System. Apr 29 23:58:11.632944 systemd[2147]: Reached target default.target - Main User Target. Apr 29 23:58:11.633016 systemd[2147]: Startup finished in 259ms. Apr 29 23:58:11.634294 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 29 23:58:11.643324 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 29 23:58:11.871102 systemd[1]: Started sshd@1-172.31.29.161:22-139.178.89.65:50552.service - OpenSSH per-connection server daemon (139.178.89.65:50552). Apr 29 23:58:12.157100 sshd[2158]: Accepted publickey for core from 139.178.89.65 port 50552 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:12.159015 sshd-session[2158]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:12.168684 systemd-logind[1915]: New session 2 of user core. Apr 29 23:58:12.174339 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 29 23:58:12.258830 amazon-ssm-agent[2108]: 2025-04-29 23:58:12 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Apr 29 23:58:12.360876 amazon-ssm-agent[2108]: 2025-04-29 23:58:12 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2162) started Apr 29 23:58:12.370086 sshd[2160]: Connection closed by 139.178.89.65 port 50552 Apr 29 23:58:12.368806 sshd-session[2158]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:12.376449 systemd-logind[1915]: Session 2 logged out. Waiting for processes to exit. Apr 29 23:58:12.376986 systemd[1]: sshd@1-172.31.29.161:22-139.178.89.65:50552.service: Deactivated successfully. Apr 29 23:58:12.383341 systemd[1]: session-2.scope: Deactivated successfully. Apr 29 23:58:12.389446 systemd-logind[1915]: Removed session 2. Apr 29 23:58:12.419146 ntpd[1911]: Listen normally on 6 eth0 [fe80::4c5:acff:fe50:8b21%2]:123 Apr 29 23:58:12.424921 ntpd[1911]: 29 Apr 23:58:12 ntpd[1911]: Listen normally on 6 eth0 [fe80::4c5:acff:fe50:8b21%2]:123 Apr 29 23:58:12.426360 systemd[1]: Started sshd@2-172.31.29.161:22-139.178.89.65:50554.service - OpenSSH per-connection server daemon (139.178.89.65:50554). Apr 29 23:58:12.461864 amazon-ssm-agent[2108]: 2025-04-29 23:58:12 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Apr 29 23:58:12.542318 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:12.545754 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 29 23:58:12.552145 systemd[1]: Startup finished in 1.115s (kernel) + 7.911s (initrd) + 8.884s (userspace) = 17.912s. Apr 29 23:58:12.557758 (kubelet)[2182]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 29 23:58:12.734856 sshd[2171]: Accepted publickey for core from 139.178.89.65 port 50554 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:12.738275 sshd-session[2171]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:12.748302 systemd-logind[1915]: New session 3 of user core. Apr 29 23:58:12.756292 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 29 23:58:12.934996 sshd[2188]: Connection closed by 139.178.89.65 port 50554 Apr 29 23:58:12.936093 sshd-session[2171]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:12.944136 systemd-logind[1915]: Session 3 logged out. Waiting for processes to exit. Apr 29 23:58:12.945494 systemd[1]: sshd@2-172.31.29.161:22-139.178.89.65:50554.service: Deactivated successfully. Apr 29 23:58:12.949833 systemd[1]: session-3.scope: Deactivated successfully. Apr 29 23:58:12.954067 systemd-logind[1915]: Removed session 3. Apr 29 23:58:13.841158 kubelet[2182]: E0429 23:58:13.841036 2182 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 29 23:58:13.846261 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 29 23:58:13.846627 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 29 23:58:13.848176 systemd[1]: kubelet.service: Consumed 1.343s CPU time. Apr 29 23:58:16.248006 systemd-resolved[1853]: Clock change detected. Flushing caches. Apr 29 23:58:22.822485 systemd[1]: Started sshd@3-172.31.29.161:22-139.178.89.65:47796.service - OpenSSH per-connection server daemon (139.178.89.65:47796). Apr 29 23:58:23.097714 sshd[2200]: Accepted publickey for core from 139.178.89.65 port 47796 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:23.100144 sshd-session[2200]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:23.108197 systemd-logind[1915]: New session 4 of user core. Apr 29 23:58:23.120262 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 29 23:58:23.299388 sshd[2202]: Connection closed by 139.178.89.65 port 47796 Apr 29 23:58:23.299264 sshd-session[2200]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:23.304926 systemd[1]: sshd@3-172.31.29.161:22-139.178.89.65:47796.service: Deactivated successfully. Apr 29 23:58:23.308674 systemd[1]: session-4.scope: Deactivated successfully. Apr 29 23:58:23.311197 systemd-logind[1915]: Session 4 logged out. Waiting for processes to exit. Apr 29 23:58:23.313522 systemd-logind[1915]: Removed session 4. Apr 29 23:58:23.365413 systemd[1]: Started sshd@4-172.31.29.161:22-139.178.89.65:47810.service - OpenSSH per-connection server daemon (139.178.89.65:47810). Apr 29 23:58:23.637701 sshd[2207]: Accepted publickey for core from 139.178.89.65 port 47810 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:23.640535 sshd-session[2207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:23.650104 systemd-logind[1915]: New session 5 of user core. Apr 29 23:58:23.660368 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 29 23:58:23.815618 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Apr 29 23:58:23.824372 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:58:23.833012 sshd[2209]: Connection closed by 139.178.89.65 port 47810 Apr 29 23:58:23.831591 sshd-session[2207]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:23.839916 systemd[1]: sshd@4-172.31.29.161:22-139.178.89.65:47810.service: Deactivated successfully. Apr 29 23:58:23.847570 systemd[1]: session-5.scope: Deactivated successfully. Apr 29 23:58:23.851509 systemd-logind[1915]: Session 5 logged out. Waiting for processes to exit. Apr 29 23:58:23.853514 systemd-logind[1915]: Removed session 5. Apr 29 23:58:23.882300 systemd[1]: Started sshd@5-172.31.29.161:22-139.178.89.65:47826.service - OpenSSH per-connection server daemon (139.178.89.65:47826). Apr 29 23:58:24.169259 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:24.169592 (kubelet)[2224]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 29 23:58:24.177028 sshd[2217]: Accepted publickey for core from 139.178.89.65 port 47826 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:24.181519 sshd-session[2217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:24.190228 systemd-logind[1915]: New session 6 of user core. Apr 29 23:58:24.198297 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 29 23:58:24.270897 kubelet[2224]: E0429 23:58:24.270777 2224 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 29 23:58:24.278619 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 29 23:58:24.279119 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 29 23:58:24.374591 sshd[2229]: Connection closed by 139.178.89.65 port 47826 Apr 29 23:58:24.375411 sshd-session[2217]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:24.381777 systemd[1]: sshd@5-172.31.29.161:22-139.178.89.65:47826.service: Deactivated successfully. Apr 29 23:58:24.384820 systemd[1]: session-6.scope: Deactivated successfully. Apr 29 23:58:24.387284 systemd-logind[1915]: Session 6 logged out. Waiting for processes to exit. Apr 29 23:58:24.389364 systemd-logind[1915]: Removed session 6. Apr 29 23:58:24.427529 systemd[1]: Started sshd@6-172.31.29.161:22-139.178.89.65:47836.service - OpenSSH per-connection server daemon (139.178.89.65:47836). Apr 29 23:58:24.712828 sshd[2236]: Accepted publickey for core from 139.178.89.65 port 47836 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:24.715403 sshd-session[2236]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:24.723188 systemd-logind[1915]: New session 7 of user core. Apr 29 23:58:24.732257 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 29 23:58:24.884775 sudo[2239]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 29 23:58:24.885459 sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 29 23:58:25.887255 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:25.895537 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:58:25.946623 systemd[1]: Reloading requested from client PID 2277 ('systemctl') (unit session-7.scope)... Apr 29 23:58:25.946857 systemd[1]: Reloading... Apr 29 23:58:26.223077 zram_generator::config[2326]: No configuration found. Apr 29 23:58:26.431328 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 29 23:58:26.598286 systemd[1]: Reloading finished in 650 ms. Apr 29 23:58:26.702354 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Apr 29 23:58:26.702577 systemd[1]: kubelet.service: Failed with result 'signal'. Apr 29 23:58:26.703307 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:26.711907 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:58:26.995495 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:27.011527 (kubelet)[2381]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 29 23:58:27.092018 kubelet[2381]: 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 29 23:58:27.092018 kubelet[2381]: 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 29 23:58:27.092018 kubelet[2381]: 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 29 23:58:27.092018 kubelet[2381]: I0429 23:58:27.091392 2381 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 29 23:58:27.718838 kubelet[2381]: I0429 23:58:27.718758 2381 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Apr 29 23:58:27.718838 kubelet[2381]: I0429 23:58:27.718815 2381 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 29 23:58:27.719259 kubelet[2381]: I0429 23:58:27.719220 2381 server.go:927] "Client rotation is on, will bootstrap in background" Apr 29 23:58:27.744929 kubelet[2381]: I0429 23:58:27.744667 2381 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 29 23:58:27.764381 kubelet[2381]: I0429 23:58:27.764335 2381 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 29 23:58:27.765891 kubelet[2381]: I0429 23:58:27.765022 2381 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 29 23:58:27.765891 kubelet[2381]: I0429 23:58:27.765074 2381 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.29.161","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Apr 29 23:58:27.765891 kubelet[2381]: I0429 23:58:27.765370 2381 topology_manager.go:138] "Creating topology manager with none policy" Apr 29 23:58:27.765891 kubelet[2381]: I0429 23:58:27.765388 2381 container_manager_linux.go:301] "Creating device plugin manager" Apr 29 23:58:27.765891 kubelet[2381]: I0429 23:58:27.765633 2381 state_mem.go:36] "Initialized new in-memory state store" Apr 29 23:58:27.767136 kubelet[2381]: I0429 23:58:27.767108 2381 kubelet.go:400] "Attempting to sync node with API server" Apr 29 23:58:27.767294 kubelet[2381]: I0429 23:58:27.767271 2381 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 29 23:58:27.767506 kubelet[2381]: I0429 23:58:27.767485 2381 kubelet.go:312] "Adding apiserver pod source" Apr 29 23:58:27.767755 kubelet[2381]: I0429 23:58:27.767653 2381 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 29 23:58:27.768480 kubelet[2381]: E0429 23:58:27.767680 2381 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:27.768480 kubelet[2381]: E0429 23:58:27.767792 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:27.769328 kubelet[2381]: I0429 23:58:27.769249 2381 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Apr 29 23:58:27.770985 kubelet[2381]: I0429 23:58:27.770098 2381 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Apr 29 23:58:27.770985 kubelet[2381]: W0429 23:58:27.770178 2381 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 29 23:58:27.771567 kubelet[2381]: I0429 23:58:27.771541 2381 server.go:1264] "Started kubelet" Apr 29 23:58:27.774233 kubelet[2381]: I0429 23:58:27.774188 2381 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 29 23:58:27.781622 kubelet[2381]: I0429 23:58:27.781557 2381 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Apr 29 23:58:27.783048 kubelet[2381]: I0429 23:58:27.783018 2381 volume_manager.go:291] "Starting Kubelet Volume Manager" Apr 29 23:58:27.783693 kubelet[2381]: I0429 23:58:27.783651 2381 server.go:455] "Adding debug handlers to kubelet server" Apr 29 23:58:27.784493 kubelet[2381]: I0429 23:58:27.784461 2381 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Apr 29 23:58:27.785340 kubelet[2381]: I0429 23:58:27.785268 2381 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 29 23:58:27.788067 kubelet[2381]: I0429 23:58:27.785572 2381 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 29 23:58:27.788067 kubelet[2381]: I0429 23:58:27.786574 2381 reconciler.go:26] "Reconciler: start to sync state" Apr 29 23:58:27.790748 kubelet[2381]: W0429 23:58:27.790696 2381 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "172.31.29.161" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 29 23:58:27.790748 kubelet[2381]: E0429 23:58:27.790752 2381 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.29.161" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 29 23:58:27.791022 kubelet[2381]: E0429 23:58:27.790806 2381 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.29.161.183aef78d2d0d491 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.29.161,UID:172.31.29.161,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.29.161,},FirstTimestamp:2025-04-29 23:58:27.771503761 +0000 UTC m=+0.753350525,LastTimestamp:2025-04-29 23:58:27.771503761 +0000 UTC m=+0.753350525,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.29.161,}" Apr 29 23:58:27.791291 kubelet[2381]: W0429 23:58:27.791115 2381 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 29 23:58:27.791291 kubelet[2381]: E0429 23:58:27.791150 2381 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 29 23:58:27.791615 kubelet[2381]: I0429 23:58:27.791507 2381 factory.go:221] Registration of the systemd container factory successfully Apr 29 23:58:27.791800 kubelet[2381]: I0429 23:58:27.791646 2381 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 29 23:58:27.792502 kubelet[2381]: W0429 23:58:27.792456 2381 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 29 23:58:27.792757 kubelet[2381]: E0429 23:58:27.792506 2381 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 29 23:58:27.792757 kubelet[2381]: E0429 23:58:27.792640 2381 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.29.161\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Apr 29 23:58:27.794550 kubelet[2381]: E0429 23:58:27.794501 2381 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 29 23:58:27.796945 kubelet[2381]: I0429 23:58:27.796894 2381 factory.go:221] Registration of the containerd container factory successfully Apr 29 23:58:27.823599 kubelet[2381]: I0429 23:58:27.823553 2381 cpu_manager.go:214] "Starting CPU manager" policy="none" Apr 29 23:58:27.823807 kubelet[2381]: I0429 23:58:27.823784 2381 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Apr 29 23:58:27.823937 kubelet[2381]: I0429 23:58:27.823918 2381 state_mem.go:36] "Initialized new in-memory state store" Apr 29 23:58:27.828743 kubelet[2381]: I0429 23:58:27.828302 2381 policy_none.go:49] "None policy: Start" Apr 29 23:58:27.830080 kubelet[2381]: I0429 23:58:27.829820 2381 memory_manager.go:170] "Starting memorymanager" policy="None" Apr 29 23:58:27.830080 kubelet[2381]: I0429 23:58:27.829860 2381 state_mem.go:35] "Initializing new in-memory state store" Apr 29 23:58:27.847952 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 29 23:58:27.867933 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 29 23:58:27.876937 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 29 23:58:27.886017 kubelet[2381]: I0429 23:58:27.885771 2381 kubelet_node_status.go:73] "Attempting to register node" node="172.31.29.161" Apr 29 23:58:27.892713 kubelet[2381]: I0429 23:58:27.892649 2381 kubelet_node_status.go:76] "Successfully registered node" node="172.31.29.161" Apr 29 23:58:27.894624 kubelet[2381]: I0429 23:58:27.894584 2381 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 29 23:58:27.897037 kubelet[2381]: I0429 23:58:27.895620 2381 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 29 23:58:27.897037 kubelet[2381]: I0429 23:58:27.896557 2381 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 29 23:58:27.905881 kubelet[2381]: I0429 23:58:27.905816 2381 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Apr 29 23:58:27.915863 kubelet[2381]: I0429 23:58:27.915825 2381 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Apr 29 23:58:27.916233 kubelet[2381]: I0429 23:58:27.916205 2381 status_manager.go:217] "Starting to sync pod status with apiserver" Apr 29 23:58:27.916413 kubelet[2381]: I0429 23:58:27.916390 2381 kubelet.go:2337] "Starting kubelet main sync loop" Apr 29 23:58:27.916706 kubelet[2381]: E0429 23:58:27.916670 2381 kubelet.go:2361] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Apr 29 23:58:27.917276 kubelet[2381]: E0429 23:58:27.917243 2381 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.29.161\" not found" Apr 29 23:58:27.940195 kubelet[2381]: E0429 23:58:27.940156 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.030758 sudo[2239]: pam_unix(sudo:session): session closed for user root Apr 29 23:58:28.041136 kubelet[2381]: E0429 23:58:28.041039 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.068463 sshd[2238]: Connection closed by 139.178.89.65 port 47836 Apr 29 23:58:28.069531 sshd-session[2236]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:28.076333 systemd[1]: sshd@6-172.31.29.161:22-139.178.89.65:47836.service: Deactivated successfully. Apr 29 23:58:28.080559 systemd[1]: session-7.scope: Deactivated successfully. Apr 29 23:58:28.085612 systemd-logind[1915]: Session 7 logged out. Waiting for processes to exit. Apr 29 23:58:28.088332 systemd-logind[1915]: Removed session 7. Apr 29 23:58:28.142218 kubelet[2381]: E0429 23:58:28.142140 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.242479 kubelet[2381]: E0429 23:58:28.242409 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.343491 kubelet[2381]: E0429 23:58:28.343446 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.444221 kubelet[2381]: E0429 23:58:28.444168 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.544834 kubelet[2381]: E0429 23:58:28.544787 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.645625 kubelet[2381]: E0429 23:58:28.645511 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.722140 kubelet[2381]: I0429 23:58:28.722086 2381 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 29 23:58:28.722342 kubelet[2381]: W0429 23:58:28.722300 2381 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 29 23:58:28.746525 kubelet[2381]: E0429 23:58:28.746484 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.768651 kubelet[2381]: E0429 23:58:28.768606 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:28.847484 kubelet[2381]: E0429 23:58:28.847415 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:28.948639 kubelet[2381]: E0429 23:58:28.948493 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:29.049506 kubelet[2381]: E0429 23:58:29.049443 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:29.150402 kubelet[2381]: E0429 23:58:29.150340 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:29.251484 kubelet[2381]: E0429 23:58:29.251308 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:29.352244 kubelet[2381]: E0429 23:58:29.352186 2381 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.29.161\" not found" Apr 29 23:58:29.453576 kubelet[2381]: I0429 23:58:29.453467 2381 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 29 23:58:29.454213 containerd[1937]: time="2025-04-29T23:58:29.453993373Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 29 23:58:29.455385 kubelet[2381]: I0429 23:58:29.455054 2381 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 29 23:58:29.769613 kubelet[2381]: E0429 23:58:29.769523 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:29.769613 kubelet[2381]: I0429 23:58:29.769539 2381 apiserver.go:52] "Watching apiserver" Apr 29 23:58:29.775490 kubelet[2381]: I0429 23:58:29.775416 2381 topology_manager.go:215] "Topology Admit Handler" podUID="6aa83032-7d25-4f67-8dc0-0b0441d2fc61" podNamespace="kube-flannel" podName="kube-flannel-ds-48gfs" Apr 29 23:58:29.776631 kubelet[2381]: I0429 23:58:29.775618 2381 topology_manager.go:215] "Topology Admit Handler" podUID="a8a07b34-d6db-4258-a957-6aa2cb872824" podNamespace="kube-system" podName="kube-proxy-d8s6x" Apr 29 23:58:29.785285 kubelet[2381]: I0429 23:58:29.785214 2381 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Apr 29 23:58:29.793108 systemd[1]: Created slice kubepods-besteffort-poda8a07b34_d6db_4258_a957_6aa2cb872824.slice - libcontainer container kubepods-besteffort-poda8a07b34_d6db_4258_a957_6aa2cb872824.slice. Apr 29 23:58:29.801897 kubelet[2381]: I0429 23:58:29.801341 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-xtables-lock\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.801897 kubelet[2381]: I0429 23:58:29.801405 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/a8a07b34-d6db-4258-a957-6aa2cb872824-kube-proxy\") pod \"kube-proxy-d8s6x\" (UID: \"a8a07b34-d6db-4258-a957-6aa2cb872824\") " pod="kube-system/kube-proxy-d8s6x" Apr 29 23:58:29.801897 kubelet[2381]: I0429 23:58:29.801444 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-flannel-cfg\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.801897 kubelet[2381]: I0429 23:58:29.801482 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t94t8\" (UniqueName: \"kubernetes.io/projected/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-kube-api-access-t94t8\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.801897 kubelet[2381]: I0429 23:58:29.801519 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a8a07b34-d6db-4258-a957-6aa2cb872824-xtables-lock\") pod \"kube-proxy-d8s6x\" (UID: \"a8a07b34-d6db-4258-a957-6aa2cb872824\") " pod="kube-system/kube-proxy-d8s6x" Apr 29 23:58:29.802283 kubelet[2381]: I0429 23:58:29.801556 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/a8a07b34-d6db-4258-a957-6aa2cb872824-lib-modules\") pod \"kube-proxy-d8s6x\" (UID: \"a8a07b34-d6db-4258-a957-6aa2cb872824\") " pod="kube-system/kube-proxy-d8s6x" Apr 29 23:58:29.802283 kubelet[2381]: I0429 23:58:29.801592 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g4xkm\" (UniqueName: \"kubernetes.io/projected/a8a07b34-d6db-4258-a957-6aa2cb872824-kube-api-access-g4xkm\") pod \"kube-proxy-d8s6x\" (UID: \"a8a07b34-d6db-4258-a957-6aa2cb872824\") " pod="kube-system/kube-proxy-d8s6x" Apr 29 23:58:29.802283 kubelet[2381]: I0429 23:58:29.801626 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-run\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.802283 kubelet[2381]: I0429 23:58:29.801659 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-cni-plugin\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.802283 kubelet[2381]: I0429 23:58:29.801697 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/6aa83032-7d25-4f67-8dc0-0b0441d2fc61-cni\") pod \"kube-flannel-ds-48gfs\" (UID: \"6aa83032-7d25-4f67-8dc0-0b0441d2fc61\") " pod="kube-flannel/kube-flannel-ds-48gfs" Apr 29 23:58:29.809987 systemd[1]: Created slice kubepods-burstable-pod6aa83032_7d25_4f67_8dc0_0b0441d2fc61.slice - libcontainer container kubepods-burstable-pod6aa83032_7d25_4f67_8dc0_0b0441d2fc61.slice. Apr 29 23:58:30.106754 containerd[1937]: time="2025-04-29T23:58:30.106191000Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d8s6x,Uid:a8a07b34-d6db-4258-a957-6aa2cb872824,Namespace:kube-system,Attempt:0,}" Apr 29 23:58:30.120400 containerd[1937]: time="2025-04-29T23:58:30.120346908Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-48gfs,Uid:6aa83032-7d25-4f67-8dc0-0b0441d2fc61,Namespace:kube-flannel,Attempt:0,}" Apr 29 23:58:30.682091 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2650802670.mount: Deactivated successfully. Apr 29 23:58:30.697199 containerd[1937]: time="2025-04-29T23:58:30.697119327Z" 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 29 23:58:30.701550 containerd[1937]: time="2025-04-29T23:58:30.701453967Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Apr 29 23:58:30.702702 containerd[1937]: time="2025-04-29T23:58:30.702644631Z" 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 29 23:58:30.704336 containerd[1937]: time="2025-04-29T23:58:30.704279535Z" 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 29 23:58:30.705453 containerd[1937]: time="2025-04-29T23:58:30.705381159Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 29 23:58:30.712503 containerd[1937]: time="2025-04-29T23:58:30.712420647Z" 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 29 23:58:30.714896 containerd[1937]: time="2025-04-29T23:58:30.714217947Z" 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 593.605455ms" Apr 29 23:58:30.716458 containerd[1937]: time="2025-04-29T23:58:30.716393127Z" 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 610.090239ms" Apr 29 23:58:30.771263 kubelet[2381]: E0429 23:58:30.770484 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:30.985292 containerd[1937]: time="2025-04-29T23:58:30.984591221Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:58:30.985292 containerd[1937]: time="2025-04-29T23:58:30.984787733Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:58:30.985292 containerd[1937]: time="2025-04-29T23:58:30.984830561Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:30.986955 containerd[1937]: time="2025-04-29T23:58:30.986677541Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:30.993325 containerd[1937]: time="2025-04-29T23:58:30.992042093Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:58:30.993325 containerd[1937]: time="2025-04-29T23:58:30.992173709Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:58:30.993325 containerd[1937]: time="2025-04-29T23:58:30.992210801Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:30.993325 containerd[1937]: time="2025-04-29T23:58:30.992350661Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:31.097773 systemd[1]: run-containerd-runc-k8s.io-e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49-runc.NFEEyU.mount: Deactivated successfully. Apr 29 23:58:31.115289 systemd[1]: Started cri-containerd-4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e.scope - libcontainer container 4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e. Apr 29 23:58:31.119743 systemd[1]: Started cri-containerd-e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49.scope - libcontainer container e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49. Apr 29 23:58:31.186675 containerd[1937]: time="2025-04-29T23:58:31.186610166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d8s6x,Uid:a8a07b34-d6db-4258-a957-6aa2cb872824,Namespace:kube-system,Attempt:0,} returns sandbox id \"e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49\"" Apr 29 23:58:31.191992 containerd[1937]: time="2025-04-29T23:58:31.191790458Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\"" Apr 29 23:58:31.208823 containerd[1937]: time="2025-04-29T23:58:31.208632554Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-48gfs,Uid:6aa83032-7d25-4f67-8dc0-0b0441d2fc61,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\"" Apr 29 23:58:31.771758 kubelet[2381]: E0429 23:58:31.771442 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:32.482587 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3509972003.mount: Deactivated successfully. Apr 29 23:58:32.773430 kubelet[2381]: E0429 23:58:32.773301 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:32.965677 containerd[1937]: time="2025-04-29T23:58:32.965619295Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:32.966991 containerd[1937]: time="2025-04-29T23:58:32.966854923Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.12: active requests=0, bytes read=25775705" Apr 29 23:58:32.970019 containerd[1937]: time="2025-04-29T23:58:32.969334015Z" level=info msg="ImageCreate event name:\"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:32.974112 containerd[1937]: time="2025-04-29T23:58:32.974062699Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:32.976241 containerd[1937]: time="2025-04-29T23:58:32.976177519Z" 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.784325381s" Apr 29 23:58:32.976241 containerd[1937]: time="2025-04-29T23:58:32.976234351Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\" returns image reference \"sha256:b4250a9efcae16f8d20358e204a159844e2b7e854edad08aee8791774acbdaed\"" Apr 29 23:58:32.980061 containerd[1937]: time="2025-04-29T23:58:32.979286971Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 29 23:58:32.985705 containerd[1937]: time="2025-04-29T23:58:32.985640455Z" level=info msg="CreateContainer within sandbox \"e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 29 23:58:33.018812 containerd[1937]: time="2025-04-29T23:58:33.018740211Z" level=info msg="CreateContainer within sandbox \"e483ff1adb9b847a78931b588f70fcf757450cd686ae48e5542101fd63d0fb49\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"137329f1769363291054a192140635515819086f97ddf78ad37fd168f22d08db\"" Apr 29 23:58:33.020521 containerd[1937]: time="2025-04-29T23:58:33.020438451Z" level=info msg="StartContainer for \"137329f1769363291054a192140635515819086f97ddf78ad37fd168f22d08db\"" Apr 29 23:58:33.073281 systemd[1]: Started cri-containerd-137329f1769363291054a192140635515819086f97ddf78ad37fd168f22d08db.scope - libcontainer container 137329f1769363291054a192140635515819086f97ddf78ad37fd168f22d08db. Apr 29 23:58:33.135909 containerd[1937]: time="2025-04-29T23:58:33.135668115Z" level=info msg="StartContainer for \"137329f1769363291054a192140635515819086f97ddf78ad37fd168f22d08db\" returns successfully" Apr 29 23:58:33.774333 kubelet[2381]: E0429 23:58:33.774259 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:33.966143 kubelet[2381]: I0429 23:58:33.966040 2381 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-d8s6x" podStartSLOduration=5.178044783 podStartE2EDuration="6.966011708s" podCreationTimestamp="2025-04-29 23:58:27 +0000 UTC" firstStartedPulling="2025-04-29 23:58:31.190411118 +0000 UTC m=+4.172257870" lastFinishedPulling="2025-04-29 23:58:32.978378043 +0000 UTC m=+5.960224795" observedRunningTime="2025-04-29 23:58:33.9651581 +0000 UTC m=+6.947004876" watchObservedRunningTime="2025-04-29 23:58:33.966011708 +0000 UTC m=+6.947858472" Apr 29 23:58:34.711319 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2877434297.mount: Deactivated successfully. Apr 29 23:58:34.767373 containerd[1937]: time="2025-04-29T23:58:34.767310271Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:34.769153 containerd[1937]: time="2025-04-29T23:58:34.769052887Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673531" Apr 29 23:58:34.770656 containerd[1937]: time="2025-04-29T23:58:34.770584472Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:34.774503 kubelet[2381]: E0429 23:58:34.774431 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:34.776485 containerd[1937]: time="2025-04-29T23:58:34.776389100Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:34.778740 containerd[1937]: time="2025-04-29T23:58:34.778021340Z" 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.798655157s" Apr 29 23:58:34.778740 containerd[1937]: time="2025-04-29T23:58:34.778099820Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Apr 29 23:58:34.782224 containerd[1937]: time="2025-04-29T23:58:34.781932212Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 29 23:58:34.800795 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2690358122.mount: Deactivated successfully. Apr 29 23:58:34.814706 containerd[1937]: time="2025-04-29T23:58:34.814558496Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761\"" Apr 29 23:58:34.815631 containerd[1937]: time="2025-04-29T23:58:34.815578736Z" level=info msg="StartContainer for \"4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761\"" Apr 29 23:58:34.867272 systemd[1]: Started cri-containerd-4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761.scope - libcontainer container 4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761. Apr 29 23:58:34.914144 containerd[1937]: time="2025-04-29T23:58:34.914024168Z" level=info msg="StartContainer for \"4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761\" returns successfully" Apr 29 23:58:34.917195 systemd[1]: cri-containerd-4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761.scope: Deactivated successfully. Apr 29 23:58:35.126578 containerd[1937]: time="2025-04-29T23:58:35.126482849Z" level=info msg="shim disconnected" id=4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761 namespace=k8s.io Apr 29 23:58:35.126578 containerd[1937]: time="2025-04-29T23:58:35.126560165Z" level=warning msg="cleaning up after shim disconnected" id=4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761 namespace=k8s.io Apr 29 23:58:35.126578 containerd[1937]: time="2025-04-29T23:58:35.126580769Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 29 23:58:35.581893 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-4200549bc8d0566fd175bd804941b96a13db84901793bc19291a545915455761-rootfs.mount: Deactivated successfully. Apr 29 23:58:35.774606 kubelet[2381]: E0429 23:58:35.774549 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:35.967788 containerd[1937]: time="2025-04-29T23:58:35.967543221Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 29 23:58:36.775068 kubelet[2381]: E0429 23:58:36.775013 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:37.776018 kubelet[2381]: E0429 23:58:37.775187 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:37.791907 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3078539596.mount: Deactivated successfully. Apr 29 23:58:38.776110 kubelet[2381]: E0429 23:58:38.776024 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:38.855240 containerd[1937]: time="2025-04-29T23:58:38.854923404Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:38.862009 containerd[1937]: time="2025-04-29T23:58:38.860097636Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:38.862009 containerd[1937]: time="2025-04-29T23:58:38.860219508Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Apr 29 23:58:38.870786 containerd[1937]: time="2025-04-29T23:58:38.870696480Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:38.873450 containerd[1937]: time="2025-04-29T23:58:38.873384732Z" 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.905749507s" Apr 29 23:58:38.873664 containerd[1937]: time="2025-04-29T23:58:38.873632136Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Apr 29 23:58:38.877842 containerd[1937]: time="2025-04-29T23:58:38.877777056Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 29 23:58:38.898782 containerd[1937]: time="2025-04-29T23:58:38.898711140Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102\"" Apr 29 23:58:38.900238 containerd[1937]: time="2025-04-29T23:58:38.900086172Z" level=info msg="StartContainer for \"182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102\"" Apr 29 23:58:38.900416 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3571009511.mount: Deactivated successfully. Apr 29 23:58:38.954267 systemd[1]: Started cri-containerd-182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102.scope - libcontainer container 182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102. Apr 29 23:58:39.002172 systemd[1]: cri-containerd-182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102.scope: Deactivated successfully. Apr 29 23:58:39.003698 containerd[1937]: time="2025-04-29T23:58:39.003053889Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/kubepods.slice/kubepods-burstable.slice/kubepods-burstable-pod6aa83032_7d25_4f67_8dc0_0b0441d2fc61.slice/cri-containerd-182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102.scope/memory.events\": no such file or directory" Apr 29 23:58:39.007423 containerd[1937]: time="2025-04-29T23:58:39.007258965Z" level=info msg="StartContainer for \"182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102\" returns successfully" Apr 29 23:58:39.047632 kubelet[2381]: I0429 23:58:39.047503 2381 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Apr 29 23:58:39.147825 containerd[1937]: time="2025-04-29T23:58:39.147662973Z" level=info msg="shim disconnected" id=182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102 namespace=k8s.io Apr 29 23:58:39.147825 containerd[1937]: time="2025-04-29T23:58:39.147749025Z" level=warning msg="cleaning up after shim disconnected" id=182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102 namespace=k8s.io Apr 29 23:58:39.147825 containerd[1937]: time="2025-04-29T23:58:39.147769845Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 29 23:58:39.776223 kubelet[2381]: E0429 23:58:39.776166 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:39.889782 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-182ba00f226e3656aa45d4ea1add6bcca8910bcdd146b2d8baa7f0d0ceff1102-rootfs.mount: Deactivated successfully. Apr 29 23:58:39.958206 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 29 23:58:39.989372 containerd[1937]: time="2025-04-29T23:58:39.989273185Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 29 23:58:40.013627 containerd[1937]: time="2025-04-29T23:58:40.013546498Z" level=info msg="CreateContainer within sandbox \"4e1be416f1b6076848d923811c36b0d1a93d5585df3a18e001db2404d5f3453e\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a\"" Apr 29 23:58:40.014756 containerd[1937]: time="2025-04-29T23:58:40.014679778Z" level=info msg="StartContainer for \"12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a\"" Apr 29 23:58:40.071289 systemd[1]: Started cri-containerd-12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a.scope - libcontainer container 12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a. Apr 29 23:58:40.115564 containerd[1937]: time="2025-04-29T23:58:40.115233766Z" level=info msg="StartContainer for \"12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a\" returns successfully" Apr 29 23:58:40.765711 kubelet[2381]: I0429 23:58:40.765661 2381 topology_manager.go:215] "Topology Admit Handler" podUID="b0ddf3df-619b-4ad4-a55d-aefdf0748733" podNamespace="default" podName="nginx-deployment-85f456d6dd-cmz4w" Apr 29 23:58:40.777467 kubelet[2381]: E0429 23:58:40.776353 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:40.777185 systemd[1]: Created slice kubepods-besteffort-podb0ddf3df_619b_4ad4_a55d_aefdf0748733.slice - libcontainer container kubepods-besteffort-podb0ddf3df_619b_4ad4_a55d_aefdf0748733.slice. Apr 29 23:58:40.789662 kubelet[2381]: W0429 23:58:40.789608 2381 reflector.go:547] object-"default"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:172.31.29.161" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '172.31.29.161' and this object Apr 29 23:58:40.789662 kubelet[2381]: E0429 23:58:40.789662 2381 reflector.go:150] object-"default"/"kube-root-ca.crt": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:172.31.29.161" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '172.31.29.161' and this object Apr 29 23:58:40.889801 systemd[1]: run-containerd-runc-k8s.io-12e4d2f9df50d6262fa83a3837530236e1383aa5872b69dd9aeeb1f4f281b20a-runc.fiu9wN.mount: Deactivated successfully. Apr 29 23:58:40.965592 kubelet[2381]: I0429 23:58:40.965530 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5bdhq\" (UniqueName: \"kubernetes.io/projected/b0ddf3df-619b-4ad4-a55d-aefdf0748733-kube-api-access-5bdhq\") pod \"nginx-deployment-85f456d6dd-cmz4w\" (UID: \"b0ddf3df-619b-4ad4-a55d-aefdf0748733\") " pod="default/nginx-deployment-85f456d6dd-cmz4w" Apr 29 23:58:41.186199 (udev-worker)[2874]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:41.203633 systemd-networkd[1850]: flannel.1: Link UP Apr 29 23:58:41.204240 systemd-networkd[1850]: flannel.1: Gained carrier Apr 29 23:58:41.777172 kubelet[2381]: E0429 23:58:41.777111 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:41.982863 containerd[1937]: time="2025-04-29T23:58:41.982334799Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-cmz4w,Uid:b0ddf3df-619b-4ad4-a55d-aefdf0748733,Namespace:default,Attempt:0,}" Apr 29 23:58:42.019671 systemd-networkd[1850]: cni0: Link UP Apr 29 23:58:42.035344 systemd-networkd[1850]: veth298f8424: Link UP Apr 29 23:58:42.038711 kernel: cni0: port 1(veth298f8424) entered blocking state Apr 29 23:58:42.038809 kernel: cni0: port 1(veth298f8424) entered disabled state Apr 29 23:58:42.039908 kernel: veth298f8424: entered allmulticast mode Apr 29 23:58:42.041169 kernel: veth298f8424: entered promiscuous mode Apr 29 23:58:42.043703 kernel: cni0: port 1(veth298f8424) entered blocking state Apr 29 23:58:42.043763 kernel: cni0: port 1(veth298f8424) entered forwarding state Apr 29 23:58:42.045713 (udev-worker)[2873]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:42.046325 kernel: cni0: port 1(veth298f8424) entered disabled state Apr 29 23:58:42.058679 kernel: cni0: port 1(veth298f8424) entered blocking state Apr 29 23:58:42.058832 kernel: cni0: port 1(veth298f8424) entered forwarding state Apr 29 23:58:42.059382 systemd-networkd[1850]: veth298f8424: Gained carrier Apr 29 23:58:42.060394 systemd-networkd[1850]: cni0: Gained carrier Apr 29 23:58:42.065612 containerd[1937]: 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)(0x4000012938), "name":"cbr0", "type":"bridge"} Apr 29 23:58:42.065612 containerd[1937]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:58:42.104368 containerd[1937]: {"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-29T23:58:42.103909548Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:58:42.104368 containerd[1937]: time="2025-04-29T23:58:42.104087928Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:58:42.104368 containerd[1937]: time="2025-04-29T23:58:42.104127204Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:42.104868 containerd[1937]: time="2025-04-29T23:58:42.104481444Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:42.141549 systemd[1]: run-containerd-runc-k8s.io-52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25-runc.oXxD41.mount: Deactivated successfully. Apr 29 23:58:42.151297 systemd[1]: Started cri-containerd-52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25.scope - libcontainer container 52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25. Apr 29 23:58:42.214185 containerd[1937]: time="2025-04-29T23:58:42.214106328Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-cmz4w,Uid:b0ddf3df-619b-4ad4-a55d-aefdf0748733,Namespace:default,Attempt:0,} returns sandbox id \"52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25\"" Apr 29 23:58:42.217136 containerd[1937]: time="2025-04-29T23:58:42.217079172Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 29 23:58:42.778130 kubelet[2381]: E0429 23:58:42.778069 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:42.821187 systemd-networkd[1850]: flannel.1: Gained IPv6LL Apr 29 23:58:43.271539 systemd-networkd[1850]: veth298f8424: Gained IPv6LL Apr 29 23:58:43.717279 systemd-networkd[1850]: cni0: Gained IPv6LL Apr 29 23:58:43.779209 kubelet[2381]: E0429 23:58:43.779119 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:44.780014 kubelet[2381]: E0429 23:58:44.779443 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:45.186397 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount616562151.mount: Deactivated successfully. Apr 29 23:58:45.779644 kubelet[2381]: E0429 23:58:45.779601 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:46.247760 ntpd[1911]: Listen normally on 7 flannel.1 192.168.1.0:123 Apr 29 23:58:46.248530 ntpd[1911]: 29 Apr 23:58:46 ntpd[1911]: Listen normally on 7 flannel.1 192.168.1.0:123 Apr 29 23:58:46.248530 ntpd[1911]: 29 Apr 23:58:46 ntpd[1911]: Listen normally on 8 cni0 192.168.1.1:123 Apr 29 23:58:46.248530 ntpd[1911]: 29 Apr 23:58:46 ntpd[1911]: Listen normally on 9 flannel.1 [fe80::d866:f1ff:fe5d:287f%3]:123 Apr 29 23:58:46.248530 ntpd[1911]: 29 Apr 23:58:46 ntpd[1911]: Listen normally on 10 cni0 [fe80::a43c:65ff:fe9a:8817%4]:123 Apr 29 23:58:46.248530 ntpd[1911]: 29 Apr 23:58:46 ntpd[1911]: Listen normally on 11 veth298f8424 [fe80::b0cd:55ff:fe7b:6f09%5]:123 Apr 29 23:58:46.247855 ntpd[1911]: Listen normally on 8 cni0 192.168.1.1:123 Apr 29 23:58:46.247982 ntpd[1911]: Listen normally on 9 flannel.1 [fe80::d866:f1ff:fe5d:287f%3]:123 Apr 29 23:58:46.248087 ntpd[1911]: Listen normally on 10 cni0 [fe80::a43c:65ff:fe9a:8817%4]:123 Apr 29 23:58:46.248156 ntpd[1911]: Listen normally on 11 veth298f8424 [fe80::b0cd:55ff:fe7b:6f09%5]:123 Apr 29 23:58:46.602305 containerd[1937]: time="2025-04-29T23:58:46.601894074Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:46.603899 containerd[1937]: time="2025-04-29T23:58:46.603834282Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948638" Apr 29 23:58:46.604815 containerd[1937]: time="2025-04-29T23:58:46.604323630Z" level=info msg="ImageCreate event name:\"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:46.610784 containerd[1937]: time="2025-04-29T23:58:46.610696650Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:46.616996 containerd[1937]: time="2025-04-29T23:58:46.615908742Z" 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.398769294s" Apr 29 23:58:46.616996 containerd[1937]: time="2025-04-29T23:58:46.616041726Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 29 23:58:46.623481 containerd[1937]: time="2025-04-29T23:58:46.623429694Z" level=info msg="CreateContainer within sandbox \"52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 29 23:58:46.644108 containerd[1937]: time="2025-04-29T23:58:46.644038722Z" level=info msg="CreateContainer within sandbox \"52f54ef9bb4e54468c0d0d4a197a4bcb7f43cba49c89dd1b35c30a81a15d5b25\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"54b0a1d51a929e82af9b45d96d5d36c96c4ada49c83a7c484f77e7dcdcec444d\"" Apr 29 23:58:46.645103 containerd[1937]: time="2025-04-29T23:58:46.645060954Z" level=info msg="StartContainer for \"54b0a1d51a929e82af9b45d96d5d36c96c4ada49c83a7c484f77e7dcdcec444d\"" Apr 29 23:58:46.701280 systemd[1]: Started cri-containerd-54b0a1d51a929e82af9b45d96d5d36c96c4ada49c83a7c484f77e7dcdcec444d.scope - libcontainer container 54b0a1d51a929e82af9b45d96d5d36c96c4ada49c83a7c484f77e7dcdcec444d. Apr 29 23:58:46.750820 containerd[1937]: time="2025-04-29T23:58:46.750487411Z" level=info msg="StartContainer for \"54b0a1d51a929e82af9b45d96d5d36c96c4ada49c83a7c484f77e7dcdcec444d\" returns successfully" Apr 29 23:58:46.780826 kubelet[2381]: E0429 23:58:46.780748 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:47.029372 kubelet[2381]: I0429 23:58:47.029183 2381 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-48gfs" podStartSLOduration=12.365275582 podStartE2EDuration="20.029159008s" podCreationTimestamp="2025-04-29 23:58:27 +0000 UTC" firstStartedPulling="2025-04-29 23:58:31.211401554 +0000 UTC m=+4.193248306" lastFinishedPulling="2025-04-29 23:58:38.875284968 +0000 UTC m=+11.857131732" observedRunningTime="2025-04-29 23:58:41.009754498 +0000 UTC m=+13.991601346" watchObservedRunningTime="2025-04-29 23:58:47.029159008 +0000 UTC m=+20.011005760" Apr 29 23:58:47.767993 kubelet[2381]: E0429 23:58:47.767906 2381 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:47.781516 kubelet[2381]: E0429 23:58:47.781474 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:48.782394 kubelet[2381]: E0429 23:58:48.782325 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:49.783213 kubelet[2381]: E0429 23:58:49.783149 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:50.589799 kubelet[2381]: I0429 23:58:50.589675 2381 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-cmz4w" podStartSLOduration=6.184656556 podStartE2EDuration="10.589639102s" podCreationTimestamp="2025-04-29 23:58:40 +0000 UTC" firstStartedPulling="2025-04-29 23:58:42.216202488 +0000 UTC m=+15.198049252" lastFinishedPulling="2025-04-29 23:58:46.621185046 +0000 UTC m=+19.603031798" observedRunningTime="2025-04-29 23:58:47.03111196 +0000 UTC m=+20.012958736" watchObservedRunningTime="2025-04-29 23:58:50.589639102 +0000 UTC m=+23.571485866" Apr 29 23:58:50.590126 kubelet[2381]: I0429 23:58:50.589885 2381 topology_manager.go:215] "Topology Admit Handler" podUID="39b0af0a-08f9-4f87-a812-6cc023877baf" podNamespace="default" podName="nfs-server-provisioner-0" Apr 29 23:58:50.601915 systemd[1]: Created slice kubepods-besteffort-pod39b0af0a_08f9_4f87_a812_6cc023877baf.slice - libcontainer container kubepods-besteffort-pod39b0af0a_08f9_4f87_a812_6cc023877baf.slice. Apr 29 23:58:50.627668 kubelet[2381]: I0429 23:58:50.627522 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/39b0af0a-08f9-4f87-a812-6cc023877baf-data\") pod \"nfs-server-provisioner-0\" (UID: \"39b0af0a-08f9-4f87-a812-6cc023877baf\") " pod="default/nfs-server-provisioner-0" Apr 29 23:58:50.627668 kubelet[2381]: I0429 23:58:50.627578 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h844l\" (UniqueName: \"kubernetes.io/projected/39b0af0a-08f9-4f87-a812-6cc023877baf-kube-api-access-h844l\") pod \"nfs-server-provisioner-0\" (UID: \"39b0af0a-08f9-4f87-a812-6cc023877baf\") " pod="default/nfs-server-provisioner-0" Apr 29 23:58:50.783573 kubelet[2381]: E0429 23:58:50.783513 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:50.908169 containerd[1937]: time="2025-04-29T23:58:50.907945320Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:39b0af0a-08f9-4f87-a812-6cc023877baf,Namespace:default,Attempt:0,}" Apr 29 23:58:50.944846 (udev-worker)[3131]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:50.946334 systemd-networkd[1850]: vethffbccf1a: Link UP Apr 29 23:58:50.951401 kernel: cni0: port 2(vethffbccf1a) entered blocking state Apr 29 23:58:50.951559 kernel: cni0: port 2(vethffbccf1a) entered disabled state Apr 29 23:58:50.951607 kernel: vethffbccf1a: entered allmulticast mode Apr 29 23:58:50.952926 kernel: vethffbccf1a: entered promiscuous mode Apr 29 23:58:50.967157 kernel: cni0: port 2(vethffbccf1a) entered blocking state Apr 29 23:58:50.967274 kernel: cni0: port 2(vethffbccf1a) entered forwarding state Apr 29 23:58:50.967567 systemd-networkd[1850]: vethffbccf1a: Gained carrier Apr 29 23:58:50.971150 containerd[1937]: 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)(0x400001c938), "name":"cbr0", "type":"bridge"} Apr 29 23:58:50.971150 containerd[1937]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:58:51.003097 containerd[1937]: {"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-29T23:58:51.002215832Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:58:51.003097 containerd[1937]: time="2025-04-29T23:58:51.002320724Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:58:51.003097 containerd[1937]: time="2025-04-29T23:58:51.002356616Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:51.003097 containerd[1937]: time="2025-04-29T23:58:51.002526260Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:58:51.048249 systemd[1]: Started cri-containerd-95dbd8da0df2a5143410f98ce6d13c6503d35c70abcff8de63fe93cecccd12ab.scope - libcontainer container 95dbd8da0df2a5143410f98ce6d13c6503d35c70abcff8de63fe93cecccd12ab. Apr 29 23:58:51.107952 containerd[1937]: time="2025-04-29T23:58:51.107812269Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:39b0af0a-08f9-4f87-a812-6cc023877baf,Namespace:default,Attempt:0,} returns sandbox id \"95dbd8da0df2a5143410f98ce6d13c6503d35c70abcff8de63fe93cecccd12ab\"" Apr 29 23:58:51.112056 containerd[1937]: time="2025-04-29T23:58:51.111914601Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 29 23:58:51.784523 kubelet[2381]: E0429 23:58:51.784406 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:52.786331 kubelet[2381]: E0429 23:58:52.786258 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:52.806487 systemd-networkd[1850]: vethffbccf1a: Gained IPv6LL Apr 29 23:58:53.585411 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3730774262.mount: Deactivated successfully. Apr 29 23:58:53.787009 kubelet[2381]: E0429 23:58:53.786920 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:54.475077 update_engine[1916]: I20250429 23:58:54.474045 1916 update_attempter.cc:509] Updating boot flags... Apr 29 23:58:54.595214 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (3245) Apr 29 23:58:54.790219 kubelet[2381]: E0429 23:58:54.790066 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:54.975052 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (3245) Apr 29 23:58:55.248030 ntpd[1911]: Listen normally on 12 vethffbccf1a [fe80::40e0:46ff:fee2:b20d%6]:123 Apr 29 23:58:55.251076 ntpd[1911]: 29 Apr 23:58:55 ntpd[1911]: Listen normally on 12 vethffbccf1a [fe80::40e0:46ff:fee2:b20d%6]:123 Apr 29 23:58:55.410230 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (3245) Apr 29 23:58:55.790906 kubelet[2381]: E0429 23:58:55.790829 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:56.791207 kubelet[2381]: E0429 23:58:56.791144 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:57.561093 containerd[1937]: time="2025-04-29T23:58:57.560192093Z" 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 29 23:58:57.562231 containerd[1937]: time="2025-04-29T23:58:57.562139489Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Apr 29 23:58:57.564696 containerd[1937]: time="2025-04-29T23:58:57.564623945Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:57.570405 containerd[1937]: time="2025-04-29T23:58:57.570325373Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:58:57.572890 containerd[1937]: time="2025-04-29T23:58:57.572339777Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 6.46031144s" Apr 29 23:58:57.572890 containerd[1937]: time="2025-04-29T23:58:57.572400521Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 29 23:58:57.577197 containerd[1937]: time="2025-04-29T23:58:57.577149317Z" level=info msg="CreateContainer within sandbox \"95dbd8da0df2a5143410f98ce6d13c6503d35c70abcff8de63fe93cecccd12ab\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 29 23:58:57.614760 containerd[1937]: time="2025-04-29T23:58:57.614701949Z" level=info msg="CreateContainer within sandbox \"95dbd8da0df2a5143410f98ce6d13c6503d35c70abcff8de63fe93cecccd12ab\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"d7116f02e7b28e6a54b58c7b6f965dd04a06bd21f7c603dcad04955092be67ac\"" Apr 29 23:58:57.616170 containerd[1937]: time="2025-04-29T23:58:57.616125581Z" level=info msg="StartContainer for \"d7116f02e7b28e6a54b58c7b6f965dd04a06bd21f7c603dcad04955092be67ac\"" Apr 29 23:58:57.671280 systemd[1]: Started cri-containerd-d7116f02e7b28e6a54b58c7b6f965dd04a06bd21f7c603dcad04955092be67ac.scope - libcontainer container d7116f02e7b28e6a54b58c7b6f965dd04a06bd21f7c603dcad04955092be67ac. Apr 29 23:58:57.721485 containerd[1937]: time="2025-04-29T23:58:57.721413366Z" level=info msg="StartContainer for \"d7116f02e7b28e6a54b58c7b6f965dd04a06bd21f7c603dcad04955092be67ac\" returns successfully" Apr 29 23:58:57.792650 kubelet[2381]: E0429 23:58:57.792595 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:58.062768 kubelet[2381]: I0429 23:58:58.062675 2381 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.599345951 podStartE2EDuration="8.062655939s" podCreationTimestamp="2025-04-29 23:58:50 +0000 UTC" firstStartedPulling="2025-04-29 23:58:51.111111921 +0000 UTC m=+24.092958673" lastFinishedPulling="2025-04-29 23:58:57.574421897 +0000 UTC m=+30.556268661" observedRunningTime="2025-04-29 23:58:58.062372571 +0000 UTC m=+31.044219335" watchObservedRunningTime="2025-04-29 23:58:58.062655939 +0000 UTC m=+31.044502691" Apr 29 23:58:58.793292 kubelet[2381]: E0429 23:58:58.793223 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:58:59.794388 kubelet[2381]: E0429 23:58:59.794303 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:00.795279 kubelet[2381]: E0429 23:59:00.795204 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:01.795561 kubelet[2381]: E0429 23:59:01.795491 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:02.796193 kubelet[2381]: E0429 23:59:02.796132 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:03.796556 kubelet[2381]: E0429 23:59:03.796493 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:04.797585 kubelet[2381]: E0429 23:59:04.797526 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:05.798366 kubelet[2381]: E0429 23:59:05.798300 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:06.799089 kubelet[2381]: E0429 23:59:06.799024 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:07.767810 kubelet[2381]: E0429 23:59:07.767735 2381 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:07.799450 kubelet[2381]: E0429 23:59:07.799404 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:08.041333 kubelet[2381]: I0429 23:59:08.040707 2381 topology_manager.go:215] "Topology Admit Handler" podUID="8759780b-7f95-47b5-8519-bccc21dbe1e6" podNamespace="default" podName="test-pod-1" Apr 29 23:59:08.051214 systemd[1]: Created slice kubepods-besteffort-pod8759780b_7f95_47b5_8519_bccc21dbe1e6.slice - libcontainer container kubepods-besteffort-pod8759780b_7f95_47b5_8519_bccc21dbe1e6.slice. Apr 29 23:59:08.142229 kubelet[2381]: I0429 23:59:08.141915 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6ddwq\" (UniqueName: \"kubernetes.io/projected/8759780b-7f95-47b5-8519-bccc21dbe1e6-kube-api-access-6ddwq\") pod \"test-pod-1\" (UID: \"8759780b-7f95-47b5-8519-bccc21dbe1e6\") " pod="default/test-pod-1" Apr 29 23:59:08.142229 kubelet[2381]: I0429 23:59:08.142009 2381 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-f6c8a421-3160-4470-a440-a1e4ee240937\" (UniqueName: \"kubernetes.io/nfs/8759780b-7f95-47b5-8519-bccc21dbe1e6-pvc-f6c8a421-3160-4470-a440-a1e4ee240937\") pod \"test-pod-1\" (UID: \"8759780b-7f95-47b5-8519-bccc21dbe1e6\") " pod="default/test-pod-1" Apr 29 23:59:08.278012 kernel: FS-Cache: Loaded Apr 29 23:59:08.322014 kernel: RPC: Registered named UNIX socket transport module. Apr 29 23:59:08.322155 kernel: RPC: Registered udp transport module. Apr 29 23:59:08.322200 kernel: RPC: Registered tcp transport module. Apr 29 23:59:08.322242 kernel: RPC: Registered tcp-with-tls transport module. Apr 29 23:59:08.322984 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 29 23:59:08.657688 kernel: NFS: Registering the id_resolver key type Apr 29 23:59:08.657856 kernel: Key type id_resolver registered Apr 29 23:59:08.657904 kernel: Key type id_legacy registered Apr 29 23:59:08.692918 nfsidmap[3674]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 29 23:59:08.699078 nfsidmap[3676]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Apr 29 23:59:08.799918 kubelet[2381]: E0429 23:59:08.799826 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:08.957505 containerd[1937]: time="2025-04-29T23:59:08.957294713Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8759780b-7f95-47b5-8519-bccc21dbe1e6,Namespace:default,Attempt:0,}" Apr 29 23:59:09.001605 (udev-worker)[3673]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:59:09.004190 systemd-networkd[1850]: vethb3f1368d: Link UP Apr 29 23:59:09.009730 kernel: cni0: port 3(vethb3f1368d) entered blocking state Apr 29 23:59:09.009839 kernel: cni0: port 3(vethb3f1368d) entered disabled state Apr 29 23:59:09.009884 kernel: vethb3f1368d: entered allmulticast mode Apr 29 23:59:09.012014 kernel: vethb3f1368d: entered promiscuous mode Apr 29 23:59:09.020029 kernel: cni0: port 3(vethb3f1368d) entered blocking state Apr 29 23:59:09.020170 kernel: cni0: port 3(vethb3f1368d) entered forwarding state Apr 29 23:59:09.020382 systemd-networkd[1850]: vethb3f1368d: Gained carrier Apr 29 23:59:09.025655 containerd[1937]: 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)(0x4000106628), "name":"cbr0", "type":"bridge"} Apr 29 23:59:09.025655 containerd[1937]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:59:09.061843 containerd[1937]: {"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-29T23:59:09.061700906Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:09.062074 containerd[1937]: time="2025-04-29T23:59:09.061826462Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:09.062074 containerd[1937]: time="2025-04-29T23:59:09.061865630Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:09.063087 containerd[1937]: time="2025-04-29T23:59:09.063001058Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:09.100477 systemd[1]: Started cri-containerd-c49d9aef55db256033e931810f9868f8487de52c850e6933e3e0d987c6b6efa9.scope - libcontainer container c49d9aef55db256033e931810f9868f8487de52c850e6933e3e0d987c6b6efa9. Apr 29 23:59:09.162536 containerd[1937]: time="2025-04-29T23:59:09.162350762Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8759780b-7f95-47b5-8519-bccc21dbe1e6,Namespace:default,Attempt:0,} returns sandbox id \"c49d9aef55db256033e931810f9868f8487de52c850e6933e3e0d987c6b6efa9\"" Apr 29 23:59:09.166405 containerd[1937]: time="2025-04-29T23:59:09.166111838Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 29 23:59:09.489671 containerd[1937]: time="2025-04-29T23:59:09.489538756Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:09.493030 containerd[1937]: time="2025-04-29T23:59:09.492703960Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 29 23:59:09.497420 containerd[1937]: time="2025-04-29T23:59:09.497330740Z" 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 331.159958ms" Apr 29 23:59:09.497420 containerd[1937]: time="2025-04-29T23:59:09.497392624Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 29 23:59:09.501333 containerd[1937]: time="2025-04-29T23:59:09.501254200Z" level=info msg="CreateContainer within sandbox \"c49d9aef55db256033e931810f9868f8487de52c850e6933e3e0d987c6b6efa9\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 29 23:59:09.535542 containerd[1937]: time="2025-04-29T23:59:09.535443568Z" level=info msg="CreateContainer within sandbox \"c49d9aef55db256033e931810f9868f8487de52c850e6933e3e0d987c6b6efa9\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"88e60c8ed9dc73de6f6f41bdf1299b4dc14a6edce7e2cb08fa432eae1f36503c\"" Apr 29 23:59:09.536722 containerd[1937]: time="2025-04-29T23:59:09.536642752Z" level=info msg="StartContainer for \"88e60c8ed9dc73de6f6f41bdf1299b4dc14a6edce7e2cb08fa432eae1f36503c\"" Apr 29 23:59:09.591353 systemd[1]: Started cri-containerd-88e60c8ed9dc73de6f6f41bdf1299b4dc14a6edce7e2cb08fa432eae1f36503c.scope - libcontainer container 88e60c8ed9dc73de6f6f41bdf1299b4dc14a6edce7e2cb08fa432eae1f36503c. Apr 29 23:59:09.648105 containerd[1937]: time="2025-04-29T23:59:09.648026957Z" level=info msg="StartContainer for \"88e60c8ed9dc73de6f6f41bdf1299b4dc14a6edce7e2cb08fa432eae1f36503c\" returns successfully" Apr 29 23:59:09.800472 kubelet[2381]: E0429 23:59:09.800311 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:10.801309 kubelet[2381]: E0429 23:59:10.801248 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:10.853270 systemd-networkd[1850]: vethb3f1368d: Gained IPv6LL Apr 29 23:59:11.801806 kubelet[2381]: E0429 23:59:11.801738 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:12.802713 kubelet[2381]: E0429 23:59:12.802661 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:13.248041 ntpd[1911]: Listen normally on 13 vethb3f1368d [fe80::fc54:6ff:fe4e:fd3c%7]:123 Apr 29 23:59:13.248519 ntpd[1911]: 29 Apr 23:59:13 ntpd[1911]: Listen normally on 13 vethb3f1368d [fe80::fc54:6ff:fe4e:fd3c%7]:123 Apr 29 23:59:13.803223 kubelet[2381]: E0429 23:59:13.803150 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:14.803636 kubelet[2381]: E0429 23:59:14.803539 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:15.804162 kubelet[2381]: E0429 23:59:15.804098 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:16.804613 kubelet[2381]: E0429 23:59:16.804537 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:17.805692 kubelet[2381]: E0429 23:59:17.805620 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:18.806050 kubelet[2381]: E0429 23:59:18.805994 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:19.807166 kubelet[2381]: E0429 23:59:19.807089 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:20.807976 kubelet[2381]: E0429 23:59:20.807913 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:21.808677 kubelet[2381]: E0429 23:59:21.808609 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:22.809057 kubelet[2381]: E0429 23:59:22.808991 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:23.809339 kubelet[2381]: E0429 23:59:23.809271 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:24.810061 kubelet[2381]: E0429 23:59:24.809952 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:25.810506 kubelet[2381]: E0429 23:59:25.810446 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:26.811071 kubelet[2381]: E0429 23:59:26.811011 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:27.768041 kubelet[2381]: E0429 23:59:27.767924 2381 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:27.812153 kubelet[2381]: E0429 23:59:27.812089 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:28.813089 kubelet[2381]: E0429 23:59:28.813018 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:29.469021 kubelet[2381]: E0429 23:59:29.468791 2381 controller.go:195] "Failed to update lease" err="Put \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Apr 29 23:59:29.813770 kubelet[2381]: E0429 23:59:29.813599 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:30.813999 kubelet[2381]: E0429 23:59:30.813935 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:31.814317 kubelet[2381]: E0429 23:59:31.814242 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:32.815116 kubelet[2381]: E0429 23:59:32.815034 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:33.815626 kubelet[2381]: E0429 23:59:33.815552 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:34.816606 kubelet[2381]: E0429 23:59:34.816536 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:35.817104 kubelet[2381]: E0429 23:59:35.816991 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:36.817399 kubelet[2381]: E0429 23:59:36.817332 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:37.818183 kubelet[2381]: E0429 23:59:37.818123 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:38.819194 kubelet[2381]: E0429 23:59:38.819128 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:39.469948 kubelet[2381]: E0429 23:59:39.469715 2381 controller.go:195] "Failed to update lease" err="Put \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Apr 29 23:59:39.495994 kubelet[2381]: E0429 23:59:39.494174 2381 controller.go:195] "Failed to update lease" err="Put \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": read tcp 172.31.29.161:35664->172.31.26.137:6443: read: connection reset by peer" Apr 29 23:59:39.499272 kubelet[2381]: E0429 23:59:39.499206 2381 controller.go:195] "Failed to update lease" err="Put \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": read tcp 172.31.29.161:44668->172.31.26.137:6443: read: connection reset by peer" Apr 29 23:59:39.500570 kubelet[2381]: E0429 23:59:39.500516 2381 controller.go:195] "Failed to update lease" err="Put \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": dial tcp 172.31.26.137:6443: connect: connection refused" Apr 29 23:59:39.501072 kubelet[2381]: I0429 23:59:39.501035 2381 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Apr 29 23:59:39.501993 kubelet[2381]: E0429 23:59:39.501892 2381 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": dial tcp 172.31.26.137:6443: connect: connection refused" interval="200ms" Apr 29 23:59:39.819663 kubelet[2381]: E0429 23:59:39.819596 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:40.820260 kubelet[2381]: E0429 23:59:40.820197 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:41.821113 kubelet[2381]: E0429 23:59:41.821053 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:42.821231 kubelet[2381]: E0429 23:59:42.821159 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:43.822041 kubelet[2381]: E0429 23:59:43.821942 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:44.822927 kubelet[2381]: E0429 23:59:44.822872 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:45.824118 kubelet[2381]: E0429 23:59:45.824054 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:46.825150 kubelet[2381]: E0429 23:59:46.825044 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:47.767989 kubelet[2381]: E0429 23:59:47.767898 2381 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:47.825357 kubelet[2381]: E0429 23:59:47.825310 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:48.826044 kubelet[2381]: E0429 23:59:48.825988 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:49.703629 kubelet[2381]: E0429 23:59:49.703530 2381 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="400ms" Apr 29 23:59:49.826180 kubelet[2381]: E0429 23:59:49.826111 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:50.827378 kubelet[2381]: E0429 23:59:50.827305 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:51.828282 kubelet[2381]: E0429 23:59:51.828235 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:52.829917 kubelet[2381]: E0429 23:59:52.829853 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:53.830420 kubelet[2381]: E0429 23:59:53.830362 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:54.830753 kubelet[2381]: E0429 23:59:54.830689 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:55.831448 kubelet[2381]: E0429 23:59:55.831388 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:56.832559 kubelet[2381]: E0429 23:59:56.832481 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:57.833140 kubelet[2381]: E0429 23:59:57.833087 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:58.833801 kubelet[2381]: E0429 23:59:58.833741 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:59.061508 kubelet[2381]: E0429 23:59:59.061434 2381 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.29.161\": Get \"https://172.31.26.137:6443/api/v1/nodes/172.31.29.161?resourceVersion=0&timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" Apr 29 23:59:59.835096 kubelet[2381]: E0429 23:59:59.834954 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:00.105278 kubelet[2381]: E0430 00:00:00.105120 2381 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.26.137:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.29.161?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="800ms" Apr 30 00:00:00.323484 systemd[1]: Started logrotate.service - Rotate and Compress System Logs. Apr 30 00:00:00.348242 systemd[1]: logrotate.service: Deactivated successfully. Apr 30 00:00:00.835262 kubelet[2381]: E0430 00:00:00.835204 2381 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"