Apr 29 23:58:37.221304 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Apr 29 23:58:37.221348 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:58:37.221374 kernel: KASLR disabled due to lack of seed Apr 29 23:58:37.221392 kernel: efi: EFI v2.7 by EDK II Apr 29 23:58:37.221410 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x78503d98 Apr 29 23:58:37.221427 kernel: secureboot: Secure boot disabled Apr 29 23:58:37.221445 kernel: ACPI: Early table checksum verification disabled Apr 29 23:58:37.221461 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Apr 29 23:58:37.221477 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Apr 29 23:58:37.221493 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Apr 29 23:58:37.221519 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Apr 29 23:58:37.221569 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Apr 29 23:58:37.221596 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Apr 29 23:58:37.221613 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Apr 29 23:58:37.221633 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Apr 29 23:58:37.221665 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Apr 29 23:58:37.221683 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Apr 29 23:58:37.221700 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Apr 29 23:58:37.221717 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Apr 29 23:58:37.221735 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Apr 29 23:58:37.221752 kernel: printk: bootconsole [uart0] enabled Apr 29 23:58:37.221770 kernel: NUMA: Failed to initialise from firmware Apr 29 23:58:37.221788 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Apr 29 23:58:37.221805 kernel: NUMA: NODE_DATA [mem 0x4b5840800-0x4b5845fff] Apr 29 23:58:37.221822 kernel: Zone ranges: Apr 29 23:58:37.221859 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Apr 29 23:58:37.221893 kernel: DMA32 empty Apr 29 23:58:37.221910 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Apr 29 23:58:37.221927 kernel: Movable zone start for each node Apr 29 23:58:37.221943 kernel: Early memory node ranges Apr 29 23:58:37.221960 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Apr 29 23:58:37.221978 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Apr 29 23:58:37.221995 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Apr 29 23:58:37.222011 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Apr 29 23:58:37.222028 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Apr 29 23:58:37.222044 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Apr 29 23:58:37.222062 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Apr 29 23:58:37.222078 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Apr 29 23:58:37.222107 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Apr 29 23:58:37.222126 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Apr 29 23:58:37.222154 kernel: psci: probing for conduit method from ACPI. Apr 29 23:58:37.222173 kernel: psci: PSCIv1.0 detected in firmware. Apr 29 23:58:37.222193 kernel: psci: Using standard PSCI v0.2 function IDs Apr 29 23:58:37.222220 kernel: psci: Trusted OS migration not required Apr 29 23:58:37.222240 kernel: psci: SMC Calling Convention v1.1 Apr 29 23:58:37.222259 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Apr 29 23:58:37.222278 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Apr 29 23:58:37.222297 kernel: pcpu-alloc: [0] 0 [0] 1 Apr 29 23:58:37.222315 kernel: Detected PIPT I-cache on CPU0 Apr 29 23:58:37.222332 kernel: CPU features: detected: GIC system register CPU interface Apr 29 23:58:37.222349 kernel: CPU features: detected: Spectre-v2 Apr 29 23:58:37.222366 kernel: CPU features: detected: Spectre-v3a Apr 29 23:58:37.222383 kernel: CPU features: detected: Spectre-BHB Apr 29 23:58:37.222399 kernel: CPU features: detected: ARM erratum 1742098 Apr 29 23:58:37.222416 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Apr 29 23:58:37.222439 kernel: alternatives: applying boot alternatives Apr 29 23:58:37.222459 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:58:37.222477 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 29 23:58:37.222514 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 29 23:58:37.222533 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 29 23:58:37.222606 kernel: Fallback order for Node 0: 0 Apr 29 23:58:37.222623 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Apr 29 23:58:37.222640 kernel: Policy zone: Normal Apr 29 23:58:37.222657 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 29 23:58:37.222673 kernel: software IO TLB: area num 2. Apr 29 23:58:37.222698 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Apr 29 23:58:37.222715 kernel: Memory: 3819836K/4030464K available (10304K kernel code, 2186K rwdata, 8104K rodata, 39744K init, 897K bss, 210628K reserved, 0K cma-reserved) Apr 29 23:58:37.222733 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 29 23:58:37.222749 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 29 23:58:37.222767 kernel: rcu: RCU event tracing is enabled. Apr 29 23:58:37.222784 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 29 23:58:37.222802 kernel: Trampoline variant of Tasks RCU enabled. Apr 29 23:58:37.222818 kernel: Tracing variant of Tasks RCU enabled. Apr 29 23:58:37.222835 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 29 23:58:37.222852 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 29 23:58:37.222869 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 29 23:58:37.222890 kernel: GICv3: 96 SPIs implemented Apr 29 23:58:37.222907 kernel: GICv3: 0 Extended SPIs implemented Apr 29 23:58:37.222924 kernel: Root IRQ handler: gic_handle_irq Apr 29 23:58:37.222940 kernel: GICv3: GICv3 features: 16 PPIs Apr 29 23:58:37.222957 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Apr 29 23:58:37.222974 kernel: ITS [mem 0x10080000-0x1009ffff] Apr 29 23:58:37.222991 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Apr 29 23:58:37.223008 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Apr 29 23:58:37.223025 kernel: GICv3: using LPI property table @0x00000004000d0000 Apr 29 23:58:37.223042 kernel: ITS: Using hypervisor restricted LPI range [128] Apr 29 23:58:37.223060 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Apr 29 23:58:37.223077 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 29 23:58:37.223098 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Apr 29 23:58:37.223116 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Apr 29 23:58:37.223132 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Apr 29 23:58:37.223149 kernel: Console: colour dummy device 80x25 Apr 29 23:58:37.223167 kernel: printk: console [tty1] enabled Apr 29 23:58:37.223184 kernel: ACPI: Core revision 20230628 Apr 29 23:58:37.223202 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Apr 29 23:58:37.223220 kernel: pid_max: default: 32768 minimum: 301 Apr 29 23:58:37.223238 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 29 23:58:37.223255 kernel: landlock: Up and running. Apr 29 23:58:37.223277 kernel: SELinux: Initializing. Apr 29 23:58:37.223294 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 29 23:58:37.223312 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 29 23:58:37.223331 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 29 23:58:37.223349 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:58:37.223367 kernel: rcu: Hierarchical SRCU implementation. Apr 29 23:58:37.223385 kernel: rcu: Max phase no-delay instances is 400. Apr 29 23:58:37.223403 kernel: Platform MSI: ITS@0x10080000 domain created Apr 29 23:58:37.223431 kernel: PCI/MSI: ITS@0x10080000 domain created Apr 29 23:58:37.223451 kernel: Remapping and enabling EFI services. Apr 29 23:58:37.223470 kernel: smp: Bringing up secondary CPUs ... Apr 29 23:58:37.223488 kernel: Detected PIPT I-cache on CPU1 Apr 29 23:58:37.223507 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Apr 29 23:58:37.223525 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Apr 29 23:58:37.223599 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Apr 29 23:58:37.223622 kernel: smp: Brought up 1 node, 2 CPUs Apr 29 23:58:37.223640 kernel: SMP: Total of 2 processors activated. Apr 29 23:58:37.223659 kernel: CPU features: detected: 32-bit EL0 Support Apr 29 23:58:37.223693 kernel: CPU features: detected: 32-bit EL1 Support Apr 29 23:58:37.223712 kernel: CPU features: detected: CRC32 instructions Apr 29 23:58:37.223744 kernel: CPU: All CPU(s) started at EL1 Apr 29 23:58:37.223770 kernel: alternatives: applying system-wide alternatives Apr 29 23:58:37.223789 kernel: devtmpfs: initialized Apr 29 23:58:37.223808 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 29 23:58:37.223827 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 29 23:58:37.223846 kernel: pinctrl core: initialized pinctrl subsystem Apr 29 23:58:37.223865 kernel: SMBIOS 3.0.0 present. Apr 29 23:58:37.223893 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Apr 29 23:58:37.223912 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 29 23:58:37.223931 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 29 23:58:37.223950 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 29 23:58:37.223969 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 29 23:58:37.223988 kernel: audit: initializing netlink subsys (disabled) Apr 29 23:58:37.224008 kernel: audit: type=2000 audit(0.242:1): state=initialized audit_enabled=0 res=1 Apr 29 23:58:37.224036 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 29 23:58:37.224058 kernel: cpuidle: using governor menu Apr 29 23:58:37.224078 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 29 23:58:37.224098 kernel: ASID allocator initialised with 65536 entries Apr 29 23:58:37.224117 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 29 23:58:37.224137 kernel: Serial: AMBA PL011 UART driver Apr 29 23:58:37.224158 kernel: Modules: 17408 pages in range for non-PLT usage Apr 29 23:58:37.224178 kernel: Modules: 508928 pages in range for PLT usage Apr 29 23:58:37.224197 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 29 23:58:37.224224 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Apr 29 23:58:37.224246 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Apr 29 23:58:37.224265 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Apr 29 23:58:37.224285 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 29 23:58:37.224304 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Apr 29 23:58:37.224322 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Apr 29 23:58:37.224340 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Apr 29 23:58:37.224359 kernel: ACPI: Added _OSI(Module Device) Apr 29 23:58:37.224377 kernel: ACPI: Added _OSI(Processor Device) Apr 29 23:58:37.224400 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 29 23:58:37.224419 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 29 23:58:37.224437 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 29 23:58:37.224455 kernel: ACPI: Interpreter enabled Apr 29 23:58:37.224473 kernel: ACPI: Using GIC for interrupt routing Apr 29 23:58:37.224491 kernel: ACPI: MCFG table detected, 1 entries Apr 29 23:58:37.224509 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Apr 29 23:58:37.224881 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 29 23:58:37.225115 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 29 23:58:37.225331 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 29 23:58:37.225639 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Apr 29 23:58:37.225951 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Apr 29 23:58:37.225992 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Apr 29 23:58:37.226014 kernel: acpiphp: Slot [1] registered Apr 29 23:58:37.226035 kernel: acpiphp: Slot [2] registered Apr 29 23:58:37.226054 kernel: acpiphp: Slot [3] registered Apr 29 23:58:37.226087 kernel: acpiphp: Slot [4] registered Apr 29 23:58:37.226108 kernel: acpiphp: Slot [5] registered Apr 29 23:58:37.226129 kernel: acpiphp: Slot [6] registered Apr 29 23:58:37.226148 kernel: acpiphp: Slot [7] registered Apr 29 23:58:37.226168 kernel: acpiphp: Slot [8] registered Apr 29 23:58:37.226186 kernel: acpiphp: Slot [9] registered Apr 29 23:58:37.226204 kernel: acpiphp: Slot [10] registered Apr 29 23:58:37.226222 kernel: acpiphp: Slot [11] registered Apr 29 23:58:37.226240 kernel: acpiphp: Slot [12] registered Apr 29 23:58:37.226258 kernel: acpiphp: Slot [13] registered Apr 29 23:58:37.226281 kernel: acpiphp: Slot [14] registered Apr 29 23:58:37.226300 kernel: acpiphp: Slot [15] registered Apr 29 23:58:37.226317 kernel: acpiphp: Slot [16] registered Apr 29 23:58:37.226335 kernel: acpiphp: Slot [17] registered Apr 29 23:58:37.226353 kernel: acpiphp: Slot [18] registered Apr 29 23:58:37.226370 kernel: acpiphp: Slot [19] registered Apr 29 23:58:37.226388 kernel: acpiphp: Slot [20] registered Apr 29 23:58:37.226406 kernel: acpiphp: Slot [21] registered Apr 29 23:58:37.226424 kernel: acpiphp: Slot [22] registered Apr 29 23:58:37.226446 kernel: acpiphp: Slot [23] registered Apr 29 23:58:37.226464 kernel: acpiphp: Slot [24] registered Apr 29 23:58:37.226482 kernel: acpiphp: Slot [25] registered Apr 29 23:58:37.226499 kernel: acpiphp: Slot [26] registered Apr 29 23:58:37.226517 kernel: acpiphp: Slot [27] registered Apr 29 23:58:37.226568 kernel: acpiphp: Slot [28] registered Apr 29 23:58:37.226594 kernel: acpiphp: Slot [29] registered Apr 29 23:58:37.226613 kernel: acpiphp: Slot [30] registered Apr 29 23:58:37.226631 kernel: acpiphp: Slot [31] registered Apr 29 23:58:37.226649 kernel: PCI host bridge to bus 0000:00 Apr 29 23:58:37.226894 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Apr 29 23:58:37.227089 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Apr 29 23:58:37.227345 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Apr 29 23:58:37.227689 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Apr 29 23:58:37.228037 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Apr 29 23:58:37.228285 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Apr 29 23:58:37.228513 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Apr 29 23:58:37.228894 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Apr 29 23:58:37.229155 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Apr 29 23:58:37.229451 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 29 23:58:37.231961 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Apr 29 23:58:37.232203 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Apr 29 23:58:37.232412 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Apr 29 23:58:37.232676 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Apr 29 23:58:37.232967 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Apr 29 23:58:37.233262 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Apr 29 23:58:37.234687 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Apr 29 23:58:37.235065 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Apr 29 23:58:37.235331 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Apr 29 23:58:37.235673 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Apr 29 23:58:37.235890 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Apr 29 23:58:37.236077 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Apr 29 23:58:37.236263 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Apr 29 23:58:37.236289 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Apr 29 23:58:37.236308 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Apr 29 23:58:37.236326 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Apr 29 23:58:37.236344 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Apr 29 23:58:37.236362 kernel: iommu: Default domain type: Translated Apr 29 23:58:37.236387 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 29 23:58:37.236405 kernel: efivars: Registered efivars operations Apr 29 23:58:37.236423 kernel: vgaarb: loaded Apr 29 23:58:37.236440 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 29 23:58:37.236459 kernel: VFS: Disk quotas dquot_6.6.0 Apr 29 23:58:37.236477 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 29 23:58:37.236495 kernel: pnp: PnP ACPI init Apr 29 23:58:37.239167 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Apr 29 23:58:37.239247 kernel: pnp: PnP ACPI: found 1 devices Apr 29 23:58:37.239276 kernel: NET: Registered PF_INET protocol family Apr 29 23:58:37.239297 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 29 23:58:37.239319 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 29 23:58:37.239339 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 29 23:58:37.239360 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 29 23:58:37.239380 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Apr 29 23:58:37.239399 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 29 23:58:37.239418 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 29 23:58:37.239446 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 29 23:58:37.239467 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 29 23:58:37.239487 kernel: PCI: CLS 0 bytes, default 64 Apr 29 23:58:37.239506 kernel: kvm [1]: HYP mode not available Apr 29 23:58:37.239524 kernel: Initialise system trusted keyrings Apr 29 23:58:37.242021 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 29 23:58:37.242051 kernel: Key type asymmetric registered Apr 29 23:58:37.242070 kernel: Asymmetric key parser 'x509' registered Apr 29 23:58:37.242089 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Apr 29 23:58:37.242118 kernel: io scheduler mq-deadline registered Apr 29 23:58:37.242137 kernel: io scheduler kyber registered Apr 29 23:58:37.242156 kernel: io scheduler bfq registered Apr 29 23:58:37.242442 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Apr 29 23:58:37.242480 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Apr 29 23:58:37.242519 kernel: ACPI: button: Power Button [PWRB] Apr 29 23:58:37.242591 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Apr 29 23:58:37.242615 kernel: ACPI: button: Sleep Button [SLPB] Apr 29 23:58:37.242646 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 29 23:58:37.242669 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Apr 29 23:58:37.242997 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Apr 29 23:58:37.243037 kernel: printk: console [ttyS0] disabled Apr 29 23:58:37.243059 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Apr 29 23:58:37.243104 kernel: printk: console [ttyS0] enabled Apr 29 23:58:37.243130 kernel: printk: bootconsole [uart0] disabled Apr 29 23:58:37.243152 kernel: thunder_xcv, ver 1.0 Apr 29 23:58:37.243173 kernel: thunder_bgx, ver 1.0 Apr 29 23:58:37.243194 kernel: nicpf, ver 1.0 Apr 29 23:58:37.243231 kernel: nicvf, ver 1.0 Apr 29 23:58:37.243497 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 29 23:58:37.243770 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-04-29T23:58:36 UTC (1745971116) Apr 29 23:58:37.243797 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 29 23:58:37.243826 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Apr 29 23:58:37.243846 kernel: watchdog: Delayed init of the lockup detector failed: -19 Apr 29 23:58:37.243864 kernel: watchdog: Hard watchdog permanently disabled Apr 29 23:58:37.243891 kernel: NET: Registered PF_INET6 protocol family Apr 29 23:58:37.243910 kernel: Segment Routing with IPv6 Apr 29 23:58:37.243928 kernel: In-situ OAM (IOAM) with IPv6 Apr 29 23:58:37.243946 kernel: NET: Registered PF_PACKET protocol family Apr 29 23:58:37.243964 kernel: Key type dns_resolver registered Apr 29 23:58:37.243982 kernel: registered taskstats version 1 Apr 29 23:58:37.244000 kernel: Loading compiled-in X.509 certificates Apr 29 23:58:37.244018 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: bbef389676bd9584646af24e9e264c7789f8630f' Apr 29 23:58:37.244035 kernel: Key type .fscrypt registered Apr 29 23:58:37.244066 kernel: Key type fscrypt-provisioning registered Apr 29 23:58:37.244092 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 29 23:58:37.244111 kernel: ima: Allocated hash algorithm: sha1 Apr 29 23:58:37.244129 kernel: ima: No architecture policies found Apr 29 23:58:37.244148 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 29 23:58:37.244166 kernel: clk: Disabling unused clocks Apr 29 23:58:37.244183 kernel: Freeing unused kernel memory: 39744K Apr 29 23:58:37.244201 kernel: Run /init as init process Apr 29 23:58:37.244219 kernel: with arguments: Apr 29 23:58:37.244237 kernel: /init Apr 29 23:58:37.244259 kernel: with environment: Apr 29 23:58:37.244277 kernel: HOME=/ Apr 29 23:58:37.244296 kernel: TERM=linux Apr 29 23:58:37.244315 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 29 23:58:37.244343 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:37.244368 systemd[1]: Detected virtualization amazon. Apr 29 23:58:37.244390 systemd[1]: Detected architecture arm64. Apr 29 23:58:37.244421 systemd[1]: Running in initrd. Apr 29 23:58:37.244443 systemd[1]: No hostname configured, using default hostname. Apr 29 23:58:37.244463 systemd[1]: Hostname set to . Apr 29 23:58:37.244484 systemd[1]: Initializing machine ID from VM UUID. Apr 29 23:58:37.244505 systemd[1]: Queued start job for default target initrd.target. Apr 29 23:58:37.244526 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:58:37.244597 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:58:37.244625 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 29 23:58:37.244660 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 29 23:58:37.244682 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 29 23:58:37.244703 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 29 23:58:37.244728 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:58:37.244751 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 29 23:58:37.244772 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:58:37.244794 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:58:37.244826 systemd[1]: Reached target paths.target - Path Units. Apr 29 23:58:37.244848 systemd[1]: Reached target slices.target - Slice Units. Apr 29 23:58:37.244869 systemd[1]: Reached target swap.target - Swaps. Apr 29 23:58:37.244890 systemd[1]: Reached target timers.target - Timer Units. Apr 29 23:58:37.244912 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 29 23:58:37.244933 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 29 23:58:37.244955 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 29 23:58:37.244975 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 29 23:58:37.244998 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:58:37.245031 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 29 23:58:37.245096 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:58:37.245139 systemd[1]: Reached target sockets.target - Socket Units. Apr 29 23:58:37.245164 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 29 23:58:37.245186 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 29 23:58:37.245208 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 29 23:58:37.245230 systemd[1]: Starting systemd-fsck-usr.service... Apr 29 23:58:37.245252 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 29 23:58:37.245287 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 29 23:58:37.245309 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:58:37.245329 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 29 23:58:37.245403 systemd-journald[252]: Collecting audit messages is disabled. Apr 29 23:58:37.245456 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:58:37.245477 systemd[1]: Finished systemd-fsck-usr.service. Apr 29 23:58:37.245498 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 29 23:58:37.245518 systemd-journald[252]: Journal started Apr 29 23:58:37.249677 systemd-journald[252]: Runtime Journal (/run/log/journal/ec25ef9cd893844493530ec72b52f00a) is 8.0M, max 75.3M, 67.3M free. Apr 29 23:58:37.240706 systemd-modules-load[253]: Inserted module 'overlay' Apr 29 23:58:37.257065 systemd[1]: Started systemd-journald.service - Journal Service. Apr 29 23:58:37.262218 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:37.282789 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:58:37.288655 kernel: Bridge firewalling registered Apr 29 23:58:37.285110 systemd-modules-load[253]: Inserted module 'br_netfilter' Apr 29 23:58:37.285534 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:58:37.296963 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 29 23:58:37.303994 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 29 23:58:37.314864 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:58:37.333094 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 29 23:58:37.345258 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 29 23:58:37.357815 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:58:37.381011 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:58:37.386687 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:58:37.401982 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 29 23:58:37.410831 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 29 23:58:37.416753 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:58:37.436195 dracut-cmdline[284]: dracut-dracut-053 Apr 29 23:58:37.443582 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:58:37.503113 systemd-resolved[285]: Positive Trust Anchors: Apr 29 23:58:37.503174 systemd-resolved[285]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 29 23:58:37.503236 systemd-resolved[285]: 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:37.634594 kernel: SCSI subsystem initialized Apr 29 23:58:37.644573 kernel: Loading iSCSI transport class v2.0-870. Apr 29 23:58:37.656579 kernel: iscsi: registered transport (tcp) Apr 29 23:58:37.678579 kernel: iscsi: registered transport (qla4xxx) Apr 29 23:58:37.678654 kernel: QLogic iSCSI HBA Driver Apr 29 23:58:37.752579 kernel: random: crng init done Apr 29 23:58:37.753017 systemd-resolved[285]: Defaulting to hostname 'linux'. Apr 29 23:58:37.756612 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 29 23:58:37.774082 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:58:37.785625 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 29 23:58:37.797940 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 29 23:58:37.835419 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 29 23:58:37.835495 kernel: device-mapper: uevent: version 1.0.3 Apr 29 23:58:37.835522 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 29 23:58:37.902629 kernel: raid6: neonx8 gen() 6622 MB/s Apr 29 23:58:37.919625 kernel: raid6: neonx4 gen() 6401 MB/s Apr 29 23:58:37.936581 kernel: raid6: neonx2 gen() 5390 MB/s Apr 29 23:58:37.953582 kernel: raid6: neonx1 gen() 3916 MB/s Apr 29 23:58:37.970638 kernel: raid6: int64x8 gen() 3741 MB/s Apr 29 23:58:37.987616 kernel: raid6: int64x4 gen() 3682 MB/s Apr 29 23:58:38.004593 kernel: raid6: int64x2 gen() 3547 MB/s Apr 29 23:58:38.022632 kernel: raid6: int64x1 gen() 2711 MB/s Apr 29 23:58:38.022836 kernel: raid6: using algorithm neonx8 gen() 6622 MB/s Apr 29 23:58:38.041444 kernel: raid6: .... xor() 4847 MB/s, rmw enabled Apr 29 23:58:38.041529 kernel: raid6: using neon recovery algorithm Apr 29 23:58:38.050766 kernel: xor: measuring software checksum speed Apr 29 23:58:38.050861 kernel: 8regs : 10617 MB/sec Apr 29 23:58:38.051915 kernel: 32regs : 11808 MB/sec Apr 29 23:58:38.053154 kernel: arm64_neon : 9468 MB/sec Apr 29 23:58:38.053216 kernel: xor: using function: 32regs (11808 MB/sec) Apr 29 23:58:38.142593 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 29 23:58:38.163568 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 29 23:58:38.173969 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:58:38.214797 systemd-udevd[468]: Using default interface naming scheme 'v255'. Apr 29 23:58:38.223936 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:58:38.236321 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 29 23:58:38.284251 dracut-pre-trigger[474]: rd.md=0: removing MD RAID activation Apr 29 23:58:38.350659 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 29 23:58:38.362858 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 29 23:58:38.503519 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:58:38.519949 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 29 23:58:38.570616 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 29 23:58:38.576246 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 29 23:58:38.581186 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:58:38.583473 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 29 23:58:38.601016 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 29 23:58:38.641134 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 29 23:58:38.715071 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Apr 29 23:58:38.715145 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Apr 29 23:58:38.768529 kernel: ena 0000:00:05.0: ENA device version: 0.10 Apr 29 23:58:38.768988 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Apr 29 23:58:38.769249 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:6c:4e:1d:94:dd Apr 29 23:58:38.769488 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Apr 29 23:58:38.769526 kernel: nvme nvme0: pci function 0000:00:04.0 Apr 29 23:58:38.773033 kernel: nvme nvme0: 2/0/0 default/read/poll queues Apr 29 23:58:38.726456 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 29 23:58:38.726744 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:58:38.729455 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:58:38.731715 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 29 23:58:38.731982 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:38.734322 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:58:38.766312 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:58:38.798581 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 29 23:58:38.798658 kernel: GPT:9289727 != 16777215 Apr 29 23:58:38.798684 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 29 23:58:38.798781 kernel: GPT:9289727 != 16777215 Apr 29 23:58:38.800575 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 29 23:58:38.801687 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:58:38.811101 (udev-worker)[527]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:38.817954 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:38.831472 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 29 23:58:38.899722 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:58:38.908638 kernel: BTRFS: device fsid 9647859b-527c-478f-8aa1-9dfa3fa871e3 devid 1 transid 43 /dev/nvme0n1p3 scanned by (udev-worker) (523) Apr 29 23:58:38.952595 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by (udev-worker) (540) Apr 29 23:58:39.025411 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Apr 29 23:58:39.031761 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Apr 29 23:58:39.052864 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Apr 29 23:58:39.080549 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Apr 29 23:58:39.108707 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 29 23:58:39.120883 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 29 23:58:39.138637 disk-uuid[660]: Primary Header is updated. Apr 29 23:58:39.138637 disk-uuid[660]: Secondary Entries is updated. Apr 29 23:58:39.138637 disk-uuid[660]: Secondary Header is updated. Apr 29 23:58:39.148662 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:58:40.167583 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Apr 29 23:58:40.168339 disk-uuid[661]: The operation has completed successfully. Apr 29 23:58:40.372679 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 29 23:58:40.373013 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 29 23:58:40.424853 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 29 23:58:40.438230 sh[922]: Success Apr 29 23:58:40.465605 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 29 23:58:40.590126 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 29 23:58:40.611772 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 29 23:58:40.625120 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 29 23:58:40.657268 kernel: BTRFS info (device dm-0): first mount of filesystem 9647859b-527c-478f-8aa1-9dfa3fa871e3 Apr 29 23:58:40.657353 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:40.657393 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 29 23:58:40.660345 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 29 23:58:40.660415 kernel: BTRFS info (device dm-0): using free space tree Apr 29 23:58:40.690601 kernel: BTRFS info (device dm-0): enabling ssd optimizations Apr 29 23:58:40.710064 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 29 23:58:40.715158 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 29 23:58:40.725968 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 29 23:58:40.736083 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 29 23:58:40.781284 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:40.781363 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:40.782871 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:58:40.791664 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:58:40.815251 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 29 23:58:40.817604 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:40.829514 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 29 23:58:40.843100 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 29 23:58:40.953651 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 29 23:58:40.973128 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 29 23:58:41.036459 systemd-networkd[1117]: lo: Link UP Apr 29 23:58:41.037204 systemd-networkd[1117]: lo: Gained carrier Apr 29 23:58:41.042883 systemd-networkd[1117]: Enumeration completed Apr 29 23:58:41.044468 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 29 23:58:41.045428 systemd-networkd[1117]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:41.045436 systemd-networkd[1117]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 29 23:58:41.051318 systemd[1]: Reached target network.target - Network. Apr 29 23:58:41.054577 systemd-networkd[1117]: eth0: Link UP Apr 29 23:58:41.054592 systemd-networkd[1117]: eth0: Gained carrier Apr 29 23:58:41.054617 systemd-networkd[1117]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:41.075719 systemd-networkd[1117]: eth0: DHCPv4 address 172.31.23.46/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 29 23:58:41.084826 ignition[1043]: Ignition 2.20.0 Apr 29 23:58:41.085467 ignition[1043]: Stage: fetch-offline Apr 29 23:58:41.085963 ignition[1043]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:41.085986 ignition[1043]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:41.086456 ignition[1043]: Ignition finished successfully Apr 29 23:58:41.096840 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 29 23:58:41.107861 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 29 23:58:41.144941 ignition[1127]: Ignition 2.20.0 Apr 29 23:58:41.144973 ignition[1127]: Stage: fetch Apr 29 23:58:41.146220 ignition[1127]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:41.146249 ignition[1127]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:41.146445 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:41.159270 ignition[1127]: PUT result: OK Apr 29 23:58:41.162621 ignition[1127]: parsed url from cmdline: "" Apr 29 23:58:41.162638 ignition[1127]: no config URL provided Apr 29 23:58:41.162654 ignition[1127]: reading system config file "/usr/lib/ignition/user.ign" Apr 29 23:58:41.162679 ignition[1127]: no config at "/usr/lib/ignition/user.ign" Apr 29 23:58:41.162713 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:41.167996 ignition[1127]: PUT result: OK Apr 29 23:58:41.168073 ignition[1127]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Apr 29 23:58:41.173050 ignition[1127]: GET result: OK Apr 29 23:58:41.175046 ignition[1127]: parsing config with SHA512: c6e52d934eda9f91547ce65b8d61fda91726c23bc4099c7ed83f05118c742d2c692532ce296824dd0e6df01459ee5dba5999c963d891f6a3d054df7e751471e7 Apr 29 23:58:41.181950 unknown[1127]: fetched base config from "system" Apr 29 23:58:41.182379 ignition[1127]: fetch: fetch complete Apr 29 23:58:41.181977 unknown[1127]: fetched base config from "system" Apr 29 23:58:41.182390 ignition[1127]: fetch: fetch passed Apr 29 23:58:41.181991 unknown[1127]: fetched user config from "aws" Apr 29 23:58:41.182478 ignition[1127]: Ignition finished successfully Apr 29 23:58:41.187118 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 29 23:58:41.200862 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 29 23:58:41.241181 ignition[1133]: Ignition 2.20.0 Apr 29 23:58:41.241210 ignition[1133]: Stage: kargs Apr 29 23:58:41.242983 ignition[1133]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:41.243011 ignition[1133]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:41.243245 ignition[1133]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:41.250659 ignition[1133]: PUT result: OK Apr 29 23:58:41.255874 ignition[1133]: kargs: kargs passed Apr 29 23:58:41.256228 ignition[1133]: Ignition finished successfully Apr 29 23:58:41.262622 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 29 23:58:41.276095 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 29 23:58:41.300822 ignition[1139]: Ignition 2.20.0 Apr 29 23:58:41.301318 ignition[1139]: Stage: disks Apr 29 23:58:41.301966 ignition[1139]: no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:41.301990 ignition[1139]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:41.302168 ignition[1139]: PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:41.304810 ignition[1139]: PUT result: OK Apr 29 23:58:41.313984 ignition[1139]: disks: disks passed Apr 29 23:58:41.314088 ignition[1139]: Ignition finished successfully Apr 29 23:58:41.320633 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 29 23:58:41.324319 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 29 23:58:41.326758 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 29 23:58:41.330918 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 29 23:58:41.332826 systemd[1]: Reached target sysinit.target - System Initialization. Apr 29 23:58:41.334791 systemd[1]: Reached target basic.target - Basic System. Apr 29 23:58:41.354162 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 29 23:58:41.418680 systemd-fsck[1147]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 29 23:58:41.426616 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 29 23:58:41.447942 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 29 23:58:41.547599 kernel: EXT4-fs (nvme0n1p9): mounted filesystem cd2ccabc-5b27-4350-bc86-21c9a8411827 r/w with ordered data mode. Quota mode: none. Apr 29 23:58:41.548676 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 29 23:58:41.549654 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 29 23:58:41.570752 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 29 23:58:41.577768 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 29 23:58:41.582094 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 29 23:58:41.591020 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:41.595267 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 29 23:58:41.614351 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1166) Apr 29 23:58:41.616870 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 29 23:58:41.625555 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:41.625624 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:41.625650 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:58:41.637082 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 29 23:58:41.645584 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:58:41.648604 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 29 23:58:41.732821 initrd-setup-root[1190]: cut: /sysroot/etc/passwd: No such file or directory Apr 29 23:58:41.741917 initrd-setup-root[1197]: cut: /sysroot/etc/group: No such file or directory Apr 29 23:58:41.750897 initrd-setup-root[1204]: cut: /sysroot/etc/shadow: No such file or directory Apr 29 23:58:41.760315 initrd-setup-root[1211]: cut: /sysroot/etc/gshadow: No such file or directory Apr 29 23:58:41.956351 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 29 23:58:41.969891 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 29 23:58:41.978979 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 29 23:58:41.998194 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 29 23:58:42.002601 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:42.046689 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 29 23:58:42.059607 ignition[1278]: INFO : Ignition 2.20.0 Apr 29 23:58:42.061517 ignition[1278]: INFO : Stage: mount Apr 29 23:58:42.063513 ignition[1278]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:42.063513 ignition[1278]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:42.067798 ignition[1278]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:42.071132 ignition[1278]: INFO : PUT result: OK Apr 29 23:58:42.075316 ignition[1278]: INFO : mount: mount passed Apr 29 23:58:42.077653 ignition[1278]: INFO : Ignition finished successfully Apr 29 23:58:42.079877 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 29 23:58:42.090831 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 29 23:58:42.124881 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 29 23:58:42.162591 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/nvme0n1p6 scanned by mount (1291) Apr 29 23:58:42.166487 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 1a221b5e-9ac2-4c84-b127-2e52009cde8a Apr 29 23:58:42.166594 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Apr 29 23:58:42.166624 kernel: BTRFS info (device nvme0n1p6): using free space tree Apr 29 23:58:42.173591 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Apr 29 23:58:42.177249 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 29 23:58:42.223471 ignition[1308]: INFO : Ignition 2.20.0 Apr 29 23:58:42.223471 ignition[1308]: INFO : Stage: files Apr 29 23:58:42.227009 ignition[1308]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:42.227009 ignition[1308]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:42.227009 ignition[1308]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:42.233983 ignition[1308]: INFO : PUT result: OK Apr 29 23:58:42.240427 ignition[1308]: DEBUG : files: compiled without relabeling support, skipping Apr 29 23:58:42.244642 ignition[1308]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 29 23:58:42.244642 ignition[1308]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 29 23:58:42.255778 ignition[1308]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 29 23:58:42.258937 ignition[1308]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 29 23:58:42.261916 unknown[1308]: wrote ssh authorized keys file for user: core Apr 29 23:58:42.264484 ignition[1308]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 29 23:58:42.269320 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 29 23:58:42.273037 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 29 23:58:42.273037 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 29 23:58:42.280734 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 29 23:58:42.280734 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 29 23:58:42.280734 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 29 23:58:42.280734 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 29 23:58:42.280734 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-arm64.raw: attempt #1 Apr 29 23:58:42.466763 systemd-networkd[1117]: eth0: Gained IPv6LL Apr 29 23:58:42.771960 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 29 23:58:43.225407 ignition[1308]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" Apr 29 23:58:43.229841 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 29 23:58:43.229841 ignition[1308]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 29 23:58:43.229841 ignition[1308]: INFO : files: files passed Apr 29 23:58:43.229841 ignition[1308]: INFO : Ignition finished successfully Apr 29 23:58:43.237314 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 29 23:58:43.254867 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 29 23:58:43.260806 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 29 23:58:43.281148 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 29 23:58:43.282030 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 29 23:58:43.308000 initrd-setup-root-after-ignition[1337]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:43.308000 initrd-setup-root-after-ignition[1337]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:43.315059 initrd-setup-root-after-ignition[1341]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 29 23:58:43.320481 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 29 23:58:43.324638 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 29 23:58:43.344521 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 29 23:58:43.398405 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 29 23:58:43.398995 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 29 23:58:43.403676 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 29 23:58:43.410408 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 29 23:58:43.412742 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 29 23:58:43.425992 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 29 23:58:43.465520 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 29 23:58:43.476863 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 29 23:58:43.513201 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 29 23:58:43.515112 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 29 23:58:43.521977 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:58:43.526216 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:58:43.528584 systemd[1]: Stopped target timers.target - Timer Units. Apr 29 23:58:43.534443 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 29 23:58:43.534581 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 29 23:58:43.537486 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 29 23:58:43.543319 systemd[1]: Stopped target basic.target - Basic System. Apr 29 23:58:43.546771 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 29 23:58:43.550351 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 29 23:58:43.554436 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 29 23:58:43.558581 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 29 23:58:43.562422 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 29 23:58:43.566433 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 29 23:58:43.570506 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 29 23:58:43.574128 systemd[1]: Stopped target swap.target - Swaps. Apr 29 23:58:43.577420 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 29 23:58:43.577615 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 29 23:58:43.584621 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:58:43.586644 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:58:43.588845 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 29 23:58:43.594925 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:58:43.597262 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 29 23:58:43.597370 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 29 23:58:43.600220 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 29 23:58:43.600306 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 29 23:58:43.611297 systemd[1]: ignition-files.service: Deactivated successfully. Apr 29 23:58:43.611386 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 29 23:58:43.621724 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 29 23:58:43.627003 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 29 23:58:43.631046 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 29 23:58:43.631185 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:58:43.639831 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 29 23:58:43.639955 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 29 23:58:43.686036 ignition[1362]: INFO : Ignition 2.20.0 Apr 29 23:58:43.686036 ignition[1362]: INFO : Stage: umount Apr 29 23:58:43.690198 ignition[1362]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 29 23:58:43.690198 ignition[1362]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Apr 29 23:58:43.690198 ignition[1362]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Apr 29 23:58:43.695590 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 29 23:58:43.702209 ignition[1362]: INFO : PUT result: OK Apr 29 23:58:43.706587 ignition[1362]: INFO : umount: umount passed Apr 29 23:58:43.708354 ignition[1362]: INFO : Ignition finished successfully Apr 29 23:58:43.712151 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 29 23:58:43.714408 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 29 23:58:43.721350 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 29 23:58:43.723363 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 29 23:58:43.727568 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 29 23:58:43.728496 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 29 23:58:43.733365 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 29 23:58:43.733456 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 29 23:58:43.735878 systemd[1]: Stopped target network.target - Network. Apr 29 23:58:43.739149 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 29 23:58:43.739838 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 29 23:58:43.743281 systemd[1]: Stopped target paths.target - Path Units. Apr 29 23:58:43.752205 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 29 23:58:43.756086 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:58:43.762222 systemd[1]: Stopped target slices.target - Slice Units. Apr 29 23:58:43.766254 systemd[1]: Stopped target sockets.target - Socket Units. Apr 29 23:58:43.768294 systemd[1]: iscsid.socket: Deactivated successfully. Apr 29 23:58:43.768387 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 29 23:58:43.771684 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 29 23:58:43.771779 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 29 23:58:43.774609 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 29 23:58:43.774726 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 29 23:58:43.776875 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 29 23:58:43.776986 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 29 23:58:43.779985 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 29 23:58:43.785276 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 29 23:58:43.791923 systemd-networkd[1117]: eth0: DHCPv6 lease lost Apr 29 23:58:43.798553 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 29 23:58:43.806068 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 29 23:58:43.825410 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 29 23:58:43.828729 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 29 23:58:43.845513 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 29 23:58:43.846366 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:58:43.858749 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 29 23:58:43.863330 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 29 23:58:43.863461 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 29 23:58:43.873276 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 29 23:58:43.873384 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:58:43.875527 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 29 23:58:43.875632 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 29 23:58:43.879479 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 29 23:58:43.879575 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:58:43.906622 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:58:43.910350 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 29 23:58:43.910570 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 29 23:58:43.931236 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 29 23:58:43.931436 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 29 23:58:43.939197 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 29 23:58:43.943430 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:58:43.948949 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 29 23:58:43.949049 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 29 23:58:43.955609 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 29 23:58:43.955700 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:58:43.959861 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 29 23:58:43.960020 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 29 23:58:43.967731 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 29 23:58:43.967828 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 29 23:58:43.970153 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 29 23:58:43.970283 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 29 23:58:43.990902 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 29 23:58:43.995479 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 29 23:58:43.995653 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:58:44.006001 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Apr 29 23:58:44.006104 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:58:44.012092 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 29 23:58:44.012266 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:58:44.013317 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 29 23:58:44.013392 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:44.016182 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 29 23:58:44.016963 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 29 23:58:44.033947 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 29 23:58:44.034171 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 29 23:58:44.047730 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 29 23:58:44.062932 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 29 23:58:44.078480 systemd[1]: Switching root. Apr 29 23:58:44.117557 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Apr 29 23:58:44.117656 systemd-journald[252]: Journal stopped Apr 29 23:58:46.160826 kernel: SELinux: policy capability network_peer_controls=1 Apr 29 23:58:46.161125 kernel: SELinux: policy capability open_perms=1 Apr 29 23:58:46.161223 kernel: SELinux: policy capability extended_socket_class=1 Apr 29 23:58:46.161265 kernel: SELinux: policy capability always_check_network=0 Apr 29 23:58:46.161306 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 29 23:58:46.161346 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 29 23:58:46.161400 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 29 23:58:46.161448 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 29 23:58:46.161484 kernel: audit: type=1403 audit(1745971124.402:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 29 23:58:46.161526 systemd[1]: Successfully loaded SELinux policy in 53.392ms. Apr 29 23:58:46.161844 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 26.116ms. Apr 29 23:58:46.161896 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:46.161932 systemd[1]: Detected virtualization amazon. Apr 29 23:58:46.161964 systemd[1]: Detected architecture arm64. Apr 29 23:58:46.162004 systemd[1]: Detected first boot. Apr 29 23:58:46.162091 systemd[1]: Initializing machine ID from VM UUID. Apr 29 23:58:46.162134 zram_generator::config[1404]: No configuration found. Apr 29 23:58:46.162171 systemd[1]: Populated /etc with preset unit settings. Apr 29 23:58:46.162204 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 29 23:58:46.162293 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 29 23:58:46.162333 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 29 23:58:46.162371 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 29 23:58:46.162403 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 29 23:58:46.162434 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 29 23:58:46.162462 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 29 23:58:46.162495 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 29 23:58:46.162524 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 29 23:58:46.164641 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 29 23:58:46.164701 systemd[1]: Created slice user.slice - User and Session Slice. Apr 29 23:58:46.164738 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 29 23:58:46.164768 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 29 23:58:46.164797 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 29 23:58:46.164829 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 29 23:58:46.164860 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 29 23:58:46.164890 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 29 23:58:46.164920 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 29 23:58:46.164949 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 29 23:58:46.164980 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 29 23:58:46.165017 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 29 23:58:46.165046 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 29 23:58:46.165075 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 29 23:58:46.165106 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 29 23:58:46.165138 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 29 23:58:46.165167 systemd[1]: Reached target slices.target - Slice Units. Apr 29 23:58:46.165198 systemd[1]: Reached target swap.target - Swaps. Apr 29 23:58:46.165227 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 29 23:58:46.165260 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 29 23:58:46.165292 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 29 23:58:46.165324 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 29 23:58:46.165358 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 29 23:58:46.165389 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 29 23:58:46.165424 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 29 23:58:46.165462 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 29 23:58:46.165493 systemd[1]: Mounting media.mount - External Media Directory... Apr 29 23:58:46.165530 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 29 23:58:46.165824 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 29 23:58:46.165876 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 29 23:58:46.165920 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:46.165951 systemd[1]: Reached target machines.target - Containers. Apr 29 23:58:46.165987 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 29 23:58:46.166022 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 29 23:58:46.166055 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 29 23:58:46.166088 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 29 23:58:46.166127 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 29 23:58:46.166171 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 29 23:58:46.166205 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 29 23:58:46.166242 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 29 23:58:46.166273 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 29 23:58:46.166303 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:46.166338 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 29 23:58:46.166368 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 29 23:58:46.166401 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 29 23:58:46.166441 systemd[1]: Stopped systemd-fsck-usr.service. Apr 29 23:58:46.166474 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 29 23:58:46.166506 kernel: loop: module loaded Apr 29 23:58:46.166587 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 29 23:58:46.166646 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 29 23:58:46.166686 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 29 23:58:46.166724 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 29 23:58:46.166764 systemd[1]: verity-setup.service: Deactivated successfully. Apr 29 23:58:46.166803 systemd[1]: Stopped verity-setup.service. Apr 29 23:58:46.166849 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 29 23:58:46.166884 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 29 23:58:46.166916 systemd[1]: Mounted media.mount - External Media Directory. Apr 29 23:58:46.166945 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 29 23:58:46.166974 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 29 23:58:46.167003 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 29 23:58:46.167033 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 29 23:58:46.167066 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 29 23:58:46.167097 kernel: ACPI: bus type drm_connector registered Apr 29 23:58:46.167125 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 29 23:58:46.167154 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 29 23:58:46.167183 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 29 23:58:46.167211 kernel: fuse: init (API version 7.39) Apr 29 23:58:46.167239 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 29 23:58:46.167275 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 29 23:58:46.167305 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 29 23:58:46.167334 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 29 23:58:46.167365 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 29 23:58:46.167395 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 29 23:58:46.167432 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 29 23:58:46.167463 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 29 23:58:46.167494 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 29 23:58:46.167524 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 29 23:58:46.167600 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 29 23:58:46.167634 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 29 23:58:46.167664 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 29 23:58:46.167694 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 29 23:58:46.167790 systemd-journald[1486]: Collecting audit messages is disabled. Apr 29 23:58:46.167870 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 29 23:58:46.167904 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 29 23:58:46.167947 systemd-journald[1486]: Journal started Apr 29 23:58:46.168013 systemd-journald[1486]: Runtime Journal (/run/log/journal/ec25ef9cd893844493530ec72b52f00a) is 8.0M, max 75.3M, 67.3M free. Apr 29 23:58:45.481376 systemd[1]: Queued start job for default target multi-user.target. Apr 29 23:58:45.509052 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Apr 29 23:58:45.510061 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 29 23:58:46.184012 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 29 23:58:46.197659 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 29 23:58:46.212484 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 29 23:58:46.215607 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 29 23:58:46.226618 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 29 23:58:46.231866 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:46.244387 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 29 23:58:46.244580 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 29 23:58:46.266587 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 29 23:58:46.273941 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 29 23:58:46.297934 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 29 23:58:46.298098 systemd[1]: Started systemd-journald.service - Journal Service. Apr 29 23:58:46.308730 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 29 23:58:46.311472 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 29 23:58:46.314982 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 29 23:58:46.319146 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 29 23:58:46.378373 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 29 23:58:46.412183 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 29 23:58:46.429125 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 29 23:58:46.443151 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 29 23:58:46.447355 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 29 23:58:46.458011 kernel: loop0: detected capacity change from 0 to 53784 Apr 29 23:58:46.490997 systemd-journald[1486]: Time spent on flushing to /var/log/journal/ec25ef9cd893844493530ec72b52f00a is 134.296ms for 896 entries. Apr 29 23:58:46.490997 systemd-journald[1486]: System Journal (/var/log/journal/ec25ef9cd893844493530ec72b52f00a) is 8.0M, max 195.6M, 187.6M free. Apr 29 23:58:46.643346 systemd-journald[1486]: Received client request to flush runtime journal. Apr 29 23:58:46.643484 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 29 23:58:46.523814 systemd-tmpfiles[1516]: ACLs are not supported, ignoring. Apr 29 23:58:46.523844 systemd-tmpfiles[1516]: ACLs are not supported, ignoring. Apr 29 23:58:46.547649 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 29 23:58:46.559873 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 29 23:58:46.576388 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 29 23:58:46.590039 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 29 23:58:46.654706 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 29 23:58:46.661311 udevadm[1550]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 29 23:58:46.699933 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 29 23:58:46.704700 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 29 23:58:46.714363 kernel: loop1: detected capacity change from 0 to 116808 Apr 29 23:58:46.741335 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 29 23:58:46.752075 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 29 23:58:46.780613 kernel: loop2: detected capacity change from 0 to 201592 Apr 29 23:58:46.795915 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Apr 29 23:58:46.796034 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. Apr 29 23:58:46.806383 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 29 23:58:46.909100 kernel: loop3: detected capacity change from 0 to 113536 Apr 29 23:58:46.980639 kernel: loop4: detected capacity change from 0 to 53784 Apr 29 23:58:47.002708 kernel: loop5: detected capacity change from 0 to 116808 Apr 29 23:58:47.025640 kernel: loop6: detected capacity change from 0 to 201592 Apr 29 23:58:47.063619 kernel: loop7: detected capacity change from 0 to 113536 Apr 29 23:58:47.092366 (sd-merge)[1566]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Apr 29 23:58:47.094628 (sd-merge)[1566]: Merged extensions into '/usr'. Apr 29 23:58:47.105176 systemd[1]: Reloading requested from client PID 1515 ('systemd-sysext') (unit systemd-sysext.service)... Apr 29 23:58:47.105216 systemd[1]: Reloading... Apr 29 23:58:47.291707 ldconfig[1511]: /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:47.312588 zram_generator::config[1592]: No configuration found. Apr 29 23:58:47.621104 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:47.741212 systemd[1]: Reloading finished in 635 ms. Apr 29 23:58:47.785659 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 29 23:58:47.791641 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 29 23:58:47.809931 systemd[1]: Starting ensure-sysext.service... Apr 29 23:58:47.816753 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 29 23:58:47.819804 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 29 23:58:47.832962 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 29 23:58:47.846750 systemd[1]: Reloading requested from client PID 1644 ('systemctl') (unit ensure-sysext.service)... Apr 29 23:58:47.846771 systemd[1]: Reloading... Apr 29 23:58:47.909907 systemd-udevd[1647]: Using default interface naming scheme 'v255'. Apr 29 23:58:47.912663 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 29 23:58:47.913368 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 29 23:58:47.915358 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 29 23:58:47.915973 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Apr 29 23:58:47.916131 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Apr 29 23:58:47.926246 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Apr 29 23:58:47.926272 systemd-tmpfiles[1645]: Skipping /boot Apr 29 23:58:47.981107 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Apr 29 23:58:47.981139 systemd-tmpfiles[1645]: Skipping /boot Apr 29 23:58:48.067591 zram_generator::config[1673]: No configuration found. Apr 29 23:58:48.211176 (udev-worker)[1693]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:58:48.464604 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (1696) Apr 29 23:58:48.492287 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:48.638662 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 29 23:58:48.640015 systemd[1]: Reloading finished in 792 ms. Apr 29 23:58:48.671815 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 29 23:58:48.675039 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 29 23:58:48.781491 systemd[1]: Finished ensure-sysext.service. Apr 29 23:58:48.812121 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 29 23:58:48.822011 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 29 23:58:48.827013 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 29 23:58:48.830909 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 29 23:58:48.837291 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 29 23:58:48.847097 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 29 23:58:48.852886 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 29 23:58:48.855193 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 29 23:58:48.859976 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 29 23:58:48.869166 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 29 23:58:48.876851 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 29 23:58:48.878924 systemd[1]: Reached target time-set.target - System Time Set. Apr 29 23:58:48.888839 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 29 23:58:48.934146 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 29 23:58:48.975320 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 29 23:58:49.039853 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 29 23:58:49.069755 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 29 23:58:49.070257 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 29 23:58:49.090262 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 29 23:58:49.090656 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 29 23:58:49.094395 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 29 23:58:49.095073 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 29 23:58:49.098701 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 29 23:58:49.099012 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 29 23:58:49.103438 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:49.104713 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 29 23:58:49.115885 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 29 23:58:49.140882 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 29 23:58:49.150137 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Apr 29 23:58:49.161941 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 29 23:58:49.166948 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 29 23:58:49.184865 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 29 23:58:49.197217 augenrules[1883]: No rules Apr 29 23:58:49.203495 systemd[1]: audit-rules.service: Deactivated successfully. Apr 29 23:58:49.203878 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 29 23:58:49.236883 lvm[1881]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 29 23:58:49.250348 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 29 23:58:49.270869 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 29 23:58:49.275640 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 29 23:58:49.297346 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 29 23:58:49.306896 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:49.316227 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 29 23:58:49.317985 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 29 23:58:49.329151 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 29 23:58:49.354505 lvm[1900]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 29 23:58:49.368961 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 29 23:58:49.393776 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 29 23:58:49.463648 systemd-networkd[1849]: lo: Link UP Apr 29 23:58:49.463681 systemd-networkd[1849]: lo: Gained carrier Apr 29 23:58:49.468217 systemd-networkd[1849]: Enumeration completed Apr 29 23:58:49.468879 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 29 23:58:49.472166 systemd-networkd[1849]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:49.472193 systemd-networkd[1849]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 29 23:58:49.476050 systemd-networkd[1849]: eth0: Link UP Apr 29 23:58:49.476680 systemd-networkd[1849]: eth0: Gained carrier Apr 29 23:58:49.476839 systemd-networkd[1849]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 29 23:58:49.480180 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 29 23:58:49.490772 systemd-networkd[1849]: eth0: DHCPv4 address 172.31.23.46/20, gateway 172.31.16.1 acquired from 172.31.16.1 Apr 29 23:58:49.504159 systemd-resolved[1852]: Positive Trust Anchors: Apr 29 23:58:49.504230 systemd-resolved[1852]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 29 23:58:49.504293 systemd-resolved[1852]: 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:49.513098 systemd-resolved[1852]: Defaulting to hostname 'linux'. Apr 29 23:58:49.516504 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 29 23:58:49.518958 systemd[1]: Reached target network.target - Network. Apr 29 23:58:49.520821 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 29 23:58:49.523223 systemd[1]: Reached target sysinit.target - System Initialization. Apr 29 23:58:49.525394 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 29 23:58:49.527784 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:49.530445 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 29 23:58:49.532747 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 29 23:58:49.535213 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 29 23:58:49.537575 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:49.537631 systemd[1]: Reached target paths.target - Path Units. Apr 29 23:58:49.539398 systemd[1]: Reached target timers.target - Timer Units. Apr 29 23:58:49.543338 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 29 23:58:49.548073 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 29 23:58:49.557020 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 29 23:58:49.560379 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 29 23:58:49.562876 systemd[1]: Reached target sockets.target - Socket Units. Apr 29 23:58:49.564946 systemd[1]: Reached target basic.target - Basic System. Apr 29 23:58:49.566990 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 29 23:58:49.567042 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 29 23:58:49.575722 systemd[1]: Starting containerd.service - containerd container runtime... Apr 29 23:58:49.591052 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 29 23:58:49.599925 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 29 23:58:49.608347 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 29 23:58:49.625925 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 29 23:58:49.635307 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:49.641933 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 29 23:58:49.657947 jq[1912]: false Apr 29 23:58:49.655883 systemd[1]: Started ntpd.service - Network Time Service. Apr 29 23:58:49.660393 systemd[1]: Starting setup-oem.service - Setup OEM... Apr 29 23:58:49.666058 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 29 23:58:49.674056 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 29 23:58:49.681989 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 29 23:58:49.683172 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 29 23:58:49.686026 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 29 23:58:49.691339 systemd[1]: Starting update-engine.service - Update Engine... Apr 29 23:58:49.694827 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 29 23:58:49.701301 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 29 23:58:49.703765 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 29 23:58:49.805824 jq[1923]: true Apr 29 23:58:49.813664 (ntainerd)[1935]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 29 23:58:49.823363 ntpd[1917]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 21:35:04 UTC 2025 (1): Starting Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: ntpd 4.2.8p17@1.4004-o Tue Apr 29 21:35:04 UTC 2025 (1): Starting Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: ---------------------------------------------------- Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: ntp-4 is maintained by Network Time Foundation, Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: corporation. Support and training for ntp-4 are Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: available at https://www.nwtime.org/support Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: ---------------------------------------------------- Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: proto: precision = 0.096 usec (-23) Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: basedate set to 2025-04-17 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: gps base set to 2025-04-20 (week 2363) Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listen normally on 3 eth0 172.31.23.46:123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listen normally on 4 lo [::1]:123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: bind(21) AF_INET6 fe80::46c:4eff:fe1d:94dd%2#123 flags 0x11 failed: Cannot assign requested address Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: unable to create socket on eth0 (5) for fe80::46c:4eff:fe1d:94dd%2#123 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: failed to init interface for address fe80::46c:4eff:fe1d:94dd%2 Apr 29 23:58:49.863707 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: Listening on routing socket on fd #21 for interface updates Apr 29 23:58:49.853131 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 29 23:58:49.823428 ntpd[1917]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Apr 29 23:58:49.823461 ntpd[1917]: ---------------------------------------------------- Apr 29 23:58:49.823480 ntpd[1917]: ntp-4 is maintained by Network Time Foundation, Apr 29 23:58:49.823499 ntpd[1917]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Apr 29 23:58:49.823521 ntpd[1917]: corporation. Support and training for ntp-4 are Apr 29 23:58:49.823558 ntpd[1917]: available at https://www.nwtime.org/support Apr 29 23:58:49.823581 ntpd[1917]: ---------------------------------------------------- Apr 29 23:58:49.833957 ntpd[1917]: proto: precision = 0.096 usec (-23) Apr 29 23:58:49.834357 ntpd[1917]: basedate set to 2025-04-17 Apr 29 23:58:49.834380 ntpd[1917]: gps base set to 2025-04-20 (week 2363) Apr 29 23:58:49.846741 dbus-daemon[1911]: [system] SELinux support is enabled Apr 29 23:58:49.865743 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 29 23:58:49.846836 ntpd[1917]: Listen and drop on 0 v6wildcard [::]:123 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found loop4 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found loop5 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found loop6 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found loop7 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p1 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p2 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p3 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found usr Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p4 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p6 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p7 Apr 29 23:58:49.900040 extend-filesystems[1913]: Found nvme0n1p9 Apr 29 23:58:49.900040 extend-filesystems[1913]: Checking size of /dev/nvme0n1p9 Apr 29 23:58:49.866190 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 29 23:58:49.846904 ntpd[1917]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 29 23:58:49.978858 update_engine[1922]: I20250429 23:58:49.954949 1922 main.cc:92] Flatcar Update Engine starting Apr 29 23:58:49.979263 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:49.979263 ntpd[1917]: 29 Apr 23:58:49 ntpd[1917]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:49.874585 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:49.855777 ntpd[1917]: Listen normally on 2 lo 127.0.0.1:123 Apr 29 23:58:49.981964 update_engine[1922]: I20250429 23:58:49.980885 1922 update_check_scheduler.cc:74] Next update check in 4m51s Apr 29 23:58:49.874679 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 29 23:58:49.855848 ntpd[1917]: Listen normally on 3 eth0 172.31.23.46:123 Apr 29 23:58:49.881931 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:49.855914 ntpd[1917]: Listen normally on 4 lo [::1]:123 Apr 29 23:58:49.881987 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 29 23:58:49.855993 ntpd[1917]: bind(21) AF_INET6 fe80::46c:4eff:fe1d:94dd%2#123 flags 0x11 failed: Cannot assign requested address Apr 29 23:58:49.910761 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 29 23:58:49.856031 ntpd[1917]: unable to create socket on eth0 (5) for fe80::46c:4eff:fe1d:94dd%2#123 Apr 29 23:58:49.926672 systemd[1]: motdgen.service: Deactivated successfully. Apr 29 23:58:49.856057 ntpd[1917]: failed to init interface for address fe80::46c:4eff:fe1d:94dd%2 Apr 29 23:58:49.927010 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 29 23:58:49.856110 ntpd[1917]: Listening on routing socket on fd #21 for interface updates Apr 29 23:58:49.980491 systemd[1]: Started update-engine.service - Update Engine. Apr 29 23:58:49.873732 dbus-daemon[1911]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1849 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Apr 29 23:58:49.881072 dbus-daemon[1911]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 29 23:58:49.905810 ntpd[1917]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:49.905856 ntpd[1917]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 29 23:58:50.008820 jq[1943]: true Apr 29 23:58:50.007869 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 29 23:58:50.031595 extend-filesystems[1913]: Resized partition /dev/nvme0n1p9 Apr 29 23:58:50.046585 extend-filesystems[1958]: resize2fs 1.47.1 (20-May-2024) Apr 29 23:58:50.058384 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Apr 29 23:58:50.140354 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Apr 29 23:58:50.159467 extend-filesystems[1958]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Apr 29 23:58:50.159467 extend-filesystems[1958]: old_desc_blocks = 1, new_desc_blocks = 1 Apr 29 23:58:50.159467 extend-filesystems[1958]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Apr 29 23:58:50.180596 extend-filesystems[1913]: Resized filesystem in /dev/nvme0n1p9 Apr 29 23:58:50.170879 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 29 23:58:50.172812 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 29 23:58:50.193036 systemd[1]: Finished setup-oem.service - Setup OEM. Apr 29 23:58:50.245081 systemd-logind[1921]: Watching system buttons on /dev/input/event0 (Power Button) Apr 29 23:58:50.245138 systemd-logind[1921]: Watching system buttons on /dev/input/event1 (Sleep Button) Apr 29 23:58:50.245501 systemd-logind[1921]: New seat seat0. Apr 29 23:58:50.249051 systemd[1]: Started systemd-logind.service - User Login Management. Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.258 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.258 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.260 INFO Fetch successful Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.260 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.260 INFO Fetch successful Apr 29 23:58:50.260875 coreos-metadata[1910]: Apr 29 23:58:50.260 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Apr 29 23:58:50.265739 coreos-metadata[1910]: Apr 29 23:58:50.262 INFO Fetch successful Apr 29 23:58:50.265739 coreos-metadata[1910]: Apr 29 23:58:50.263 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Apr 29 23:58:50.265739 coreos-metadata[1910]: Apr 29 23:58:50.264 INFO Fetch successful Apr 29 23:58:50.265739 coreos-metadata[1910]: Apr 29 23:58:50.264 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetch failed with 404: resource not found Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetch successful Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetch successful Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetch successful Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.270 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.271 INFO Fetch successful Apr 29 23:58:50.274347 coreos-metadata[1910]: Apr 29 23:58:50.271 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Apr 29 23:58:50.282562 coreos-metadata[1910]: Apr 29 23:58:50.275 INFO Fetch successful Apr 29 23:58:50.323736 bash[1984]: Updated "/home/core/.ssh/authorized_keys" Apr 29 23:58:50.408230 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (1679) Apr 29 23:58:50.408699 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 29 23:58:50.438285 systemd[1]: Starting sshkeys.service... Apr 29 23:58:50.495134 dbus-daemon[1911]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 29 23:58:50.499940 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 29 23:58:50.511829 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 29 23:58:50.517260 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 29 23:58:50.518631 dbus-daemon[1911]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1947 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Apr 29 23:58:50.529505 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 29 23:58:50.579579 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 29 23:58:50.588362 systemd[1]: Starting polkit.service - Authorization Manager... Apr 29 23:58:50.670962 polkitd[2032]: Started polkitd version 121 Apr 29 23:58:50.681073 locksmithd[1953]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 29 23:58:50.688010 polkitd[2032]: Loading rules from directory /etc/polkit-1/rules.d Apr 29 23:58:50.688173 polkitd[2032]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 29 23:58:50.692087 polkitd[2032]: Finished loading, compiling and executing 2 rules Apr 29 23:58:50.695810 dbus-daemon[1911]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 29 23:58:50.697729 systemd[1]: Started polkit.service - Authorization Manager. Apr 29 23:58:50.698888 polkitd[2032]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 29 23:58:50.758152 systemd-hostnamed[1947]: Hostname set to (transient) Apr 29 23:58:50.760308 systemd-resolved[1852]: System hostname changed to 'ip-172-31-23-46'. Apr 29 23:58:50.785602 containerd[1935]: time="2025-04-29T23:58:50.781744414Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 29 23:58:50.786722 systemd-networkd[1849]: eth0: Gained IPv6LL Apr 29 23:58:50.794603 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 29 23:58:50.801688 systemd[1]: Reached target network-online.target - Network is Online. Apr 29 23:58:50.828348 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Apr 29 23:58:50.850301 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:58:50.863478 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 29 23:58:50.962224 coreos-metadata[2021]: Apr 29 23:58:50.961 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Apr 29 23:58:50.979069 coreos-metadata[2021]: Apr 29 23:58:50.973 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Apr 29 23:58:50.979069 coreos-metadata[2021]: Apr 29 23:58:50.973 INFO Fetch successful Apr 29 23:58:50.979069 coreos-metadata[2021]: Apr 29 23:58:50.973 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 29 23:58:50.979069 coreos-metadata[2021]: Apr 29 23:58:50.974 INFO Fetch successful Apr 29 23:58:50.979399 unknown[2021]: wrote ssh authorized keys file for user: core Apr 29 23:58:51.046635 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 29 23:58:51.078357 amazon-ssm-agent[2085]: Initializing new seelog logger Apr 29 23:58:51.079968 amazon-ssm-agent[2085]: New Seelog Logger Creation Complete Apr 29 23:58:51.079968 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.079968 amazon-ssm-agent[2085]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.079968 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 processing appconfig overrides Apr 29 23:58:51.083925 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.084400 amazon-ssm-agent[2085]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.087613 update-ssh-keys[2110]: Updated "/home/core/.ssh/authorized_keys" Apr 29 23:58:51.088019 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 processing appconfig overrides Apr 29 23:58:51.088019 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.088019 amazon-ssm-agent[2085]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.088019 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 processing appconfig overrides Apr 29 23:58:51.089347 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO Proxy environment variables: Apr 29 23:58:51.090657 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 29 23:58:51.099556 systemd[1]: Finished sshkeys.service. Apr 29 23:58:51.107567 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.107567 amazon-ssm-agent[2085]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Apr 29 23:58:51.107567 amazon-ssm-agent[2085]: 2025/04/29 23:58:51 processing appconfig overrides Apr 29 23:58:51.138575 containerd[1935]: time="2025-04-29T23:58:51.136602296Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.146372 containerd[1935]: time="2025-04-29T23:58:51.146261420Z" 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:51.146372 containerd[1935]: time="2025-04-29T23:58:51.146331800Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 29 23:58:51.146372 containerd[1935]: time="2025-04-29T23:58:51.146368808Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 29 23:58:51.152611 containerd[1935]: time="2025-04-29T23:58:51.151842356Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 29 23:58:51.152611 containerd[1935]: time="2025-04-29T23:58:51.151910852Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152042852Z" 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:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152071676Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152384312Z" 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:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152415800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152445704Z" 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:51.152611 containerd[1935]: time="2025-04-29T23:58:51.152469128Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.161281 containerd[1935]: time="2025-04-29T23:58:51.160173944Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.161281 containerd[1935]: time="2025-04-29T23:58:51.160730036Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 29 23:58:51.161281 containerd[1935]: time="2025-04-29T23:58:51.160985684Z" 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:51.161281 containerd[1935]: time="2025-04-29T23:58:51.161018324Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 29 23:58:51.161281 containerd[1935]: time="2025-04-29T23:58:51.161203304Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 29 23:58:51.162245 containerd[1935]: time="2025-04-29T23:58:51.161301092Z" level=info msg="metadata content store policy set" policy=shared Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.173850956Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.173987084Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.174040376Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.174077948Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.174114812Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.174385328Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.174839504Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175082600Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175127720Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175166444Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175200584Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175231064Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175259960Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.175842 containerd[1935]: time="2025-04-29T23:58:51.175294388Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175333040Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175367432Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175397696Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175431296Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175480208Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.176572 containerd[1935]: time="2025-04-29T23:58:51.175514156Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.182568 containerd[1935]: time="2025-04-29T23:58:51.179463968Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.182568 containerd[1935]: time="2025-04-29T23:58:51.179593892Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.182568 containerd[1935]: time="2025-04-29T23:58:51.179632400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.182568 containerd[1935]: time="2025-04-29T23:58:51.179697296Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.182568 containerd[1935]: time="2025-04-29T23:58:51.179730884Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.182998244Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183088004Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183127520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183157124Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183186704Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183219536Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183252236Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183299852Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183331016Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183357560Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183514628Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 29 23:58:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183579128Z" 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:51.184601 containerd[1935]: time="2025-04-29T23:58:51.183614252Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 29 23:58:51.185190 containerd[1935]: time="2025-04-29T23:58:51.183651692Z" 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:51.185190 containerd[1935]: time="2025-04-29T23:58:51.183679736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.185190 containerd[1935]: time="2025-04-29T23:58:51.183713156Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 29 23:58:51.185190 containerd[1935]: time="2025-04-29T23:58:51.183737348Z" level=info msg="NRI interface is disabled by configuration." Apr 29 23:58:51.185190 containerd[1935]: time="2025-04-29T23:58:51.183762500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 29 23:58:51.185427 containerd[1935]: time="2025-04-29T23:58:51.184406108Z" 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:51.185427 containerd[1935]: time="2025-04-29T23:58:51.184492532Z" level=info msg="Connect containerd service" Apr 29 23:58:51.185839 containerd[1935]: time="2025-04-29T23:58:51.185569172Z" level=info msg="using legacy CRI server" Apr 29 23:58:51.185839 containerd[1935]: time="2025-04-29T23:58:51.185625020Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 29 23:58:51.187638 containerd[1935]: time="2025-04-29T23:58:51.185981732Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.189324848Z" 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:51.195357 containerd[1935]: time="2025-04-29T23:58:51.189872468Z" level=info msg="Start subscribing containerd event" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.189998264Z" level=info msg="Start recovering state" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190033652Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190132724Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190158092Z" level=info msg="Start event monitor" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190184228Z" level=info msg="Start snapshots syncer" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190213808Z" level=info msg="Start cni network conf syncer for default" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190242632Z" level=info msg="Start streaming server" Apr 29 23:58:51.195357 containerd[1935]: time="2025-04-29T23:58:51.190420160Z" level=info msg="containerd successfully booted in 0.415405s" Apr 29 23:58:51.190675 systemd[1]: Started containerd.service - containerd container runtime. Apr 29 23:58:51.197591 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO https_proxy: Apr 29 23:58:51.297339 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO http_proxy: Apr 29 23:58:51.397001 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO no_proxy: Apr 29 23:58:51.495606 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO Checking if agent identity type OnPrem can be assumed Apr 29 23:58:51.594057 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO Checking if agent identity type EC2 can be assumed Apr 29 23:58:51.612707 sshd_keygen[1942]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 29 23:58:51.670710 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 29 23:58:51.684208 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 29 23:58:51.694570 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO Agent will take identity from EC2 Apr 29 23:58:51.721913 systemd[1]: issuegen.service: Deactivated successfully. Apr 29 23:58:51.726005 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 29 23:58:51.736263 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 29 23:58:51.768780 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 29 23:58:51.786729 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 29 23:58:51.795918 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:51.792892 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 29 23:58:51.795439 systemd[1]: Reached target getty.target - Login Prompts. Apr 29 23:58:51.812454 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 29 23:58:51.825589 systemd[1]: Started sshd@0-172.31.23.46:22-139.178.89.65:38756.service - OpenSSH per-connection server daemon (139.178.89.65:38756). Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] using named pipe channel for IPC Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] Starting Core Agent Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [amazon-ssm-agent] registrar detected. Attempting registration Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [Registrar] Starting registrar module Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [EC2Identity] EC2 registration was successful. Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [CredentialRefresher] credentialRefresher has started Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [CredentialRefresher] Starting credentials refresher loop Apr 29 23:58:51.867705 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO EC2RoleProvider Successfully connected with instance profile role credentials Apr 29 23:58:51.891851 amazon-ssm-agent[2085]: 2025-04-29 23:58:51 INFO [CredentialRefresher] Next credential rotation will be in 30.883321721533335 minutes Apr 29 23:58:52.121483 sshd[2146]: Accepted publickey for core from 139.178.89.65 port 38756 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:52.126163 sshd-session[2146]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:52.152288 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 29 23:58:52.161098 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 29 23:58:52.170656 systemd-logind[1921]: New session 1 of user core. Apr 29 23:58:52.206276 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 29 23:58:52.222240 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 29 23:58:52.247394 (systemd)[2150]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 29 23:58:52.498857 systemd[2150]: Queued start job for default target default.target. Apr 29 23:58:52.506414 systemd[2150]: Created slice app.slice - User Application Slice. Apr 29 23:58:52.506507 systemd[2150]: Reached target paths.target - Paths. Apr 29 23:58:52.507199 systemd[2150]: Reached target timers.target - Timers. Apr 29 23:58:52.510792 systemd[2150]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 29 23:58:52.550154 systemd[2150]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 29 23:58:52.550309 systemd[2150]: Reached target sockets.target - Sockets. Apr 29 23:58:52.550343 systemd[2150]: Reached target basic.target - Basic System. Apr 29 23:58:52.550430 systemd[2150]: Reached target default.target - Main User Target. Apr 29 23:58:52.550491 systemd[2150]: Startup finished in 286ms. Apr 29 23:58:52.551027 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 29 23:58:52.564897 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 29 23:58:52.793130 systemd[1]: Started sshd@1-172.31.23.46:22-139.178.89.65:38758.service - OpenSSH per-connection server daemon (139.178.89.65:38758). Apr 29 23:58:52.824163 ntpd[1917]: Listen normally on 6 eth0 [fe80::46c:4eff:fe1d:94dd%2]:123 Apr 29 23:58:52.824799 ntpd[1917]: 29 Apr 23:58:52 ntpd[1917]: Listen normally on 6 eth0 [fe80::46c:4eff:fe1d:94dd%2]:123 Apr 29 23:58:52.899502 amazon-ssm-agent[2085]: 2025-04-29 23:58:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Apr 29 23:58:52.999529 amazon-ssm-agent[2085]: 2025-04-29 23:58:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2164) started Apr 29 23:58:53.092859 sshd[2161]: Accepted publickey for core from 139.178.89.65 port 38758 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:53.099399 sshd-session[2161]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:53.100756 amazon-ssm-agent[2085]: 2025-04-29 23:58:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Apr 29 23:58:53.112821 systemd-logind[1921]: New session 2 of user core. Apr 29 23:58:53.128959 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 29 23:58:53.304597 sshd[2174]: Connection closed by 139.178.89.65 port 38758 Apr 29 23:58:53.305590 sshd-session[2161]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:53.313981 systemd[1]: sshd@1-172.31.23.46:22-139.178.89.65:38758.service: Deactivated successfully. Apr 29 23:58:53.318088 systemd[1]: session-2.scope: Deactivated successfully. Apr 29 23:58:53.320134 systemd-logind[1921]: Session 2 logged out. Waiting for processes to exit. Apr 29 23:58:53.323037 systemd-logind[1921]: Removed session 2. Apr 29 23:58:53.367718 systemd[1]: Started sshd@2-172.31.23.46:22-139.178.89.65:38774.service - OpenSSH per-connection server daemon (139.178.89.65:38774). Apr 29 23:58:53.373876 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:58:53.378419 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 29 23:58:53.386738 systemd[1]: Startup finished in 1.165s (kernel) + 7.597s (initrd) + 9.036s (userspace) = 17.799s. Apr 29 23:58:53.398082 (kubelet)[2184]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 29 23:58:53.676512 sshd[2183]: Accepted publickey for core from 139.178.89.65 port 38774 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:58:53.679531 sshd-session[2183]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:58:53.688325 systemd-logind[1921]: New session 3 of user core. Apr 29 23:58:53.698817 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 29 23:58:53.882947 sshd[2195]: Connection closed by 139.178.89.65 port 38774 Apr 29 23:58:53.883969 sshd-session[2183]: pam_unix(sshd:session): session closed for user core Apr 29 23:58:53.891510 systemd[1]: sshd@2-172.31.23.46:22-139.178.89.65:38774.service: Deactivated successfully. Apr 29 23:58:53.897357 systemd[1]: session-3.scope: Deactivated successfully. Apr 29 23:58:53.899454 systemd-logind[1921]: Session 3 logged out. Waiting for processes to exit. Apr 29 23:58:53.901918 systemd-logind[1921]: Removed session 3. Apr 29 23:58:54.517289 kubelet[2184]: E0429 23:58:54.517185 2184 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Apr 29 23:58:54.521846 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 29 23:58:54.522241 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 29 23:58:54.523021 systemd[1]: kubelet.service: Consumed 1.382s CPU time. Apr 29 23:58:56.518069 systemd-resolved[1852]: Clock change detected. Flushing caches. Apr 29 23:59:03.631836 systemd[1]: Started sshd@3-172.31.23.46:22-139.178.89.65:58860.service - OpenSSH per-connection server daemon (139.178.89.65:58860). Apr 29 23:59:03.902483 sshd[2202]: Accepted publickey for core from 139.178.89.65 port 58860 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:59:03.904983 sshd-session[2202]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:59:03.913394 systemd-logind[1921]: New session 4 of user core. Apr 29 23:59:03.920681 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 29 23:59:04.103763 sshd[2204]: Connection closed by 139.178.89.65 port 58860 Apr 29 23:59:04.102964 sshd-session[2202]: pam_unix(sshd:session): session closed for user core Apr 29 23:59:04.108601 systemd[1]: sshd@3-172.31.23.46:22-139.178.89.65:58860.service: Deactivated successfully. Apr 29 23:59:04.112442 systemd[1]: session-4.scope: Deactivated successfully. Apr 29 23:59:04.114017 systemd-logind[1921]: Session 4 logged out. Waiting for processes to exit. Apr 29 23:59:04.118225 systemd-logind[1921]: Removed session 4. Apr 29 23:59:04.162999 systemd[1]: Started sshd@4-172.31.23.46:22-139.178.89.65:58868.service - OpenSSH per-connection server daemon (139.178.89.65:58868). Apr 29 23:59:04.402946 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Apr 29 23:59:04.416714 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:59:04.451442 sshd[2209]: Accepted publickey for core from 139.178.89.65 port 58868 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:59:04.454847 sshd-session[2209]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:59:04.466174 systemd-logind[1921]: New session 5 of user core. Apr 29 23:59:04.479726 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 29 23:59:04.648359 sshd[2214]: Connection closed by 139.178.89.65 port 58868 Apr 29 23:59:04.650621 sshd-session[2209]: pam_unix(sshd:session): session closed for user core Apr 29 23:59:04.665069 systemd[1]: sshd@4-172.31.23.46:22-139.178.89.65:58868.service: Deactivated successfully. Apr 29 23:59:04.673790 systemd[1]: session-5.scope: Deactivated successfully. Apr 29 23:59:04.679254 systemd-logind[1921]: Session 5 logged out. Waiting for processes to exit. Apr 29 23:59:04.682235 systemd-logind[1921]: Removed session 5. Apr 29 23:59:04.707540 systemd[1]: Started sshd@5-172.31.23.46:22-139.178.89.65:58872.service - OpenSSH per-connection server daemon (139.178.89.65:58872). Apr 29 23:59:04.793636 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:59:04.808770 (kubelet)[2226]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 29 23:59:04.887836 kubelet[2226]: E0429 23:59:04.887654 2226 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Apr 29 23:59:04.895150 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 29 23:59:04.895595 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 29 23:59:04.992549 sshd[2219]: Accepted publickey for core from 139.178.89.65 port 58872 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:59:04.996024 sshd-session[2219]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:59:05.007368 systemd-logind[1921]: New session 6 of user core. Apr 29 23:59:05.015780 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 29 23:59:05.190248 sshd[2232]: Connection closed by 139.178.89.65 port 58872 Apr 29 23:59:05.191130 sshd-session[2219]: pam_unix(sshd:session): session closed for user core Apr 29 23:59:05.195901 systemd[1]: sshd@5-172.31.23.46:22-139.178.89.65:58872.service: Deactivated successfully. Apr 29 23:59:05.198696 systemd[1]: session-6.scope: Deactivated successfully. Apr 29 23:59:05.201768 systemd-logind[1921]: Session 6 logged out. Waiting for processes to exit. Apr 29 23:59:05.204049 systemd-logind[1921]: Removed session 6. Apr 29 23:59:05.248096 systemd[1]: Started sshd@6-172.31.23.46:22-139.178.89.65:58874.service - OpenSSH per-connection server daemon (139.178.89.65:58874). Apr 29 23:59:05.528282 sshd[2237]: Accepted publickey for core from 139.178.89.65 port 58874 ssh2: RSA SHA256:rMShF5lv1krIneOW1i/lrlpFaOnnFxuzLqGDXTZQrzA Apr 29 23:59:05.530669 sshd-session[2237]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 29 23:59:05.537770 systemd-logind[1921]: New session 7 of user core. Apr 29 23:59:05.546533 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 29 23:59:05.705644 sudo[2240]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 29 23:59:05.706378 sudo[2240]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 29 23:59:06.698542 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:59:06.718089 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:59:06.781746 systemd[1]: Reloading requested from client PID 2272 ('systemctl') (unit session-7.scope)... Apr 29 23:59:06.781775 systemd[1]: Reloading... Apr 29 23:59:07.052358 zram_generator::config[2321]: No configuration found. Apr 29 23:59:07.301632 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:59:07.488349 systemd[1]: Reloading finished in 705 ms. Apr 29 23:59:07.587858 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Apr 29 23:59:07.588052 systemd[1]: kubelet.service: Failed with result 'signal'. Apr 29 23:59:07.588535 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:59:07.596034 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 29 23:59:07.942775 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 29 23:59:07.953374 (kubelet)[2375]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 29 23:59:08.046152 kubelet[2375]: 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:59:08.046152 kubelet[2375]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Apr 29 23:59:08.046152 kubelet[2375]: 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:59:08.046908 kubelet[2375]: I0429 23:59:08.046252 2375 server.go:215] "--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:59:09.208541 kubelet[2375]: I0429 23:59:09.208475 2375 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" Apr 29 23:59:09.208541 kubelet[2375]: I0429 23:59:09.208525 2375 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 29 23:59:09.209154 kubelet[2375]: I0429 23:59:09.209006 2375 server.go:954] "Client rotation is on, will bootstrap in background" Apr 29 23:59:09.257015 kubelet[2375]: I0429 23:59:09.256281 2375 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 29 23:59:09.270417 kubelet[2375]: E0429 23:59:09.270359 2375 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Apr 29 23:59:09.270622 kubelet[2375]: I0429 23:59:09.270597 2375 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Apr 29 23:59:09.275699 kubelet[2375]: I0429 23:59:09.275655 2375 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 29 23:59:09.276529 kubelet[2375]: I0429 23:59:09.276446 2375 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 29 23:59:09.277277 kubelet[2375]: I0429 23:59:09.276896 2375 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.23.46","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":"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},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"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,"CgroupVersion":2} Apr 29 23:59:09.278243 kubelet[2375]: I0429 23:59:09.277650 2375 topology_manager.go:138] "Creating topology manager with none policy" Apr 29 23:59:09.278243 kubelet[2375]: I0429 23:59:09.277680 2375 container_manager_linux.go:304] "Creating device plugin manager" Apr 29 23:59:09.278243 kubelet[2375]: I0429 23:59:09.277928 2375 state_mem.go:36] "Initialized new in-memory state store" Apr 29 23:59:09.281818 kubelet[2375]: I0429 23:59:09.281746 2375 kubelet.go:446] "Attempting to sync node with API server" Apr 29 23:59:09.282093 kubelet[2375]: I0429 23:59:09.282063 2375 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 29 23:59:09.282219 kubelet[2375]: I0429 23:59:09.282199 2375 kubelet.go:352] "Adding apiserver pod source" Apr 29 23:59:09.282542 kubelet[2375]: I0429 23:59:09.282325 2375 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 29 23:59:09.282643 kubelet[2375]: E0429 23:59:09.282519 2375 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:09.283046 kubelet[2375]: E0429 23:59:09.282947 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:09.288015 kubelet[2375]: I0429 23:59:09.287940 2375 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Apr 29 23:59:09.289707 kubelet[2375]: I0429 23:59:09.289607 2375 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Apr 29 23:59:09.289843 kubelet[2375]: W0429 23:59:09.289740 2375 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 29 23:59:09.291503 kubelet[2375]: I0429 23:59:09.291440 2375 watchdog_linux.go:99] "Systemd watchdog is not enabled" Apr 29 23:59:09.291613 kubelet[2375]: I0429 23:59:09.291513 2375 server.go:1287] "Started kubelet" Apr 29 23:59:09.301847 kubelet[2375]: I0429 23:59:09.300976 2375 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 29 23:59:09.310187 kubelet[2375]: I0429 23:59:09.310128 2375 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Apr 29 23:59:09.313101 kubelet[2375]: I0429 23:59:09.312881 2375 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 29 23:59:09.314052 kubelet[2375]: I0429 23:59:09.313993 2375 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 29 23:59:09.314212 kubelet[2375]: I0429 23:59:09.314184 2375 server.go:490] "Adding debug handlers to kubelet server" Apr 29 23:59:09.314780 kubelet[2375]: I0429 23:59:09.314731 2375 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Apr 29 23:59:09.316707 kubelet[2375]: I0429 23:59:09.316673 2375 volume_manager.go:297] "Starting Kubelet Volume Manager" Apr 29 23:59:09.322743 kubelet[2375]: I0429 23:59:09.316863 2375 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Apr 29 23:59:09.323098 kubelet[2375]: E0429 23:59:09.317079 2375 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"172.31.23.46\" not found" Apr 29 23:59:09.323258 kubelet[2375]: I0429 23:59:09.323015 2375 reconciler.go:26] "Reconciler: start to sync state" Apr 29 23:59:09.324983 kubelet[2375]: E0429 23:59:09.324898 2375 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 29 23:59:09.327826 kubelet[2375]: I0429 23:59:09.326596 2375 factory.go:221] Registration of the systemd container factory successfully Apr 29 23:59:09.327826 kubelet[2375]: I0429 23:59:09.326901 2375 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:59:09.340639 kubelet[2375]: E0429 23:59:09.339440 2375 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.23.46\" not found" node="172.31.23.46" Apr 29 23:59:09.346388 kubelet[2375]: I0429 23:59:09.345762 2375 factory.go:221] Registration of the containerd container factory successfully Apr 29 23:59:09.386443 kubelet[2375]: I0429 23:59:09.386388 2375 cpu_manager.go:221] "Starting CPU manager" policy="none" Apr 29 23:59:09.386443 kubelet[2375]: I0429 23:59:09.386437 2375 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Apr 29 23:59:09.386656 kubelet[2375]: I0429 23:59:09.386473 2375 state_mem.go:36] "Initialized new in-memory state store" Apr 29 23:59:09.390207 kubelet[2375]: I0429 23:59:09.390149 2375 policy_none.go:49] "None policy: Start" Apr 29 23:59:09.390207 kubelet[2375]: I0429 23:59:09.390217 2375 memory_manager.go:186] "Starting memorymanager" policy="None" Apr 29 23:59:09.390617 kubelet[2375]: I0429 23:59:09.390247 2375 state_mem.go:35] "Initializing new in-memory state store" Apr 29 23:59:09.413611 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 29 23:59:09.423654 kubelet[2375]: E0429 23:59:09.423598 2375 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"172.31.23.46\" not found" Apr 29 23:59:09.438185 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 29 23:59:09.464602 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 29 23:59:09.466054 kubelet[2375]: I0429 23:59:09.465992 2375 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Apr 29 23:59:09.469955 kubelet[2375]: I0429 23:59:09.469910 2375 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Apr 29 23:59:09.470247 kubelet[2375]: I0429 23:59:09.470224 2375 status_manager.go:227] "Starting to sync pod status with apiserver" Apr 29 23:59:09.470973 kubelet[2375]: I0429 23:59:09.470922 2375 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Apr 29 23:59:09.470973 kubelet[2375]: I0429 23:59:09.470978 2375 kubelet.go:2388] "Starting kubelet main sync loop" Apr 29 23:59:09.471210 kubelet[2375]: E0429 23:59:09.471166 2375 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Apr 29 23:59:09.475687 kubelet[2375]: I0429 23:59:09.475612 2375 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 29 23:59:09.476030 kubelet[2375]: I0429 23:59:09.475982 2375 eviction_manager.go:189] "Eviction manager: starting control loop" Apr 29 23:59:09.476143 kubelet[2375]: I0429 23:59:09.476020 2375 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 29 23:59:09.478106 kubelet[2375]: I0429 23:59:09.477692 2375 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 29 23:59:09.487234 kubelet[2375]: E0429 23:59:09.486787 2375 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Apr 29 23:59:09.487234 kubelet[2375]: E0429 23:59:09.486861 2375 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.23.46\" not found" Apr 29 23:59:09.578436 kubelet[2375]: I0429 23:59:09.578395 2375 kubelet_node_status.go:76] "Attempting to register node" node="172.31.23.46" Apr 29 23:59:09.583752 kubelet[2375]: I0429 23:59:09.583696 2375 kubelet_node_status.go:79] "Successfully registered node" node="172.31.23.46" Apr 29 23:59:09.691776 kubelet[2375]: I0429 23:59:09.691366 2375 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 29 23:59:09.692371 containerd[1935]: time="2025-04-29T23:59:09.692274557Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 29 23:59:09.693991 kubelet[2375]: I0429 23:59:09.692818 2375 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 29 23:59:10.005989 sudo[2240]: pam_unix(sudo:session): session closed for user root Apr 29 23:59:10.045041 sshd[2239]: Connection closed by 139.178.89.65 port 58874 Apr 29 23:59:10.044096 sshd-session[2237]: pam_unix(sshd:session): session closed for user core Apr 29 23:59:10.050945 systemd-logind[1921]: Session 7 logged out. Waiting for processes to exit. Apr 29 23:59:10.051761 systemd[1]: sshd@6-172.31.23.46:22-139.178.89.65:58874.service: Deactivated successfully. Apr 29 23:59:10.055746 systemd[1]: session-7.scope: Deactivated successfully. Apr 29 23:59:10.060745 systemd-logind[1921]: Removed session 7. Apr 29 23:59:10.212614 kubelet[2375]: I0429 23:59:10.211898 2375 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 29 23:59:10.212614 kubelet[2375]: W0429 23:59:10.212126 2375 reflector.go:492] 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:59:10.212614 kubelet[2375]: W0429 23:59:10.212507 2375 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver 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:59:10.212614 kubelet[2375]: W0429 23:59:10.212559 2375 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service 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:59:10.284290 kubelet[2375]: I0429 23:59:10.283795 2375 apiserver.go:52] "Watching apiserver" Apr 29 23:59:10.284290 kubelet[2375]: E0429 23:59:10.284119 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:10.308268 systemd[1]: Created slice kubepods-burstable-pod4d0aeed3_7408_4454_bbcd_6533a1be1452.slice - libcontainer container kubepods-burstable-pod4d0aeed3_7408_4454_bbcd_6533a1be1452.slice. Apr 29 23:59:10.324000 kubelet[2375]: I0429 23:59:10.323929 2375 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Apr 29 23:59:10.327335 kubelet[2375]: I0429 23:59:10.327233 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/4d0aeed3-7408-4454-bbcd-6533a1be1452-cni-plugin\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.327335 kubelet[2375]: I0429 23:59:10.327338 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/4d0aeed3-7408-4454-bbcd-6533a1be1452-flannel-cfg\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.327549 kubelet[2375]: I0429 23:59:10.327379 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mfl7g\" (UniqueName: \"kubernetes.io/projected/4d0aeed3-7408-4454-bbcd-6533a1be1452-kube-api-access-mfl7g\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.327549 kubelet[2375]: I0429 23:59:10.327442 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/4d0aeed3-7408-4454-bbcd-6533a1be1452-xtables-lock\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.327549 kubelet[2375]: I0429 23:59:10.327480 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0a9ff8cd-a3f1-4b87-ae92-83589b073a86-kube-proxy\") pod \"kube-proxy-rqm6w\" (UID: \"0a9ff8cd-a3f1-4b87-ae92-83589b073a86\") " pod="kube-system/kube-proxy-rqm6w" Apr 29 23:59:10.327549 kubelet[2375]: I0429 23:59:10.327516 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0a9ff8cd-a3f1-4b87-ae92-83589b073a86-xtables-lock\") pod \"kube-proxy-rqm6w\" (UID: \"0a9ff8cd-a3f1-4b87-ae92-83589b073a86\") " pod="kube-system/kube-proxy-rqm6w" Apr 29 23:59:10.327751 kubelet[2375]: I0429 23:59:10.327554 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0a9ff8cd-a3f1-4b87-ae92-83589b073a86-lib-modules\") pod \"kube-proxy-rqm6w\" (UID: \"0a9ff8cd-a3f1-4b87-ae92-83589b073a86\") " pod="kube-system/kube-proxy-rqm6w" Apr 29 23:59:10.327751 kubelet[2375]: I0429 23:59:10.327591 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7wnxq\" (UniqueName: \"kubernetes.io/projected/0a9ff8cd-a3f1-4b87-ae92-83589b073a86-kube-api-access-7wnxq\") pod \"kube-proxy-rqm6w\" (UID: \"0a9ff8cd-a3f1-4b87-ae92-83589b073a86\") " pod="kube-system/kube-proxy-rqm6w" Apr 29 23:59:10.327751 kubelet[2375]: I0429 23:59:10.327625 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/4d0aeed3-7408-4454-bbcd-6533a1be1452-run\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.327751 kubelet[2375]: I0429 23:59:10.327658 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/4d0aeed3-7408-4454-bbcd-6533a1be1452-cni\") pod \"kube-flannel-ds-8cnkk\" (UID: \"4d0aeed3-7408-4454-bbcd-6533a1be1452\") " pod="kube-flannel/kube-flannel-ds-8cnkk" Apr 29 23:59:10.330802 systemd[1]: Created slice kubepods-besteffort-pod0a9ff8cd_a3f1_4b87_ae92_83589b073a86.slice - libcontainer container kubepods-besteffort-pod0a9ff8cd_a3f1_4b87_ae92_83589b073a86.slice. Apr 29 23:59:10.625784 containerd[1935]: time="2025-04-29T23:59:10.625613046Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8cnkk,Uid:4d0aeed3-7408-4454-bbcd-6533a1be1452,Namespace:kube-flannel,Attempt:0,}" Apr 29 23:59:10.644261 containerd[1935]: time="2025-04-29T23:59:10.644119926Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rqm6w,Uid:0a9ff8cd-a3f1-4b87-ae92-83589b073a86,Namespace:kube-system,Attempt:0,}" Apr 29 23:59:11.231954 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2887927172.mount: Deactivated successfully. Apr 29 23:59:11.246221 containerd[1935]: time="2025-04-29T23:59:11.245977133Z" 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:59:11.249465 containerd[1935]: time="2025-04-29T23:59:11.249401897Z" 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:59:11.250741 containerd[1935]: time="2025-04-29T23:59:11.250291481Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Apr 29 23:59:11.251669 containerd[1935]: time="2025-04-29T23:59:11.251621345Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 29 23:59:11.253143 containerd[1935]: time="2025-04-29T23:59:11.252961193Z" 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:59:11.263676 containerd[1935]: time="2025-04-29T23:59:11.263528081Z" 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:59:11.267426 containerd[1935]: time="2025-04-29T23:59:11.266676785Z" 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 622.435467ms" Apr 29 23:59:11.270144 containerd[1935]: time="2025-04-29T23:59:11.269719313Z" 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 643.682391ms" Apr 29 23:59:11.284471 kubelet[2375]: E0429 23:59:11.284346 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:11.550890 containerd[1935]: time="2025-04-29T23:59:11.550343634Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:11.550890 containerd[1935]: time="2025-04-29T23:59:11.550499478Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:11.550890 containerd[1935]: time="2025-04-29T23:59:11.550544046Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:11.551797 containerd[1935]: time="2025-04-29T23:59:11.550720926Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:11.566740 containerd[1935]: time="2025-04-29T23:59:11.565597458Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:11.566740 containerd[1935]: time="2025-04-29T23:59:11.565688574Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:11.566740 containerd[1935]: time="2025-04-29T23:59:11.565714974Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:11.566740 containerd[1935]: time="2025-04-29T23:59:11.565860102Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:11.693721 systemd[1]: Started cri-containerd-3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf.scope - libcontainer container 3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf. Apr 29 23:59:11.700520 systemd[1]: Started cri-containerd-ebea2d84ea3f58b97d16c0fe6583eb3e192412560eb409bd482059ddd431cbda.scope - libcontainer container ebea2d84ea3f58b97d16c0fe6583eb3e192412560eb409bd482059ddd431cbda. Apr 29 23:59:11.772053 containerd[1935]: time="2025-04-29T23:59:11.771974611Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-rqm6w,Uid:0a9ff8cd-a3f1-4b87-ae92-83589b073a86,Namespace:kube-system,Attempt:0,} returns sandbox id \"ebea2d84ea3f58b97d16c0fe6583eb3e192412560eb409bd482059ddd431cbda\"" Apr 29 23:59:11.782946 containerd[1935]: time="2025-04-29T23:59:11.782686267Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" Apr 29 23:59:11.811971 containerd[1935]: time="2025-04-29T23:59:11.811271743Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8cnkk,Uid:4d0aeed3-7408-4454-bbcd-6533a1be1452,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\"" Apr 29 23:59:12.285858 kubelet[2375]: E0429 23:59:12.284768 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:13.110920 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3542597980.mount: Deactivated successfully. Apr 29 23:59:13.285737 kubelet[2375]: E0429 23:59:13.285600 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:13.713077 containerd[1935]: time="2025-04-29T23:59:13.712767273Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:13.715901 containerd[1935]: time="2025-04-29T23:59:13.715816833Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=27370351" Apr 29 23:59:13.717445 containerd[1935]: time="2025-04-29T23:59:13.717285417Z" level=info msg="ImageCreate event name:\"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:13.726351 containerd[1935]: time="2025-04-29T23:59:13.726158601Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:13.728216 containerd[1935]: time="2025-04-29T23:59:13.727608177Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"27369370\" in 1.944805978s" Apr 29 23:59:13.728216 containerd[1935]: time="2025-04-29T23:59:13.727666221Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\"" Apr 29 23:59:13.730855 containerd[1935]: time="2025-04-29T23:59:13.730795617Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 29 23:59:13.732751 containerd[1935]: time="2025-04-29T23:59:13.732696249Z" level=info msg="CreateContainer within sandbox \"ebea2d84ea3f58b97d16c0fe6583eb3e192412560eb409bd482059ddd431cbda\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 29 23:59:13.760351 containerd[1935]: time="2025-04-29T23:59:13.760260633Z" level=info msg="CreateContainer within sandbox \"ebea2d84ea3f58b97d16c0fe6583eb3e192412560eb409bd482059ddd431cbda\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"8b7f35e8230f5543c6f9b0d6cf7ed9f27a538b3495835e5468aeba94c3576cee\"" Apr 29 23:59:13.762371 containerd[1935]: time="2025-04-29T23:59:13.761417265Z" level=info msg="StartContainer for \"8b7f35e8230f5543c6f9b0d6cf7ed9f27a538b3495835e5468aeba94c3576cee\"" Apr 29 23:59:13.821605 systemd[1]: Started cri-containerd-8b7f35e8230f5543c6f9b0d6cf7ed9f27a538b3495835e5468aeba94c3576cee.scope - libcontainer container 8b7f35e8230f5543c6f9b0d6cf7ed9f27a538b3495835e5468aeba94c3576cee. Apr 29 23:59:13.882005 containerd[1935]: time="2025-04-29T23:59:13.881923342Z" level=info msg="StartContainer for \"8b7f35e8230f5543c6f9b0d6cf7ed9f27a538b3495835e5468aeba94c3576cee\" returns successfully" Apr 29 23:59:14.285948 kubelet[2375]: E0429 23:59:14.285890 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:15.286845 kubelet[2375]: E0429 23:59:15.286775 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:15.475333 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount996167042.mount: Deactivated successfully. Apr 29 23:59:15.536800 containerd[1935]: time="2025-04-29T23:59:15.536734246Z" 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:59:15.538506 containerd[1935]: time="2025-04-29T23:59:15.538351786Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673531" Apr 29 23:59:15.542066 containerd[1935]: time="2025-04-29T23:59:15.541830046Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:15.546770 containerd[1935]: time="2025-04-29T23:59:15.546702298Z" 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:59:15.548932 containerd[1935]: time="2025-04-29T23:59:15.548447974Z" 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.817594493s" Apr 29 23:59:15.548932 containerd[1935]: time="2025-04-29T23:59:15.548507686Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Apr 29 23:59:15.553029 containerd[1935]: time="2025-04-29T23:59:15.552910918Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 29 23:59:15.578252 containerd[1935]: time="2025-04-29T23:59:15.578131402Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf\"" Apr 29 23:59:15.579330 containerd[1935]: time="2025-04-29T23:59:15.579187846Z" level=info msg="StartContainer for \"5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf\"" Apr 29 23:59:15.644637 systemd[1]: Started cri-containerd-5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf.scope - libcontainer container 5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf. Apr 29 23:59:15.697067 containerd[1935]: time="2025-04-29T23:59:15.696883079Z" level=info msg="StartContainer for \"5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf\" returns successfully" Apr 29 23:59:15.698894 systemd[1]: cri-containerd-5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf.scope: Deactivated successfully. Apr 29 23:59:15.905404 containerd[1935]: time="2025-04-29T23:59:15.904679304Z" level=info msg="shim disconnected" id=5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf namespace=k8s.io Apr 29 23:59:15.905404 containerd[1935]: time="2025-04-29T23:59:15.904758420Z" level=warning msg="cleaning up after shim disconnected" id=5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf namespace=k8s.io Apr 29 23:59:15.905404 containerd[1935]: time="2025-04-29T23:59:15.904779840Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 29 23:59:16.287408 kubelet[2375]: E0429 23:59:16.287292 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:16.333517 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5ba764a920f7f4aefd9cd9ca43e4da30b30215a63dc915ff2ad1f54f3dab3ecf-rootfs.mount: Deactivated successfully. Apr 29 23:59:16.514016 containerd[1935]: time="2025-04-29T23:59:16.513959267Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 29 23:59:16.532579 kubelet[2375]: I0429 23:59:16.532402 2375 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-rqm6w" podStartSLOduration=5.581828217 podStartE2EDuration="7.532379411s" podCreationTimestamp="2025-04-29 23:59:09 +0000 UTC" firstStartedPulling="2025-04-29 23:59:11.779162791 +0000 UTC m=+3.815034092" lastFinishedPulling="2025-04-29 23:59:13.729713997 +0000 UTC m=+5.765585286" observedRunningTime="2025-04-29 23:59:14.519517209 +0000 UTC m=+6.555388522" watchObservedRunningTime="2025-04-29 23:59:16.532379411 +0000 UTC m=+8.568250724" Apr 29 23:59:17.288510 kubelet[2375]: E0429 23:59:17.288439 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:18.289428 kubelet[2375]: E0429 23:59:18.289338 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:19.289935 kubelet[2375]: E0429 23:59:19.289865 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:19.627218 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1219289871.mount: Deactivated successfully. Apr 29 23:59:20.290493 kubelet[2375]: E0429 23:59:20.290252 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:20.467539 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 29 23:59:20.704086 containerd[1935]: time="2025-04-29T23:59:20.703557400Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:20.705743 containerd[1935]: time="2025-04-29T23:59:20.705672340Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Apr 29 23:59:20.706734 containerd[1935]: time="2025-04-29T23:59:20.706643272Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:20.712175 containerd[1935]: time="2025-04-29T23:59:20.712089052Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:20.714515 containerd[1935]: time="2025-04-29T23:59:20.714451636Z" 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 4.199930265s" Apr 29 23:59:20.714515 containerd[1935]: time="2025-04-29T23:59:20.714510124Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Apr 29 23:59:20.718579 containerd[1935]: time="2025-04-29T23:59:20.718510036Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 29 23:59:20.737283 containerd[1935]: time="2025-04-29T23:59:20.737170072Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e\"" Apr 29 23:59:20.738656 containerd[1935]: time="2025-04-29T23:59:20.738482860Z" level=info msg="StartContainer for \"7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e\"" Apr 29 23:59:20.785716 systemd[1]: run-containerd-runc-k8s.io-7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e-runc.w8lnXb.mount: Deactivated successfully. Apr 29 23:59:20.796658 systemd[1]: Started cri-containerd-7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e.scope - libcontainer container 7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e. Apr 29 23:59:20.848365 systemd[1]: cri-containerd-7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e.scope: Deactivated successfully. Apr 29 23:59:20.854400 containerd[1935]: time="2025-04-29T23:59:20.853414648Z" 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-pod4d0aeed3_7408_4454_bbcd_6533a1be1452.slice/cri-containerd-7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e.scope/memory.events\": no such file or directory" Apr 29 23:59:20.856587 containerd[1935]: time="2025-04-29T23:59:20.856464172Z" level=info msg="StartContainer for \"7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e\" returns successfully" Apr 29 23:59:20.866486 kubelet[2375]: I0429 23:59:20.866448 2375 kubelet_node_status.go:502] "Fast updating node status as it just became ready" Apr 29 23:59:21.010780 containerd[1935]: time="2025-04-29T23:59:21.010632985Z" level=info msg="shim disconnected" id=7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e namespace=k8s.io Apr 29 23:59:21.010780 containerd[1935]: time="2025-04-29T23:59:21.010732285Z" level=warning msg="cleaning up after shim disconnected" id=7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e namespace=k8s.io Apr 29 23:59:21.010780 containerd[1935]: time="2025-04-29T23:59:21.010754305Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 29 23:59:21.290646 kubelet[2375]: E0429 23:59:21.290477 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:21.534693 containerd[1935]: time="2025-04-29T23:59:21.534495736Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 29 23:59:21.549874 containerd[1935]: time="2025-04-29T23:59:21.549367132Z" level=info msg="CreateContainer within sandbox \"3ae8320e2de2a0bdf8783d518bc71df17df27116ac49c88e6ea02948e31fffcf\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"bb70bc53410e65f4739f9751efaa5a53b63b312897d9edc96ca8d981a84ca8eb\"" Apr 29 23:59:21.552055 containerd[1935]: time="2025-04-29T23:59:21.550271512Z" level=info msg="StartContainer for \"bb70bc53410e65f4739f9751efaa5a53b63b312897d9edc96ca8d981a84ca8eb\"" Apr 29 23:59:21.597582 systemd[1]: Started cri-containerd-bb70bc53410e65f4739f9751efaa5a53b63b312897d9edc96ca8d981a84ca8eb.scope - libcontainer container bb70bc53410e65f4739f9751efaa5a53b63b312897d9edc96ca8d981a84ca8eb. Apr 29 23:59:21.644019 containerd[1935]: time="2025-04-29T23:59:21.643900336Z" level=info msg="StartContainer for \"bb70bc53410e65f4739f9751efaa5a53b63b312897d9edc96ca8d981a84ca8eb\" returns successfully" Apr 29 23:59:21.731234 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-7ea2031aa2a0678d93ba783925f2333110487032120055aae76c01b45217e58e-rootfs.mount: Deactivated successfully. Apr 29 23:59:22.291626 kubelet[2375]: E0429 23:59:22.291554 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:22.714258 (udev-worker)[2877]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:59:22.732883 systemd-networkd[1849]: flannel.1: Link UP Apr 29 23:59:22.732912 systemd-networkd[1849]: flannel.1: Gained carrier Apr 29 23:59:22.784739 kubelet[2375]: I0429 23:59:22.784288 2375 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-8cnkk" podStartSLOduration=4.8831372250000005 podStartE2EDuration="13.784248246s" podCreationTimestamp="2025-04-29 23:59:09 +0000 UTC" firstStartedPulling="2025-04-29 23:59:11.815519311 +0000 UTC m=+3.851390588" lastFinishedPulling="2025-04-29 23:59:20.71663032 +0000 UTC m=+12.752501609" observedRunningTime="2025-04-29 23:59:22.557910005 +0000 UTC m=+14.593781306" watchObservedRunningTime="2025-04-29 23:59:22.784248246 +0000 UTC m=+14.820119535" Apr 29 23:59:22.800705 systemd[1]: Created slice kubepods-besteffort-pod8cfb8d3d_325b_4014_ae2d_6cec17fead2e.slice - libcontainer container kubepods-besteffort-pod8cfb8d3d_325b_4014_ae2d_6cec17fead2e.slice. Apr 29 23:59:22.806164 kubelet[2375]: I0429 23:59:22.805406 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vb247\" (UniqueName: \"kubernetes.io/projected/8cfb8d3d-325b-4014-ae2d-6cec17fead2e-kube-api-access-vb247\") pod \"nginx-deployment-7fcdb87857-thjdm\" (UID: \"8cfb8d3d-325b-4014-ae2d-6cec17fead2e\") " pod="default/nginx-deployment-7fcdb87857-thjdm" Apr 29 23:59:23.110551 containerd[1935]: time="2025-04-29T23:59:23.110008036Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-thjdm,Uid:8cfb8d3d-325b-4014-ae2d-6cec17fead2e,Namespace:default,Attempt:0,}" Apr 29 23:59:23.156162 systemd-networkd[1849]: cni0: Link UP Apr 29 23:59:23.171772 (udev-worker)[2975]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:59:23.173497 systemd-networkd[1849]: vethd3bd1037: Link UP Apr 29 23:59:23.182818 kernel: cni0: port 1(vethd3bd1037) entered blocking state Apr 29 23:59:23.183070 kernel: cni0: port 1(vethd3bd1037) entered disabled state Apr 29 23:59:23.184431 kernel: vethd3bd1037: entered allmulticast mode Apr 29 23:59:23.188377 kernel: vethd3bd1037: entered promiscuous mode Apr 29 23:59:23.188500 kernel: cni0: port 1(vethd3bd1037) entered blocking state Apr 29 23:59:23.188541 kernel: cni0: port 1(vethd3bd1037) entered forwarding state Apr 29 23:59:23.190402 kernel: cni0: port 1(vethd3bd1037) entered disabled state Apr 29 23:59:23.212548 kernel: cni0: port 1(vethd3bd1037) entered blocking state Apr 29 23:59:23.212698 kernel: cni0: port 1(vethd3bd1037) entered forwarding state Apr 29 23:59:23.212463 systemd-networkd[1849]: vethd3bd1037: Gained carrier Apr 29 23:59:23.213418 systemd-networkd[1849]: cni0: Gained carrier Apr 29 23:59:23.219185 containerd[1935]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Apr 29 23:59:23.219185 containerd[1935]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:59:23.260561 containerd[1935]: {"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:23.260068216Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:23.260561 containerd[1935]: time="2025-04-29T23:59:23.260201752Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:23.260561 containerd[1935]: time="2025-04-29T23:59:23.260246752Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:23.260561 containerd[1935]: time="2025-04-29T23:59:23.260470876Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:23.292146 kubelet[2375]: E0429 23:59:23.292041 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:23.300360 systemd[1]: Started cri-containerd-51c1c6b807a913300d34ae6d5a28eab43fb706f0e1c8328106b99305b5b4b180.scope - libcontainer container 51c1c6b807a913300d34ae6d5a28eab43fb706f0e1c8328106b99305b5b4b180. Apr 29 23:59:23.360785 containerd[1935]: time="2025-04-29T23:59:23.360643109Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-thjdm,Uid:8cfb8d3d-325b-4014-ae2d-6cec17fead2e,Namespace:default,Attempt:0,} returns sandbox id \"51c1c6b807a913300d34ae6d5a28eab43fb706f0e1c8328106b99305b5b4b180\"" Apr 29 23:59:23.364868 containerd[1935]: time="2025-04-29T23:59:23.364749425Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 29 23:59:23.824705 systemd-networkd[1849]: flannel.1: Gained IPv6LL Apr 29 23:59:24.293052 kubelet[2375]: E0429 23:59:24.292985 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:24.467056 systemd-networkd[1849]: vethd3bd1037: Gained IPv6LL Apr 29 23:59:25.104589 systemd-networkd[1849]: cni0: Gained IPv6LL Apr 29 23:59:25.293518 kubelet[2375]: E0429 23:59:25.293472 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:26.294968 kubelet[2375]: E0429 23:59:26.294876 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:26.514696 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4050632658.mount: Deactivated successfully. Apr 29 23:59:27.295938 kubelet[2375]: E0429 23:59:27.295854 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:27.518125 ntpd[1917]: Listen normally on 7 flannel.1 192.168.1.0:123 Apr 29 23:59:27.519478 ntpd[1917]: 29 Apr 23:59:27 ntpd[1917]: Listen normally on 7 flannel.1 192.168.1.0:123 Apr 29 23:59:27.519478 ntpd[1917]: 29 Apr 23:59:27 ntpd[1917]: Listen normally on 8 cni0 192.168.1.1:123 Apr 29 23:59:27.519478 ntpd[1917]: 29 Apr 23:59:27 ntpd[1917]: Listen normally on 9 flannel.1 [fe80::18f5:aff:fe24:ceb5%3]:123 Apr 29 23:59:27.519478 ntpd[1917]: 29 Apr 23:59:27 ntpd[1917]: Listen normally on 10 cni0 [fe80::986a:19ff:fe77:fdb1%4]:123 Apr 29 23:59:27.519478 ntpd[1917]: 29 Apr 23:59:27 ntpd[1917]: Listen normally on 11 vethd3bd1037 [fe80::8c03:e0ff:fe09:bccb%5]:123 Apr 29 23:59:27.518245 ntpd[1917]: Listen normally on 8 cni0 192.168.1.1:123 Apr 29 23:59:27.518452 ntpd[1917]: Listen normally on 9 flannel.1 [fe80::18f5:aff:fe24:ceb5%3]:123 Apr 29 23:59:27.518543 ntpd[1917]: Listen normally on 10 cni0 [fe80::986a:19ff:fe77:fdb1%4]:123 Apr 29 23:59:27.518617 ntpd[1917]: Listen normally on 11 vethd3bd1037 [fe80::8c03:e0ff:fe09:bccb%5]:123 Apr 29 23:59:28.079154 containerd[1935]: time="2025-04-29T23:59:28.078741104Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:28.080777 containerd[1935]: time="2025-04-29T23:59:28.080693360Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948638" Apr 29 23:59:28.085344 containerd[1935]: time="2025-04-29T23:59:28.083442632Z" level=info msg="ImageCreate event name:\"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:28.089998 containerd[1935]: time="2025-04-29T23:59:28.089918612Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:28.092398 containerd[1935]: time="2025-04-29T23:59:28.092336312Z" 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.727472023s" Apr 29 23:59:28.092618 containerd[1935]: time="2025-04-29T23:59:28.092582708Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 29 23:59:28.096597 containerd[1935]: time="2025-04-29T23:59:28.096523748Z" level=info msg="CreateContainer within sandbox \"51c1c6b807a913300d34ae6d5a28eab43fb706f0e1c8328106b99305b5b4b180\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 29 23:59:28.120420 containerd[1935]: time="2025-04-29T23:59:28.120344660Z" level=info msg="CreateContainer within sandbox \"51c1c6b807a913300d34ae6d5a28eab43fb706f0e1c8328106b99305b5b4b180\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"fbf78534eea68253322aa94a7e786689bd2e79fbb293db8fa5f17cdcdd78cadd\"" Apr 29 23:59:28.121510 containerd[1935]: time="2025-04-29T23:59:28.121462892Z" level=info msg="StartContainer for \"fbf78534eea68253322aa94a7e786689bd2e79fbb293db8fa5f17cdcdd78cadd\"" Apr 29 23:59:28.177656 systemd[1]: Started cri-containerd-fbf78534eea68253322aa94a7e786689bd2e79fbb293db8fa5f17cdcdd78cadd.scope - libcontainer container fbf78534eea68253322aa94a7e786689bd2e79fbb293db8fa5f17cdcdd78cadd. Apr 29 23:59:28.230936 containerd[1935]: time="2025-04-29T23:59:28.230868645Z" level=info msg="StartContainer for \"fbf78534eea68253322aa94a7e786689bd2e79fbb293db8fa5f17cdcdd78cadd\" returns successfully" Apr 29 23:59:28.296802 kubelet[2375]: E0429 23:59:28.296644 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:28.574997 kubelet[2375]: I0429 23:59:28.574900 2375 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-thjdm" podStartSLOduration=1.844332104 podStartE2EDuration="6.574880219s" podCreationTimestamp="2025-04-29 23:59:22 +0000 UTC" firstStartedPulling="2025-04-29 23:59:23.363664937 +0000 UTC m=+15.399536202" lastFinishedPulling="2025-04-29 23:59:28.094212992 +0000 UTC m=+20.130084317" observedRunningTime="2025-04-29 23:59:28.574710527 +0000 UTC m=+20.610581876" watchObservedRunningTime="2025-04-29 23:59:28.574880219 +0000 UTC m=+20.610751496" Apr 29 23:59:29.282449 kubelet[2375]: E0429 23:59:29.282370 2375 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:29.296886 kubelet[2375]: E0429 23:59:29.296838 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:30.297484 kubelet[2375]: E0429 23:59:30.297416 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:31.297795 kubelet[2375]: E0429 23:59:31.297727 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:31.493847 systemd[1]: Created slice kubepods-besteffort-pod3dcf7618_3d22_426e_93d2_870a0768d68a.slice - libcontainer container kubepods-besteffort-pod3dcf7618_3d22_426e_93d2_870a0768d68a.slice. Apr 29 23:59:31.552606 kubelet[2375]: I0429 23:59:31.552439 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/3dcf7618-3d22-426e-93d2-870a0768d68a-data\") pod \"nfs-server-provisioner-0\" (UID: \"3dcf7618-3d22-426e-93d2-870a0768d68a\") " pod="default/nfs-server-provisioner-0" Apr 29 23:59:31.552606 kubelet[2375]: I0429 23:59:31.552514 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-skb78\" (UniqueName: \"kubernetes.io/projected/3dcf7618-3d22-426e-93d2-870a0768d68a-kube-api-access-skb78\") pod \"nfs-server-provisioner-0\" (UID: \"3dcf7618-3d22-426e-93d2-870a0768d68a\") " pod="default/nfs-server-provisioner-0" Apr 29 23:59:31.800905 containerd[1935]: time="2025-04-29T23:59:31.800781315Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:3dcf7618-3d22-426e-93d2-870a0768d68a,Namespace:default,Attempt:0,}" Apr 29 23:59:31.851423 (udev-worker)[3143]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:59:31.855598 kernel: cni0: port 2(veth5b5299a3) entered blocking state Apr 29 23:59:31.855653 kernel: cni0: port 2(veth5b5299a3) entered disabled state Apr 29 23:59:31.855831 systemd-networkd[1849]: veth5b5299a3: Link UP Apr 29 23:59:31.858855 kernel: veth5b5299a3: entered allmulticast mode Apr 29 23:59:31.861355 kernel: veth5b5299a3: entered promiscuous mode Apr 29 23:59:31.875516 kernel: cni0: port 2(veth5b5299a3) entered blocking state Apr 29 23:59:31.875663 kernel: cni0: port 2(veth5b5299a3) entered forwarding state Apr 29 23:59:31.876177 systemd-networkd[1849]: veth5b5299a3: Gained carrier Apr 29 23:59:31.880426 containerd[1935]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Apr 29 23:59:31.880426 containerd[1935]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:59:31.914641 containerd[1935]: {"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:31.914505663Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:31.914927 containerd[1935]: time="2025-04-29T23:59:31.914613795Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:31.914927 containerd[1935]: time="2025-04-29T23:59:31.914640375Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:31.914927 containerd[1935]: time="2025-04-29T23:59:31.914829579Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:31.955622 systemd[1]: Started cri-containerd-f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7.scope - libcontainer container f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7. Apr 29 23:59:32.017829 containerd[1935]: time="2025-04-29T23:59:32.017661552Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:3dcf7618-3d22-426e-93d2-870a0768d68a,Namespace:default,Attempt:0,} returns sandbox id \"f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7\"" Apr 29 23:59:32.023647 containerd[1935]: time="2025-04-29T23:59:32.023189508Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 29 23:59:32.298549 kubelet[2375]: E0429 23:59:32.298476 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:32.676511 systemd[1]: run-containerd-runc-k8s.io-f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7-runc.ZiN4Z1.mount: Deactivated successfully. Apr 29 23:59:33.040526 systemd-networkd[1849]: veth5b5299a3: Gained IPv6LL Apr 29 23:59:33.299157 kubelet[2375]: E0429 23:59:33.298986 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:34.300161 kubelet[2375]: E0429 23:59:34.300068 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:34.691991 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2100233611.mount: Deactivated successfully. Apr 29 23:59:35.283345 update_engine[1922]: I20250429 23:59:35.283192 1922 update_attempter.cc:509] Updating boot flags... Apr 29 23:59:35.303767 kubelet[2375]: E0429 23:59:35.301043 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:35.420728 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (3262) Apr 29 23:59:35.518009 ntpd[1917]: Listen normally on 12 veth5b5299a3 [fe80::bc23:42ff:fe77:5511%6]:123 Apr 29 23:59:35.519244 ntpd[1917]: 29 Apr 23:59:35 ntpd[1917]: Listen normally on 12 veth5b5299a3 [fe80::bc23:42ff:fe77:5511%6]:123 Apr 29 23:59:35.911443 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 43 scanned by (udev-worker) (3263) Apr 29 23:59:36.301834 kubelet[2375]: E0429 23:59:36.301759 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:37.302625 kubelet[2375]: E0429 23:59:37.302464 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:38.303447 kubelet[2375]: E0429 23:59:38.303357 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:38.709973 containerd[1935]: time="2025-04-29T23:59:38.709712829Z" 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:59:38.712499 containerd[1935]: time="2025-04-29T23:59:38.712403253Z" 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:59:38.714723 containerd[1935]: time="2025-04-29T23:59:38.714604557Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:38.727514 containerd[1935]: time="2025-04-29T23:59:38.727420749Z" 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:59:38.731258 containerd[1935]: time="2025-04-29T23:59:38.730358133Z" 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.706939977s" Apr 29 23:59:38.731258 containerd[1935]: time="2025-04-29T23:59:38.730428513Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Apr 29 23:59:38.735528 containerd[1935]: time="2025-04-29T23:59:38.735467109Z" level=info msg="CreateContainer within sandbox \"f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 29 23:59:38.758622 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount14101520.mount: Deactivated successfully. Apr 29 23:59:38.766413 containerd[1935]: time="2025-04-29T23:59:38.766340349Z" level=info msg="CreateContainer within sandbox \"f6a46e07a724b863d1f13e172336a12dc009c1878de90eaadf3221c7afbadad7\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"c97db7ee4dea15f8bb15da5830ceff4ea13ad779247f17971dfa22bf804cd5c3\"" Apr 29 23:59:38.767364 containerd[1935]: time="2025-04-29T23:59:38.767237505Z" level=info msg="StartContainer for \"c97db7ee4dea15f8bb15da5830ceff4ea13ad779247f17971dfa22bf804cd5c3\"" Apr 29 23:59:38.819605 systemd[1]: Started cri-containerd-c97db7ee4dea15f8bb15da5830ceff4ea13ad779247f17971dfa22bf804cd5c3.scope - libcontainer container c97db7ee4dea15f8bb15da5830ceff4ea13ad779247f17971dfa22bf804cd5c3. Apr 29 23:59:38.867051 containerd[1935]: time="2025-04-29T23:59:38.866846842Z" level=info msg="StartContainer for \"c97db7ee4dea15f8bb15da5830ceff4ea13ad779247f17971dfa22bf804cd5c3\" returns successfully" Apr 29 23:59:39.304116 kubelet[2375]: E0429 23:59:39.304061 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:40.305203 kubelet[2375]: E0429 23:59:40.305102 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:41.306328 kubelet[2375]: E0429 23:59:41.306222 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:42.306863 kubelet[2375]: E0429 23:59:42.306760 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:43.307203 kubelet[2375]: E0429 23:59:43.307127 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:44.307915 kubelet[2375]: E0429 23:59:44.307827 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:45.308133 kubelet[2375]: E0429 23:59:45.308042 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:46.309194 kubelet[2375]: E0429 23:59:46.309122 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:47.309452 kubelet[2375]: E0429 23:59:47.309374 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:48.310407 kubelet[2375]: E0429 23:59:48.310338 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:48.895075 kubelet[2375]: I0429 23:59:48.894867 2375 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.183332831 podStartE2EDuration="17.894834824s" podCreationTimestamp="2025-04-29 23:59:31 +0000 UTC" firstStartedPulling="2025-04-29 23:59:32.021442272 +0000 UTC m=+24.057313549" lastFinishedPulling="2025-04-29 23:59:38.732944265 +0000 UTC m=+30.768815542" observedRunningTime="2025-04-29 23:59:39.619219354 +0000 UTC m=+31.655090691" watchObservedRunningTime="2025-04-29 23:59:48.894834824 +0000 UTC m=+40.930706101" Apr 29 23:59:48.919964 systemd[1]: Created slice kubepods-besteffort-pod8d6839a6_11a2_4074_b4ae_938b6da6f047.slice - libcontainer container kubepods-besteffort-pod8d6839a6_11a2_4074_b4ae_938b6da6f047.slice. Apr 29 23:59:48.983722 kubelet[2375]: I0429 23:59:48.983583 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-g6fts\" (UniqueName: \"kubernetes.io/projected/8d6839a6-11a2-4074-b4ae-938b6da6f047-kube-api-access-g6fts\") pod \"test-pod-1\" (UID: \"8d6839a6-11a2-4074-b4ae-938b6da6f047\") " pod="default/test-pod-1" Apr 29 23:59:48.983722 kubelet[2375]: I0429 23:59:48.983651 2375 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-05c14543-1b02-4fcc-b49a-c177e170f3f1\" (UniqueName: \"kubernetes.io/nfs/8d6839a6-11a2-4074-b4ae-938b6da6f047-pvc-05c14543-1b02-4fcc-b49a-c177e170f3f1\") pod \"test-pod-1\" (UID: \"8d6839a6-11a2-4074-b4ae-938b6da6f047\") " pod="default/test-pod-1" Apr 29 23:59:49.119353 kernel: FS-Cache: Loaded Apr 29 23:59:49.163334 kernel: RPC: Registered named UNIX socket transport module. Apr 29 23:59:49.163474 kernel: RPC: Registered udp transport module. Apr 29 23:59:49.163519 kernel: RPC: Registered tcp transport module. Apr 29 23:59:49.163560 kernel: RPC: Registered tcp-with-tls transport module. Apr 29 23:59:49.164231 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 29 23:59:49.282814 kubelet[2375]: E0429 23:59:49.282738 2375 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:49.311594 kubelet[2375]: E0429 23:59:49.311510 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:49.508712 kernel: NFS: Registering the id_resolver key type Apr 29 23:59:49.508848 kernel: Key type id_resolver registered Apr 29 23:59:49.508887 kernel: Key type id_legacy registered Apr 29 23:59:49.548542 nfsidmap[3603]: 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:49.555924 nfsidmap[3604]: 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:49.828338 containerd[1935]: time="2025-04-29T23:59:49.828147980Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8d6839a6-11a2-4074-b4ae-938b6da6f047,Namespace:default,Attempt:0,}" Apr 29 23:59:49.873227 (udev-worker)[3596]: Network interface NamePolicy= disabled on kernel command line. Apr 29 23:59:49.878525 kernel: cni0: port 3(veth9018f59c) entered blocking state Apr 29 23:59:49.878591 kernel: cni0: port 3(veth9018f59c) entered disabled state Apr 29 23:59:49.878632 kernel: veth9018f59c: entered allmulticast mode Apr 29 23:59:49.874153 systemd-networkd[1849]: veth9018f59c: Link UP Apr 29 23:59:49.881338 kernel: veth9018f59c: entered promiscuous mode Apr 29 23:59:49.897824 kernel: cni0: port 3(veth9018f59c) entered blocking state Apr 29 23:59:49.897941 kernel: cni0: port 3(veth9018f59c) entered forwarding state Apr 29 23:59:49.898159 systemd-networkd[1849]: veth9018f59c: Gained carrier Apr 29 23:59:49.901160 containerd[1935]: 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)(0x4000016938), "name":"cbr0", "type":"bridge"} Apr 29 23:59:49.901160 containerd[1935]: delegateAdd: netconf sent to delegate plugin: Apr 29 23:59:49.937002 containerd[1935]: {"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:49.936803325Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 29 23:59:49.937395 containerd[1935]: time="2025-04-29T23:59:49.937235493Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 29 23:59:49.937395 containerd[1935]: time="2025-04-29T23:59:49.937333329Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:49.938436 containerd[1935]: time="2025-04-29T23:59:49.938141025Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 29 23:59:49.970795 systemd[1]: Started cri-containerd-1962b4c34b3193b06bf841cf8200cde7936476ede6ddf75db680a657ee99e0e5.scope - libcontainer container 1962b4c34b3193b06bf841cf8200cde7936476ede6ddf75db680a657ee99e0e5. Apr 29 23:59:50.036334 containerd[1935]: time="2025-04-29T23:59:50.036228509Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8d6839a6-11a2-4074-b4ae-938b6da6f047,Namespace:default,Attempt:0,} returns sandbox id \"1962b4c34b3193b06bf841cf8200cde7936476ede6ddf75db680a657ee99e0e5\"" Apr 29 23:59:50.039528 containerd[1935]: time="2025-04-29T23:59:50.038734253Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 29 23:59:50.312467 kubelet[2375]: E0429 23:59:50.312400 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:50.347770 containerd[1935]: time="2025-04-29T23:59:50.347688127Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 29 23:59:50.349775 containerd[1935]: time="2025-04-29T23:59:50.349683535Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 29 23:59:50.355576 containerd[1935]: time="2025-04-29T23:59:50.355378363Z" 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 316.572638ms" Apr 29 23:59:50.355576 containerd[1935]: time="2025-04-29T23:59:50.355434115Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e20c52090e36e47716225aae95fda06191c98f3a8d7f6371786c19c9e59befb1\"" Apr 29 23:59:50.359449 containerd[1935]: time="2025-04-29T23:59:50.359393851Z" level=info msg="CreateContainer within sandbox \"1962b4c34b3193b06bf841cf8200cde7936476ede6ddf75db680a657ee99e0e5\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 29 23:59:50.383392 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1414364242.mount: Deactivated successfully. Apr 29 23:59:50.395667 containerd[1935]: time="2025-04-29T23:59:50.395568691Z" level=info msg="CreateContainer within sandbox \"1962b4c34b3193b06bf841cf8200cde7936476ede6ddf75db680a657ee99e0e5\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"c50f47d4579d94de3c311bb8039bc1059a8c1f92df4fc742d034a021d85d16ea\"" Apr 29 23:59:50.398352 containerd[1935]: time="2025-04-29T23:59:50.396986179Z" level=info msg="StartContainer for \"c50f47d4579d94de3c311bb8039bc1059a8c1f92df4fc742d034a021d85d16ea\"" Apr 29 23:59:50.455648 systemd[1]: Started cri-containerd-c50f47d4579d94de3c311bb8039bc1059a8c1f92df4fc742d034a021d85d16ea.scope - libcontainer container c50f47d4579d94de3c311bb8039bc1059a8c1f92df4fc742d034a021d85d16ea. Apr 29 23:59:50.513520 containerd[1935]: time="2025-04-29T23:59:50.513270044Z" level=info msg="StartContainer for \"c50f47d4579d94de3c311bb8039bc1059a8c1f92df4fc742d034a021d85d16ea\" returns successfully" Apr 29 23:59:50.653271 kubelet[2375]: I0429 23:59:50.653065 2375 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.334482438 podStartE2EDuration="18.653038844s" podCreationTimestamp="2025-04-29 23:59:32 +0000 UTC" firstStartedPulling="2025-04-29 23:59:50.038202749 +0000 UTC m=+42.074074026" lastFinishedPulling="2025-04-29 23:59:50.356759107 +0000 UTC m=+42.392630432" observedRunningTime="2025-04-29 23:59:50.652280264 +0000 UTC m=+42.688151613" watchObservedRunningTime="2025-04-29 23:59:50.653038844 +0000 UTC m=+42.688910121" Apr 29 23:59:51.313389 kubelet[2375]: E0429 23:59:51.313282 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:51.728516 systemd-networkd[1849]: veth9018f59c: Gained IPv6LL Apr 29 23:59:52.314608 kubelet[2375]: E0429 23:59:52.314506 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:53.315054 kubelet[2375]: E0429 23:59:53.314974 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:54.315731 kubelet[2375]: E0429 23:59:54.315658 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:54.518250 ntpd[1917]: Listen normally on 13 veth9018f59c [fe80::f0e8:6cff:fe76:6436%7]:123 Apr 29 23:59:54.520037 ntpd[1917]: 29 Apr 23:59:54 ntpd[1917]: Listen normally on 13 veth9018f59c [fe80::f0e8:6cff:fe76:6436%7]:123 Apr 29 23:59:55.316658 kubelet[2375]: E0429 23:59:55.316572 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:56.317812 kubelet[2375]: E0429 23:59:56.317737 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:57.318914 kubelet[2375]: E0429 23:59:57.318843 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:58.319291 kubelet[2375]: E0429 23:59:58.319218 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 29 23:59:59.319718 kubelet[2375]: E0429 23:59:59.319645 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:00.320720 kubelet[2375]: E0430 00:00:00.320593 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:01.321083 kubelet[2375]: E0430 00:00:01.321015 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:02.321773 kubelet[2375]: E0430 00:00:02.321718 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:03.323258 kubelet[2375]: E0430 00:00:03.323160 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:04.323750 kubelet[2375]: E0430 00:00:04.323677 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:05.324151 kubelet[2375]: E0430 00:00:05.324078 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:06.324822 kubelet[2375]: E0430 00:00:06.324761 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:07.325571 kubelet[2375]: E0430 00:00:07.325501 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:08.326107 kubelet[2375]: E0430 00:00:08.325955 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:09.283174 kubelet[2375]: E0430 00:00:09.283014 2375 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:09.326707 kubelet[2375]: E0430 00:00:09.326557 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:09.505913 systemd[1]: Started logrotate.service - Rotate and Compress System Logs. Apr 30 00:00:09.525397 systemd[1]: logrotate.service: Deactivated successfully. Apr 30 00:00:10.327769 kubelet[2375]: E0430 00:00:10.327691 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:10.687624 kubelet[2375]: E0430 00:00:10.687434 2375 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Apr 30 00:00:11.328616 kubelet[2375]: E0430 00:00:11.328546 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:12.328910 kubelet[2375]: E0430 00:00:12.328777 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:13.329851 kubelet[2375]: E0430 00:00:13.329776 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:14.330614 kubelet[2375]: E0430 00:00:14.330504 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:15.330856 kubelet[2375]: E0430 00:00:15.330760 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:16.331068 kubelet[2375]: E0430 00:00:16.330920 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:17.331928 kubelet[2375]: E0430 00:00:17.331831 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:18.332877 kubelet[2375]: E0430 00:00:18.332800 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:19.333919 kubelet[2375]: E0430 00:00:19.333851 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:20.334392 kubelet[2375]: E0430 00:00:20.334322 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:20.689156 kubelet[2375]: E0430 00:00:20.688827 2375 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Apr 30 00:00:21.335602 kubelet[2375]: E0430 00:00:21.335497 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:22.336465 kubelet[2375]: E0430 00:00:22.336196 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:23.336844 kubelet[2375]: E0430 00:00:23.336778 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:24.337436 kubelet[2375]: E0430 00:00:24.337355 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:25.338622 kubelet[2375]: E0430 00:00:25.338551 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:26.339410 kubelet[2375]: E0430 00:00:26.339290 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:27.339656 kubelet[2375]: E0430 00:00:27.339582 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:28.340283 kubelet[2375]: E0430 00:00:28.340205 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:29.282786 kubelet[2375]: E0430 00:00:29.282714 2375 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:29.340914 kubelet[2375]: E0430 00:00:29.340851 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:30.341339 kubelet[2375]: E0430 00:00:30.341247 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:30.689907 kubelet[2375]: E0430 00:00:30.689752 2375 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": context deadline exceeded" Apr 30 00:00:31.341887 kubelet[2375]: E0430 00:00:31.341823 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:32.343080 kubelet[2375]: E0430 00:00:32.342981 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:33.344150 kubelet[2375]: E0430 00:00:33.344076 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:34.345279 kubelet[2375]: E0430 00:00:34.345166 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:35.346316 kubelet[2375]: E0430 00:00:35.346227 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:36.346638 kubelet[2375]: E0430 00:00:36.346578 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:37.347108 kubelet[2375]: E0430 00:00:37.347015 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:38.347618 kubelet[2375]: E0430 00:00:38.347535 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:39.348129 kubelet[2375]: E0430 00:00:39.348056 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:39.760381 kubelet[2375]: E0430 00:00:39.758536 2375 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": unexpected EOF" Apr 30 00:00:39.770157 kubelet[2375]: E0430 00:00:39.770108 2375 controller.go:195] "Failed to update lease" err="Put \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": write tcp 172.31.23.46:41160->172.31.22.19:6443: write: connection reset by peer" Apr 30 00:00:39.770427 kubelet[2375]: I0430 00:00:39.770401 2375 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Apr 30 00:00:39.771283 kubelet[2375]: E0430 00:00:39.771219 2375 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": dial tcp 172.31.22.19:6443: connect: connection refused" interval="200ms" Apr 30 00:00:39.972492 kubelet[2375]: E0430 00:00:39.972418 2375 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": dial tcp 172.31.22.19:6443: connect: connection refused" interval="400ms" Apr 30 00:00:40.348564 kubelet[2375]: E0430 00:00:40.348485 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:40.373248 kubelet[2375]: E0430 00:00:40.373182 2375 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": dial tcp 172.31.22.19:6443: connect: connection refused" interval="800ms" Apr 30 00:00:41.348753 kubelet[2375]: E0430 00:00:41.348669 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:42.349188 kubelet[2375]: E0430 00:00:42.349046 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:43.349936 kubelet[2375]: E0430 00:00:43.349862 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:44.350420 kubelet[2375]: E0430 00:00:44.350356 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:45.351188 kubelet[2375]: E0430 00:00:45.351114 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:46.351417 kubelet[2375]: E0430 00:00:46.351357 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:47.351871 kubelet[2375]: E0430 00:00:47.351796 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:48.353057 kubelet[2375]: E0430 00:00:48.352985 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:49.283289 kubelet[2375]: E0430 00:00:49.283198 2375 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:49.353244 kubelet[2375]: E0430 00:00:49.353164 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:50.353972 kubelet[2375]: E0430 00:00:50.353907 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:51.174512 kubelet[2375]: E0430 00:00:51.174386 2375 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="1.6s" Apr 30 00:00:51.355114 kubelet[2375]: E0430 00:00:51.355055 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:52.356244 kubelet[2375]: E0430 00:00:52.356174 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:53.357147 kubelet[2375]: E0430 00:00:53.356998 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:54.357992 kubelet[2375]: E0430 00:00:54.357849 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:55.359028 kubelet[2375]: E0430 00:00:55.358947 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:56.359506 kubelet[2375]: E0430 00:00:56.359429 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:57.360028 kubelet[2375]: E0430 00:00:57.359903 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:58.360461 kubelet[2375]: E0430 00:00:58.360392 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:00:59.360650 kubelet[2375]: E0430 00:00:59.360568 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:01:00.361386 kubelet[2375]: E0430 00:01:00.361270 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:01:01.361955 kubelet[2375]: E0430 00:01:01.361887 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:01:02.362410 kubelet[2375]: E0430 00:01:02.362348 2375 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 00:01:02.776619 kubelet[2375]: E0430 00:01:02.776520 2375 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.22.19:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.23.46?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="3.2s"