Feb 13 16:05:42.228952 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Feb 13 16:05:42.228998 kernel: Linux version 6.6.71-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Thu Feb 13 14:34:20 -00 2025 Feb 13 16:05:42.229041 kernel: KASLR disabled due to lack of seed Feb 13 16:05:42.229064 kernel: efi: EFI v2.7 by EDK II Feb 13 16:05:42.229080 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7852ee18 Feb 13 16:05:42.229107 kernel: ACPI: Early table checksum verification disabled Feb 13 16:05:42.229130 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Feb 13 16:05:42.229147 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Feb 13 16:05:42.229164 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Feb 13 16:05:42.229180 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Feb 13 16:05:42.229204 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Feb 13 16:05:42.229220 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Feb 13 16:05:42.229236 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Feb 13 16:05:42.229252 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Feb 13 16:05:42.229270 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Feb 13 16:05:42.229291 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Feb 13 16:05:42.229309 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Feb 13 16:05:42.229325 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Feb 13 16:05:42.229342 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Feb 13 16:05:42.229358 kernel: printk: bootconsole [uart0] enabled Feb 13 16:05:42.229374 kernel: NUMA: Failed to initialise from firmware Feb 13 16:05:42.229391 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Feb 13 16:05:42.229407 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Feb 13 16:05:42.229423 kernel: Zone ranges: Feb 13 16:05:42.229439 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Feb 13 16:05:42.229455 kernel: DMA32 empty Feb 13 16:05:42.229476 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Feb 13 16:05:42.229493 kernel: Movable zone start for each node Feb 13 16:05:42.229508 kernel: Early memory node ranges Feb 13 16:05:42.229525 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Feb 13 16:05:42.229541 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Feb 13 16:05:42.229557 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Feb 13 16:05:42.229573 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Feb 13 16:05:42.229589 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Feb 13 16:05:42.229606 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Feb 13 16:05:42.229622 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Feb 13 16:05:42.229638 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Feb 13 16:05:42.229654 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Feb 13 16:05:42.229675 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Feb 13 16:05:42.229692 kernel: psci: probing for conduit method from ACPI. Feb 13 16:05:42.229716 kernel: psci: PSCIv1.0 detected in firmware. Feb 13 16:05:42.229733 kernel: psci: Using standard PSCI v0.2 function IDs Feb 13 16:05:42.229750 kernel: psci: Trusted OS migration not required Feb 13 16:05:42.229773 kernel: psci: SMC Calling Convention v1.1 Feb 13 16:05:42.229791 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Feb 13 16:05:42.229808 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Feb 13 16:05:42.229827 kernel: pcpu-alloc: [0] 0 [0] 1 Feb 13 16:05:42.229846 kernel: Detected PIPT I-cache on CPU0 Feb 13 16:05:42.229864 kernel: CPU features: detected: GIC system register CPU interface Feb 13 16:05:42.229881 kernel: CPU features: detected: Spectre-v2 Feb 13 16:05:42.229898 kernel: CPU features: detected: Spectre-v3a Feb 13 16:05:42.229915 kernel: CPU features: detected: Spectre-BHB Feb 13 16:05:42.229933 kernel: CPU features: detected: ARM erratum 1742098 Feb 13 16:05:42.229950 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Feb 13 16:05:42.229972 kernel: alternatives: applying boot alternatives Feb 13 16:05:42.229993 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=55866785c450f887021047c4ba00d104a5882975060a5fc692d64491b0d81886 Feb 13 16:05:42.230011 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Feb 13 16:05:42.233097 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Feb 13 16:05:42.233130 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Feb 13 16:05:42.233149 kernel: Fallback order for Node 0: 0 Feb 13 16:05:42.233166 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Feb 13 16:05:42.233184 kernel: Policy zone: Normal Feb 13 16:05:42.233201 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 13 16:05:42.233218 kernel: software IO TLB: area num 2. Feb 13 16:05:42.233236 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Feb 13 16:05:42.233267 kernel: Memory: 3820216K/4030464K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39360K init, 897K bss, 210248K reserved, 0K cma-reserved) Feb 13 16:05:42.233285 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Feb 13 16:05:42.233302 kernel: rcu: Preemptible hierarchical RCU implementation. Feb 13 16:05:42.233321 kernel: rcu: RCU event tracing is enabled. Feb 13 16:05:42.233339 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Feb 13 16:05:42.233357 kernel: Trampoline variant of Tasks RCU enabled. Feb 13 16:05:42.233375 kernel: Tracing variant of Tasks RCU enabled. Feb 13 16:05:42.233393 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Feb 13 16:05:42.233411 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Feb 13 16:05:42.233428 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Feb 13 16:05:42.233446 kernel: GICv3: 96 SPIs implemented Feb 13 16:05:42.233469 kernel: GICv3: 0 Extended SPIs implemented Feb 13 16:05:42.233497 kernel: Root IRQ handler: gic_handle_irq Feb 13 16:05:42.233520 kernel: GICv3: GICv3 features: 16 PPIs Feb 13 16:05:42.233539 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Feb 13 16:05:42.233556 kernel: ITS [mem 0x10080000-0x1009ffff] Feb 13 16:05:42.233575 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Feb 13 16:05:42.233593 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Feb 13 16:05:42.233610 kernel: GICv3: using LPI property table @0x00000004000d0000 Feb 13 16:05:42.233628 kernel: ITS: Using hypervisor restricted LPI range [128] Feb 13 16:05:42.233645 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Feb 13 16:05:42.233663 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 13 16:05:42.233681 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Feb 13 16:05:42.233705 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Feb 13 16:05:42.233723 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Feb 13 16:05:42.233742 kernel: Console: colour dummy device 80x25 Feb 13 16:05:42.233760 kernel: printk: console [tty1] enabled Feb 13 16:05:42.233778 kernel: ACPI: Core revision 20230628 Feb 13 16:05:42.233796 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Feb 13 16:05:42.233814 kernel: pid_max: default: 32768 minimum: 301 Feb 13 16:05:42.233831 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Feb 13 16:05:42.233849 kernel: landlock: Up and running. Feb 13 16:05:42.233871 kernel: SELinux: Initializing. Feb 13 16:05:42.233889 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 13 16:05:42.233907 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 13 16:05:42.233925 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Feb 13 16:05:42.233943 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Feb 13 16:05:42.233961 kernel: rcu: Hierarchical SRCU implementation. Feb 13 16:05:42.233979 kernel: rcu: Max phase no-delay instances is 400. Feb 13 16:05:42.233997 kernel: Platform MSI: ITS@0x10080000 domain created Feb 13 16:05:42.234014 kernel: PCI/MSI: ITS@0x10080000 domain created Feb 13 16:05:42.234057 kernel: Remapping and enabling EFI services. Feb 13 16:05:42.234078 kernel: smp: Bringing up secondary CPUs ... Feb 13 16:05:42.234096 kernel: Detected PIPT I-cache on CPU1 Feb 13 16:05:42.234113 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Feb 13 16:05:42.234132 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Feb 13 16:05:42.234149 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Feb 13 16:05:42.234167 kernel: smp: Brought up 1 node, 2 CPUs Feb 13 16:05:42.234184 kernel: SMP: Total of 2 processors activated. Feb 13 16:05:42.234202 kernel: CPU features: detected: 32-bit EL0 Support Feb 13 16:05:42.234224 kernel: CPU features: detected: 32-bit EL1 Support Feb 13 16:05:42.234243 kernel: CPU features: detected: CRC32 instructions Feb 13 16:05:42.234260 kernel: CPU: All CPU(s) started at EL1 Feb 13 16:05:42.234306 kernel: alternatives: applying system-wide alternatives Feb 13 16:05:42.234331 kernel: devtmpfs: initialized Feb 13 16:05:42.234350 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Feb 13 16:05:42.234368 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Feb 13 16:05:42.234387 kernel: pinctrl core: initialized pinctrl subsystem Feb 13 16:05:42.234405 kernel: SMBIOS 3.0.0 present. Feb 13 16:05:42.234423 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Feb 13 16:05:42.234447 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 13 16:05:42.234466 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Feb 13 16:05:42.234485 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Feb 13 16:05:42.234503 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Feb 13 16:05:42.234521 kernel: audit: initializing netlink subsys (disabled) Feb 13 16:05:42.234540 kernel: audit: type=2000 audit(0.290:1): state=initialized audit_enabled=0 res=1 Feb 13 16:05:42.234558 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 13 16:05:42.234581 kernel: cpuidle: using governor menu Feb 13 16:05:42.234600 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 13 16:05:42.234618 kernel: ASID allocator initialised with 65536 entries Feb 13 16:05:42.234636 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Feb 13 16:05:42.234655 kernel: Serial: AMBA PL011 UART driver Feb 13 16:05:42.234673 kernel: Modules: 17520 pages in range for non-PLT usage Feb 13 16:05:42.234692 kernel: Modules: 509040 pages in range for PLT usage Feb 13 16:05:42.234710 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Feb 13 16:05:42.234747 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Feb 13 16:05:42.234774 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Feb 13 16:05:42.234793 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Feb 13 16:05:42.234827 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Feb 13 16:05:42.234846 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Feb 13 16:05:42.234865 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Feb 13 16:05:42.234883 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Feb 13 16:05:42.234901 kernel: ACPI: Added _OSI(Module Device) Feb 13 16:05:42.234920 kernel: ACPI: Added _OSI(Processor Device) Feb 13 16:05:42.234938 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Feb 13 16:05:42.234962 kernel: ACPI: Added _OSI(Processor Aggregator Device) Feb 13 16:05:42.234981 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Feb 13 16:05:42.234999 kernel: ACPI: Interpreter enabled Feb 13 16:05:42.235017 kernel: ACPI: Using GIC for interrupt routing Feb 13 16:05:42.237264 kernel: ACPI: MCFG table detected, 1 entries Feb 13 16:05:42.237289 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Feb 13 16:05:42.237642 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Feb 13 16:05:42.237866 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Feb 13 16:05:42.238111 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Feb 13 16:05:42.238407 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Feb 13 16:05:42.238620 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Feb 13 16:05:42.238646 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Feb 13 16:05:42.238665 kernel: acpiphp: Slot [1] registered Feb 13 16:05:42.238684 kernel: acpiphp: Slot [2] registered Feb 13 16:05:42.238714 kernel: acpiphp: Slot [3] registered Feb 13 16:05:42.238754 kernel: acpiphp: Slot [4] registered Feb 13 16:05:42.238784 kernel: acpiphp: Slot [5] registered Feb 13 16:05:42.238804 kernel: acpiphp: Slot [6] registered Feb 13 16:05:42.238823 kernel: acpiphp: Slot [7] registered Feb 13 16:05:42.238842 kernel: acpiphp: Slot [8] registered Feb 13 16:05:42.238861 kernel: acpiphp: Slot [9] registered Feb 13 16:05:42.238879 kernel: acpiphp: Slot [10] registered Feb 13 16:05:42.238898 kernel: acpiphp: Slot [11] registered Feb 13 16:05:42.238926 kernel: acpiphp: Slot [12] registered Feb 13 16:05:42.238950 kernel: acpiphp: Slot [13] registered Feb 13 16:05:42.238970 kernel: acpiphp: Slot [14] registered Feb 13 16:05:42.238996 kernel: acpiphp: Slot [15] registered Feb 13 16:05:42.239015 kernel: acpiphp: Slot [16] registered Feb 13 16:05:42.246268 kernel: acpiphp: Slot [17] registered Feb 13 16:05:42.246298 kernel: acpiphp: Slot [18] registered Feb 13 16:05:42.246317 kernel: acpiphp: Slot [19] registered Feb 13 16:05:42.246336 kernel: acpiphp: Slot [20] registered Feb 13 16:05:42.246354 kernel: acpiphp: Slot [21] registered Feb 13 16:05:42.246373 kernel: acpiphp: Slot [22] registered Feb 13 16:05:42.246391 kernel: acpiphp: Slot [23] registered Feb 13 16:05:42.246419 kernel: acpiphp: Slot [24] registered Feb 13 16:05:42.246438 kernel: acpiphp: Slot [25] registered Feb 13 16:05:42.246456 kernel: acpiphp: Slot [26] registered Feb 13 16:05:42.246475 kernel: acpiphp: Slot [27] registered Feb 13 16:05:42.246493 kernel: acpiphp: Slot [28] registered Feb 13 16:05:42.246512 kernel: acpiphp: Slot [29] registered Feb 13 16:05:42.246530 kernel: acpiphp: Slot [30] registered Feb 13 16:05:42.246549 kernel: acpiphp: Slot [31] registered Feb 13 16:05:42.246587 kernel: PCI host bridge to bus 0000:00 Feb 13 16:05:42.246890 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Feb 13 16:05:42.247195 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Feb 13 16:05:42.247402 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Feb 13 16:05:42.247594 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Feb 13 16:05:42.247862 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Feb 13 16:05:42.251011 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Feb 13 16:05:42.251329 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Feb 13 16:05:42.251566 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Feb 13 16:05:42.251772 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Feb 13 16:05:42.251986 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Feb 13 16:05:42.252794 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Feb 13 16:05:42.253018 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Feb 13 16:05:42.253330 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Feb 13 16:05:42.253565 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Feb 13 16:05:42.253785 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Feb 13 16:05:42.254000 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Feb 13 16:05:42.254255 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Feb 13 16:05:42.254536 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Feb 13 16:05:42.254787 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Feb 13 16:05:42.255001 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Feb 13 16:05:42.257307 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Feb 13 16:05:42.257504 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Feb 13 16:05:42.257692 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Feb 13 16:05:42.257719 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Feb 13 16:05:42.257739 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Feb 13 16:05:42.257759 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Feb 13 16:05:42.257777 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Feb 13 16:05:42.257796 kernel: iommu: Default domain type: Translated Feb 13 16:05:42.257814 kernel: iommu: DMA domain TLB invalidation policy: strict mode Feb 13 16:05:42.257842 kernel: efivars: Registered efivars operations Feb 13 16:05:42.257860 kernel: vgaarb: loaded Feb 13 16:05:42.257878 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 13 16:05:42.257897 kernel: VFS: Disk quotas dquot_6.6.0 Feb 13 16:05:42.257915 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 13 16:05:42.257934 kernel: pnp: PnP ACPI init Feb 13 16:05:42.258244 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Feb 13 16:05:42.258275 kernel: pnp: PnP ACPI: found 1 devices Feb 13 16:05:42.258301 kernel: NET: Registered PF_INET protocol family Feb 13 16:05:42.258320 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Feb 13 16:05:42.258340 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Feb 13 16:05:42.258358 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 13 16:05:42.258377 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Feb 13 16:05:42.258395 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Feb 13 16:05:42.258414 kernel: TCP: Hash tables configured (established 32768 bind 32768) Feb 13 16:05:42.258433 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 13 16:05:42.258451 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 13 16:05:42.258475 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 13 16:05:42.258494 kernel: PCI: CLS 0 bytes, default 64 Feb 13 16:05:42.258512 kernel: kvm [1]: HYP mode not available Feb 13 16:05:42.258531 kernel: Initialise system trusted keyrings Feb 13 16:05:42.258549 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Feb 13 16:05:42.258567 kernel: Key type asymmetric registered Feb 13 16:05:42.258585 kernel: Asymmetric key parser 'x509' registered Feb 13 16:05:42.258604 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Feb 13 16:05:42.258622 kernel: io scheduler mq-deadline registered Feb 13 16:05:42.258645 kernel: io scheduler kyber registered Feb 13 16:05:42.258663 kernel: io scheduler bfq registered Feb 13 16:05:42.258915 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Feb 13 16:05:42.258945 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Feb 13 16:05:42.258964 kernel: ACPI: button: Power Button [PWRB] Feb 13 16:05:42.258983 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Feb 13 16:05:42.259002 kernel: ACPI: button: Sleep Button [SLPB] Feb 13 16:05:42.259020 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Feb 13 16:05:42.259136 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Feb 13 16:05:42.259361 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Feb 13 16:05:42.259388 kernel: printk: console [ttyS0] disabled Feb 13 16:05:42.259408 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Feb 13 16:05:42.259427 kernel: printk: console [ttyS0] enabled Feb 13 16:05:42.259446 kernel: printk: bootconsole [uart0] disabled Feb 13 16:05:42.259464 kernel: thunder_xcv, ver 1.0 Feb 13 16:05:42.259483 kernel: thunder_bgx, ver 1.0 Feb 13 16:05:42.259502 kernel: nicpf, ver 1.0 Feb 13 16:05:42.259527 kernel: nicvf, ver 1.0 Feb 13 16:05:42.259743 kernel: rtc-efi rtc-efi.0: registered as rtc0 Feb 13 16:05:42.259972 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-02-13T16:05:41 UTC (1739462741) Feb 13 16:05:42.260002 kernel: hid: raw HID events driver (C) Jiri Kosina Feb 13 16:05:42.262087 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Feb 13 16:05:42.262149 kernel: watchdog: Delayed init of the lockup detector failed: -19 Feb 13 16:05:42.262170 kernel: watchdog: Hard watchdog permanently disabled Feb 13 16:05:42.262189 kernel: NET: Registered PF_INET6 protocol family Feb 13 16:05:42.262218 kernel: Segment Routing with IPv6 Feb 13 16:05:42.262237 kernel: In-situ OAM (IOAM) with IPv6 Feb 13 16:05:42.262256 kernel: NET: Registered PF_PACKET protocol family Feb 13 16:05:42.262274 kernel: Key type dns_resolver registered Feb 13 16:05:42.262293 kernel: registered taskstats version 1 Feb 13 16:05:42.262312 kernel: Loading compiled-in X.509 certificates Feb 13 16:05:42.262331 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: d3f151cc07005f6a29244b13ac54c8677429c8f5' Feb 13 16:05:42.262349 kernel: Key type .fscrypt registered Feb 13 16:05:42.262367 kernel: Key type fscrypt-provisioning registered Feb 13 16:05:42.262391 kernel: ima: No TPM chip found, activating TPM-bypass! Feb 13 16:05:42.262410 kernel: ima: Allocated hash algorithm: sha1 Feb 13 16:05:42.262428 kernel: ima: No architecture policies found Feb 13 16:05:42.262447 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Feb 13 16:05:42.262465 kernel: clk: Disabling unused clocks Feb 13 16:05:42.262484 kernel: Freeing unused kernel memory: 39360K Feb 13 16:05:42.262502 kernel: Run /init as init process Feb 13 16:05:42.262520 kernel: with arguments: Feb 13 16:05:42.262538 kernel: /init Feb 13 16:05:42.262556 kernel: with environment: Feb 13 16:05:42.262579 kernel: HOME=/ Feb 13 16:05:42.262597 kernel: TERM=linux Feb 13 16:05:42.262615 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Feb 13 16:05:42.262640 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) Feb 13 16:05:42.262663 systemd[1]: Detected virtualization amazon. Feb 13 16:05:42.262684 systemd[1]: Detected architecture arm64. Feb 13 16:05:42.262734 systemd[1]: Running in initrd. Feb 13 16:05:42.262768 systemd[1]: No hostname configured, using default hostname. Feb 13 16:05:42.262791 systemd[1]: Hostname set to . Feb 13 16:05:42.262813 systemd[1]: Initializing machine ID from VM UUID. Feb 13 16:05:42.262833 systemd[1]: Queued start job for default target initrd.target. Feb 13 16:05:42.262854 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 16:05:42.262876 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 16:05:42.262898 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Feb 13 16:05:42.262919 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 16:05:42.262945 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Feb 13 16:05:42.262967 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Feb 13 16:05:42.262990 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Feb 13 16:05:42.263012 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Feb 13 16:05:42.263094 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 16:05:42.263118 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 16:05:42.263139 systemd[1]: Reached target paths.target - Path Units. Feb 13 16:05:42.263184 systemd[1]: Reached target slices.target - Slice Units. Feb 13 16:05:42.263217 systemd[1]: Reached target swap.target - Swaps. Feb 13 16:05:42.263241 systemd[1]: Reached target timers.target - Timer Units. Feb 13 16:05:42.263262 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 16:05:42.263283 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 16:05:42.263303 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Feb 13 16:05:42.263324 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Feb 13 16:05:42.263344 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 16:05:42.263364 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 16:05:42.263391 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 16:05:42.263412 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 16:05:42.263432 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Feb 13 16:05:42.263452 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 16:05:42.263472 systemd[1]: Finished network-cleanup.service - Network Cleanup. Feb 13 16:05:42.263492 systemd[1]: Starting systemd-fsck-usr.service... Feb 13 16:05:42.263512 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 16:05:42.263533 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 16:05:42.263558 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 16:05:42.263579 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Feb 13 16:05:42.263599 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 16:05:42.263619 systemd[1]: Finished systemd-fsck-usr.service. Feb 13 16:05:42.263692 systemd-journald[251]: Collecting audit messages is disabled. Feb 13 16:05:42.263743 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Feb 13 16:05:42.263766 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Feb 13 16:05:42.263786 kernel: Bridge firewalling registered Feb 13 16:05:42.263812 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 16:05:42.263833 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 16:05:42.263854 systemd-journald[251]: Journal started Feb 13 16:05:42.263892 systemd-journald[251]: Runtime Journal (/run/log/journal/ec2d91d5cad511f1cf550691d7d61a44) is 8.0M, max 75.3M, 67.3M free. Feb 13 16:05:42.195353 systemd-modules-load[252]: Inserted module 'overlay' Feb 13 16:05:42.269682 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 16:05:42.239684 systemd-modules-load[252]: Inserted module 'br_netfilter' Feb 13 16:05:42.287506 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 16:05:42.292248 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 16:05:42.298316 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 16:05:42.315306 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 16:05:42.345353 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 16:05:42.348830 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 16:05:42.366607 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 16:05:42.379376 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 16:05:42.398683 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 16:05:42.422504 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Feb 13 16:05:42.425839 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 16:05:42.456628 dracut-cmdline[287]: dracut-dracut-053 Feb 13 16:05:42.464820 dracut-cmdline[287]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=55866785c450f887021047c4ba00d104a5882975060a5fc692d64491b0d81886 Feb 13 16:05:42.524718 systemd-resolved[279]: Positive Trust Anchors: Feb 13 16:05:42.524746 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 16:05:42.524807 systemd-resolved[279]: 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 Feb 13 16:05:42.639078 kernel: SCSI subsystem initialized Feb 13 16:05:42.647147 kernel: Loading iSCSI transport class v2.0-870. Feb 13 16:05:42.661161 kernel: iscsi: registered transport (tcp) Feb 13 16:05:42.684161 kernel: iscsi: registered transport (qla4xxx) Feb 13 16:05:42.684233 kernel: QLogic iSCSI HBA Driver Feb 13 16:05:42.755201 kernel: random: crng init done Feb 13 16:05:42.754317 systemd-resolved[279]: Defaulting to hostname 'linux'. Feb 13 16:05:42.756971 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 16:05:42.761920 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 16:05:42.792714 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Feb 13 16:05:42.806454 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Feb 13 16:05:42.844194 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Feb 13 16:05:42.844273 kernel: device-mapper: uevent: version 1.0.3 Feb 13 16:05:42.844301 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Feb 13 16:05:42.913097 kernel: raid6: neonx8 gen() 6615 MB/s Feb 13 16:05:42.930084 kernel: raid6: neonx4 gen() 6396 MB/s Feb 13 16:05:42.947076 kernel: raid6: neonx2 gen() 5343 MB/s Feb 13 16:05:42.964079 kernel: raid6: neonx1 gen() 3911 MB/s Feb 13 16:05:42.981088 kernel: raid6: int64x8 gen() 3769 MB/s Feb 13 16:05:42.998082 kernel: raid6: int64x4 gen() 3657 MB/s Feb 13 16:05:43.015062 kernel: raid6: int64x2 gen() 3528 MB/s Feb 13 16:05:43.032852 kernel: raid6: int64x1 gen() 2745 MB/s Feb 13 16:05:43.032905 kernel: raid6: using algorithm neonx8 gen() 6615 MB/s Feb 13 16:05:43.050883 kernel: raid6: .... xor() 4844 MB/s, rmw enabled Feb 13 16:05:43.050981 kernel: raid6: using neon recovery algorithm Feb 13 16:05:43.059886 kernel: xor: measuring software checksum speed Feb 13 16:05:43.059976 kernel: 8regs : 10442 MB/sec Feb 13 16:05:43.061057 kernel: 32regs : 11012 MB/sec Feb 13 16:05:43.063069 kernel: arm64_neon : 9017 MB/sec Feb 13 16:05:43.063105 kernel: xor: using function: 32regs (11012 MB/sec) Feb 13 16:05:43.148078 kernel: Btrfs loaded, zoned=no, fsverity=no Feb 13 16:05:43.168230 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Feb 13 16:05:43.182321 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 16:05:43.218382 systemd-udevd[470]: Using default interface naming scheme 'v255'. Feb 13 16:05:43.226731 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 16:05:43.248194 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Feb 13 16:05:43.277937 dracut-pre-trigger[483]: rd.md=0: removing MD RAID activation Feb 13 16:05:43.340098 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 16:05:43.355362 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 16:05:43.477264 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 16:05:43.497280 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Feb 13 16:05:43.555126 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Feb 13 16:05:43.565079 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 16:05:43.570449 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 16:05:43.575672 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 16:05:43.593630 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Feb 13 16:05:43.641129 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Feb 13 16:05:43.713929 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Feb 13 16:05:43.714021 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Feb 13 16:05:43.756222 kernel: ena 0000:00:05.0: ENA device version: 0.10 Feb 13 16:05:43.756498 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Feb 13 16:05:43.756736 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:a9:c2:dc:2c:f5 Feb 13 16:05:43.732718 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 16:05:43.733013 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 16:05:43.776099 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Feb 13 16:05:43.780625 kernel: nvme nvme0: pci function 0000:00:04.0 Feb 13 16:05:43.738710 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 16:05:43.743447 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 16:05:43.797057 kernel: nvme nvme0: 2/0/0 default/read/poll queues Feb 13 16:05:43.743786 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 16:05:43.748713 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 16:05:43.765573 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 16:05:43.768875 (udev-worker)[542]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:05:43.809871 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Feb 13 16:05:43.809922 kernel: GPT:9289727 != 16777215 Feb 13 16:05:43.809948 kernel: GPT:Alternate GPT header not at the end of the disk. Feb 13 16:05:43.811265 kernel: GPT:9289727 != 16777215 Feb 13 16:05:43.812925 kernel: GPT: Use GNU Parted to correct GPT errors. Feb 13 16:05:43.814405 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Feb 13 16:05:43.828752 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 16:05:43.843339 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 16:05:43.894888 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 16:05:43.968136 kernel: BTRFS: device fsid 39fc2625-8d65-490f-9a1f-39e365051e19 devid 1 transid 40 /dev/nvme0n1p3 scanned by (udev-worker) (518) Feb 13 16:05:43.981300 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (522) Feb 13 16:05:44.033144 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Feb 13 16:05:44.091054 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Feb 13 16:05:44.107275 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Feb 13 16:05:44.115356 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Feb 13 16:05:44.133626 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Feb 13 16:05:44.155499 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Feb 13 16:05:44.171916 disk-uuid[664]: Primary Header is updated. Feb 13 16:05:44.171916 disk-uuid[664]: Secondary Entries is updated. Feb 13 16:05:44.171916 disk-uuid[664]: Secondary Header is updated. Feb 13 16:05:44.184214 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Feb 13 16:05:44.191364 kernel: GPT:disk_guids don't match. Feb 13 16:05:44.191443 kernel: GPT: Use GNU Parted to correct GPT errors. Feb 13 16:05:44.191471 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Feb 13 16:05:44.202077 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Feb 13 16:05:45.202083 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Feb 13 16:05:45.203583 disk-uuid[665]: The operation has completed successfully. Feb 13 16:05:45.402904 systemd[1]: disk-uuid.service: Deactivated successfully. Feb 13 16:05:45.403154 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Feb 13 16:05:45.442312 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Feb 13 16:05:45.453375 sh[1006]: Success Feb 13 16:05:45.481083 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Feb 13 16:05:45.583970 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Feb 13 16:05:45.608299 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Feb 13 16:05:45.613928 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Feb 13 16:05:45.641431 kernel: BTRFS info (device dm-0): first mount of filesystem 39fc2625-8d65-490f-9a1f-39e365051e19 Feb 13 16:05:45.641494 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Feb 13 16:05:45.641521 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Feb 13 16:05:45.643095 kernel: BTRFS info (device dm-0): disabling log replay at mount time Feb 13 16:05:45.644291 kernel: BTRFS info (device dm-0): using free space tree Feb 13 16:05:45.806073 kernel: BTRFS info (device dm-0): enabling ssd optimizations Feb 13 16:05:45.836314 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Feb 13 16:05:45.842111 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Feb 13 16:05:45.855299 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Feb 13 16:05:45.867349 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Feb 13 16:05:45.883717 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c8afbf79-805d-40d9-b4c9-cafa51441c41 Feb 13 16:05:45.883795 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Feb 13 16:05:45.883824 kernel: BTRFS info (device nvme0n1p6): using free space tree Feb 13 16:05:45.889100 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Feb 13 16:05:45.904067 systemd[1]: mnt-oem.mount: Deactivated successfully. Feb 13 16:05:45.907859 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem c8afbf79-805d-40d9-b4c9-cafa51441c41 Feb 13 16:05:45.930618 systemd[1]: Finished ignition-setup.service - Ignition (setup). Feb 13 16:05:45.942389 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Feb 13 16:05:46.063016 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 16:05:46.078435 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 16:05:46.134906 systemd-networkd[1198]: lo: Link UP Feb 13 16:05:46.134929 systemd-networkd[1198]: lo: Gained carrier Feb 13 16:05:46.138463 systemd-networkd[1198]: Enumeration completed Feb 13 16:05:46.139660 systemd-networkd[1198]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 16:05:46.139667 systemd-networkd[1198]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 16:05:46.143204 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 16:05:46.152165 systemd-networkd[1198]: eth0: Link UP Feb 13 16:05:46.152173 systemd-networkd[1198]: eth0: Gained carrier Feb 13 16:05:46.152191 systemd-networkd[1198]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 16:05:46.161170 systemd[1]: Reached target network.target - Network. Feb 13 16:05:46.183150 systemd-networkd[1198]: eth0: DHCPv4 address 172.31.21.70/20, gateway 172.31.16.1 acquired from 172.31.16.1 Feb 13 16:05:46.417720 ignition[1102]: Ignition 2.19.0 Feb 13 16:05:46.417751 ignition[1102]: Stage: fetch-offline Feb 13 16:05:46.418421 ignition[1102]: no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:46.418449 ignition[1102]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:46.425549 ignition[1102]: Ignition finished successfully Feb 13 16:05:46.433332 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 16:05:46.448404 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Feb 13 16:05:46.479141 ignition[1209]: Ignition 2.19.0 Feb 13 16:05:46.479177 ignition[1209]: Stage: fetch Feb 13 16:05:46.479845 ignition[1209]: no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:46.479872 ignition[1209]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:46.480596 ignition[1209]: PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:46.504556 ignition[1209]: PUT result: OK Feb 13 16:05:46.508460 ignition[1209]: parsed url from cmdline: "" Feb 13 16:05:46.508479 ignition[1209]: no config URL provided Feb 13 16:05:46.508496 ignition[1209]: reading system config file "/usr/lib/ignition/user.ign" Feb 13 16:05:46.508525 ignition[1209]: no config at "/usr/lib/ignition/user.ign" Feb 13 16:05:46.508575 ignition[1209]: PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:46.511158 ignition[1209]: PUT result: OK Feb 13 16:05:46.511304 ignition[1209]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Feb 13 16:05:46.518603 ignition[1209]: GET result: OK Feb 13 16:05:46.528356 unknown[1209]: fetched base config from "system" Feb 13 16:05:46.522884 ignition[1209]: parsing config with SHA512: c032fbbe4504602ddfb8fcfbef5318582cd37bb3aff9b0023b605a5c6392f35b1421189b8920f2cdc1e41213b3a0edf7910ca474a9ceeb8c5a1a7720bb246e1c Feb 13 16:05:46.528373 unknown[1209]: fetched base config from "system" Feb 13 16:05:46.528873 ignition[1209]: fetch: fetch complete Feb 13 16:05:46.528387 unknown[1209]: fetched user config from "aws" Feb 13 16:05:46.528886 ignition[1209]: fetch: fetch passed Feb 13 16:05:46.534483 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Feb 13 16:05:46.528976 ignition[1209]: Ignition finished successfully Feb 13 16:05:46.553691 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Feb 13 16:05:46.583549 ignition[1217]: Ignition 2.19.0 Feb 13 16:05:46.585513 ignition[1217]: Stage: kargs Feb 13 16:05:46.587593 ignition[1217]: no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:46.587619 ignition[1217]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:46.587769 ignition[1217]: PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:46.590759 ignition[1217]: PUT result: OK Feb 13 16:05:46.600568 ignition[1217]: kargs: kargs passed Feb 13 16:05:46.600684 ignition[1217]: Ignition finished successfully Feb 13 16:05:46.608169 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Feb 13 16:05:46.629191 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Feb 13 16:05:46.655904 ignition[1223]: Ignition 2.19.0 Feb 13 16:05:46.655930 ignition[1223]: Stage: disks Feb 13 16:05:46.657484 ignition[1223]: no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:46.657515 ignition[1223]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:46.657712 ignition[1223]: PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:46.660446 ignition[1223]: PUT result: OK Feb 13 16:05:46.667833 ignition[1223]: disks: disks passed Feb 13 16:05:46.667934 ignition[1223]: Ignition finished successfully Feb 13 16:05:46.673220 systemd[1]: Finished ignition-disks.service - Ignition (disks). Feb 13 16:05:46.677982 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Feb 13 16:05:46.682935 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Feb 13 16:05:46.693900 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 16:05:46.694895 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 16:05:46.695544 systemd[1]: Reached target basic.target - Basic System. Feb 13 16:05:46.714459 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Feb 13 16:05:46.764825 systemd-fsck[1231]: ROOT: clean, 14/553520 files, 52654/553472 blocks Feb 13 16:05:46.777110 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Feb 13 16:05:46.792394 systemd[1]: Mounting sysroot.mount - /sysroot... Feb 13 16:05:46.876061 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 1daf3470-d909-4a02-84d2-f6d9b0a5b55c r/w with ordered data mode. Quota mode: none. Feb 13 16:05:46.878005 systemd[1]: Mounted sysroot.mount - /sysroot. Feb 13 16:05:46.882968 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Feb 13 16:05:46.907308 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 16:05:46.913337 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Feb 13 16:05:46.922686 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Feb 13 16:05:46.924469 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Feb 13 16:05:46.939491 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1250) Feb 13 16:05:46.924556 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 16:05:46.954210 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c8afbf79-805d-40d9-b4c9-cafa51441c41 Feb 13 16:05:46.954289 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Feb 13 16:05:46.954333 kernel: BTRFS info (device nvme0n1p6): using free space tree Feb 13 16:05:46.954361 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Feb 13 16:05:46.961466 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 16:05:46.969633 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Feb 13 16:05:46.981403 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Feb 13 16:05:47.579962 initrd-setup-root[1274]: cut: /sysroot/etc/passwd: No such file or directory Feb 13 16:05:47.603480 initrd-setup-root[1281]: cut: /sysroot/etc/group: No such file or directory Feb 13 16:05:47.611823 initrd-setup-root[1288]: cut: /sysroot/etc/shadow: No such file or directory Feb 13 16:05:47.620944 initrd-setup-root[1295]: cut: /sysroot/etc/gshadow: No such file or directory Feb 13 16:05:47.987647 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Feb 13 16:05:48.008333 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Feb 13 16:05:48.015639 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Feb 13 16:05:48.045363 systemd[1]: sysroot-oem.mount: Deactivated successfully. Feb 13 16:05:48.050162 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem c8afbf79-805d-40d9-b4c9-cafa51441c41 Feb 13 16:05:48.078745 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Feb 13 16:05:48.091280 ignition[1363]: INFO : Ignition 2.19.0 Feb 13 16:05:48.091280 ignition[1363]: INFO : Stage: mount Feb 13 16:05:48.095183 ignition[1363]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:48.095183 ignition[1363]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:48.095183 ignition[1363]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:48.094271 systemd-networkd[1198]: eth0: Gained IPv6LL Feb 13 16:05:48.106084 ignition[1363]: INFO : PUT result: OK Feb 13 16:05:48.111305 ignition[1363]: INFO : mount: mount passed Feb 13 16:05:48.113447 ignition[1363]: INFO : Ignition finished successfully Feb 13 16:05:48.117852 systemd[1]: Finished ignition-mount.service - Ignition (mount). Feb 13 16:05:48.139393 systemd[1]: Starting ignition-files.service - Ignition (files)... Feb 13 16:05:48.163447 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 16:05:48.184071 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1375) Feb 13 16:05:48.188101 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c8afbf79-805d-40d9-b4c9-cafa51441c41 Feb 13 16:05:48.188156 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Feb 13 16:05:48.189340 kernel: BTRFS info (device nvme0n1p6): using free space tree Feb 13 16:05:48.194070 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Feb 13 16:05:48.197572 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 16:05:48.246864 ignition[1391]: INFO : Ignition 2.19.0 Feb 13 16:05:48.250220 ignition[1391]: INFO : Stage: files Feb 13 16:05:48.250220 ignition[1391]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:48.250220 ignition[1391]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:48.250220 ignition[1391]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:48.266996 ignition[1391]: INFO : PUT result: OK Feb 13 16:05:48.272531 ignition[1391]: DEBUG : files: compiled without relabeling support, skipping Feb 13 16:05:48.276606 ignition[1391]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Feb 13 16:05:48.276606 ignition[1391]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Feb 13 16:05:48.302844 ignition[1391]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Feb 13 16:05:48.306565 ignition[1391]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Feb 13 16:05:48.312296 unknown[1391]: wrote ssh authorized keys file for user: core Feb 13 16:05:48.316262 ignition[1391]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Feb 13 16:05:48.319513 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Feb 13 16:05:48.325151 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Feb 13 16:05:48.691709 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Feb 13 16:05:49.055329 ignition[1391]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Feb 13 16:05:49.071190 ignition[1391]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Feb 13 16:05:49.071190 ignition[1391]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Feb 13 16:05:49.071190 ignition[1391]: INFO : files: files passed Feb 13 16:05:49.071190 ignition[1391]: INFO : Ignition finished successfully Feb 13 16:05:49.071373 systemd[1]: Finished ignition-files.service - Ignition (files). Feb 13 16:05:49.100822 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Feb 13 16:05:49.108331 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Feb 13 16:05:49.121609 systemd[1]: ignition-quench.service: Deactivated successfully. Feb 13 16:05:49.124388 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Feb 13 16:05:49.143406 initrd-setup-root-after-ignition[1420]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 16:05:49.143406 initrd-setup-root-after-ignition[1420]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Feb 13 16:05:49.151947 initrd-setup-root-after-ignition[1424]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 16:05:49.152090 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 16:05:49.154327 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Feb 13 16:05:49.175559 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Feb 13 16:05:49.230662 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Feb 13 16:05:49.231277 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Feb 13 16:05:49.243469 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Feb 13 16:05:49.246078 systemd[1]: Reached target initrd.target - Initrd Default Target. Feb 13 16:05:49.248900 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Feb 13 16:05:49.271010 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Feb 13 16:05:49.306195 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 16:05:49.330496 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Feb 13 16:05:49.356724 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Feb 13 16:05:49.363442 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 16:05:49.366261 systemd[1]: Stopped target timers.target - Timer Units. Feb 13 16:05:49.368857 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Feb 13 16:05:49.369158 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 16:05:49.381453 systemd[1]: Stopped target initrd.target - Initrd Default Target. Feb 13 16:05:49.385904 systemd[1]: Stopped target basic.target - Basic System. Feb 13 16:05:49.388982 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Feb 13 16:05:49.391808 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 16:05:49.394989 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Feb 13 16:05:49.397843 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Feb 13 16:05:49.400521 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 16:05:49.404013 systemd[1]: Stopped target sysinit.target - System Initialization. Feb 13 16:05:49.423180 systemd[1]: Stopped target local-fs.target - Local File Systems. Feb 13 16:05:49.425566 systemd[1]: Stopped target swap.target - Swaps. Feb 13 16:05:49.427595 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Feb 13 16:05:49.427864 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Feb 13 16:05:49.439668 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Feb 13 16:05:49.442783 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 16:05:49.445911 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Feb 13 16:05:49.454328 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 16:05:49.457346 systemd[1]: dracut-initqueue.service: Deactivated successfully. Feb 13 16:05:49.457918 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Feb 13 16:05:49.466955 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Feb 13 16:05:49.467577 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 16:05:49.477301 systemd[1]: ignition-files.service: Deactivated successfully. Feb 13 16:05:49.478201 systemd[1]: Stopped ignition-files.service - Ignition (files). Feb 13 16:05:49.503232 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Feb 13 16:05:49.522505 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Feb 13 16:05:49.526984 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Feb 13 16:05:49.543156 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 16:05:49.553523 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Feb 13 16:05:49.553817 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 16:05:49.572958 systemd[1]: initrd-cleanup.service: Deactivated successfully. Feb 13 16:05:49.575211 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Feb 13 16:05:49.583079 ignition[1444]: INFO : Ignition 2.19.0 Feb 13 16:05:49.583079 ignition[1444]: INFO : Stage: umount Feb 13 16:05:49.583079 ignition[1444]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 16:05:49.583079 ignition[1444]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Feb 13 16:05:49.593685 ignition[1444]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Feb 13 16:05:49.593685 ignition[1444]: INFO : PUT result: OK Feb 13 16:05:49.587751 systemd[1]: sysroot-boot.mount: Deactivated successfully. Feb 13 16:05:49.608125 ignition[1444]: INFO : umount: umount passed Feb 13 16:05:49.610716 ignition[1444]: INFO : Ignition finished successfully Feb 13 16:05:49.614677 systemd[1]: ignition-mount.service: Deactivated successfully. Feb 13 16:05:49.614973 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Feb 13 16:05:49.623168 systemd[1]: sysroot-boot.service: Deactivated successfully. Feb 13 16:05:49.623398 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Feb 13 16:05:49.627984 systemd[1]: ignition-disks.service: Deactivated successfully. Feb 13 16:05:49.628267 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Feb 13 16:05:49.634463 systemd[1]: ignition-kargs.service: Deactivated successfully. Feb 13 16:05:49.634578 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Feb 13 16:05:49.637874 systemd[1]: ignition-fetch.service: Deactivated successfully. Feb 13 16:05:49.637983 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Feb 13 16:05:49.640773 systemd[1]: Stopped target network.target - Network. Feb 13 16:05:49.643495 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Feb 13 16:05:49.643621 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 16:05:49.659449 systemd[1]: Stopped target paths.target - Path Units. Feb 13 16:05:49.661574 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Feb 13 16:05:49.666802 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 16:05:49.679594 systemd[1]: Stopped target slices.target - Slice Units. Feb 13 16:05:49.682791 systemd[1]: Stopped target sockets.target - Socket Units. Feb 13 16:05:49.687890 systemd[1]: iscsid.socket: Deactivated successfully. Feb 13 16:05:49.688112 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 16:05:49.691949 systemd[1]: iscsiuio.socket: Deactivated successfully. Feb 13 16:05:49.692064 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 16:05:49.694588 systemd[1]: ignition-setup.service: Deactivated successfully. Feb 13 16:05:49.694713 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Feb 13 16:05:49.697195 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Feb 13 16:05:49.697308 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Feb 13 16:05:49.699997 systemd[1]: initrd-setup-root.service: Deactivated successfully. Feb 13 16:05:49.700144 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Feb 13 16:05:49.707959 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Feb 13 16:05:49.712671 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Feb 13 16:05:49.725185 systemd-networkd[1198]: eth0: DHCPv6 lease lost Feb 13 16:05:49.731719 systemd[1]: systemd-resolved.service: Deactivated successfully. Feb 13 16:05:49.732321 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Feb 13 16:05:49.746613 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 13 16:05:49.747066 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Feb 13 16:05:49.756576 systemd[1]: systemd-networkd.socket: Deactivated successfully. Feb 13 16:05:49.756723 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Feb 13 16:05:49.780223 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Feb 13 16:05:49.785069 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Feb 13 16:05:49.785207 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 16:05:49.788941 systemd[1]: systemd-sysctl.service: Deactivated successfully. Feb 13 16:05:49.789265 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Feb 13 16:05:49.806175 systemd[1]: systemd-modules-load.service: Deactivated successfully. Feb 13 16:05:49.806516 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Feb 13 16:05:49.813997 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Feb 13 16:05:49.814153 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 16:05:49.825522 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 16:05:49.851866 systemd[1]: systemd-udevd.service: Deactivated successfully. Feb 13 16:05:49.852257 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 16:05:49.858168 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Feb 13 16:05:49.858334 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Feb 13 16:05:49.863151 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Feb 13 16:05:49.863246 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 16:05:49.868489 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Feb 13 16:05:49.868609 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Feb 13 16:05:49.873861 systemd[1]: dracut-cmdline.service: Deactivated successfully. Feb 13 16:05:49.874047 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Feb 13 16:05:49.879097 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 16:05:49.879215 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 16:05:49.898944 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Feb 13 16:05:49.909611 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Feb 13 16:05:49.909752 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 16:05:49.914427 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Feb 13 16:05:49.914548 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 16:05:49.926801 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Feb 13 16:05:49.926916 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 16:05:49.930018 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 16:05:49.930193 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 16:05:49.943571 systemd[1]: network-cleanup.service: Deactivated successfully. Feb 13 16:05:49.943782 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Feb 13 16:05:49.968803 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Feb 13 16:05:49.969110 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Feb 13 16:05:49.975531 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Feb 13 16:05:49.992351 systemd[1]: Starting initrd-switch-root.service - Switch Root... Feb 13 16:05:50.009512 systemd[1]: Switching root. Feb 13 16:05:50.079751 systemd-journald[251]: Journal stopped Feb 13 16:05:53.366390 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Feb 13 16:05:53.366630 kernel: SELinux: policy capability network_peer_controls=1 Feb 13 16:05:53.366750 kernel: SELinux: policy capability open_perms=1 Feb 13 16:05:53.370189 kernel: SELinux: policy capability extended_socket_class=1 Feb 13 16:05:53.370235 kernel: SELinux: policy capability always_check_network=0 Feb 13 16:05:53.370269 kernel: SELinux: policy capability cgroup_seclabel=1 Feb 13 16:05:53.370312 kernel: SELinux: policy capability nnp_nosuid_transition=1 Feb 13 16:05:53.370344 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Feb 13 16:05:53.370374 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Feb 13 16:05:53.370413 kernel: audit: type=1403 audit(1739462751.239:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 13 16:05:53.370447 systemd[1]: Successfully loaded SELinux policy in 61.821ms. Feb 13 16:05:53.370495 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.900ms. Feb 13 16:05:53.370538 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) Feb 13 16:05:53.370573 systemd[1]: Detected virtualization amazon. Feb 13 16:05:53.370615 systemd[1]: Detected architecture arm64. Feb 13 16:05:53.370648 systemd[1]: Detected first boot. Feb 13 16:05:53.370682 systemd[1]: Initializing machine ID from VM UUID. Feb 13 16:05:53.370745 zram_generator::config[1486]: No configuration found. Feb 13 16:05:53.370789 systemd[1]: Populated /etc with preset unit settings. Feb 13 16:05:53.370842 systemd[1]: initrd-switch-root.service: Deactivated successfully. Feb 13 16:05:53.370877 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Feb 13 16:05:53.370911 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Feb 13 16:05:53.370942 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Feb 13 16:05:53.370983 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Feb 13 16:05:53.371017 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 13 16:05:53.371133 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Feb 13 16:05:53.371177 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Feb 13 16:05:53.371212 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Feb 13 16:05:53.371257 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Feb 13 16:05:53.371300 systemd[1]: Created slice user.slice - User and Session Slice. Feb 13 16:05:53.371333 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 16:05:53.371366 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 16:05:53.371398 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Feb 13 16:05:53.371430 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Feb 13 16:05:53.371469 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Feb 13 16:05:53.371502 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 16:05:53.371533 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Feb 13 16:05:53.371563 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 16:05:53.371592 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Feb 13 16:05:53.371622 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Feb 13 16:05:53.371655 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Feb 13 16:05:53.371687 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Feb 13 16:05:53.371722 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 16:05:53.376139 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 16:05:53.376199 systemd[1]: Reached target slices.target - Slice Units. Feb 13 16:05:53.376233 systemd[1]: Reached target swap.target - Swaps. Feb 13 16:05:53.376263 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Feb 13 16:05:53.376296 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Feb 13 16:05:53.376329 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 16:05:53.376359 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 16:05:53.376391 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 16:05:53.376429 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Feb 13 16:05:53.376462 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Feb 13 16:05:53.376494 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Feb 13 16:05:53.376525 systemd[1]: Mounting media.mount - External Media Directory... Feb 13 16:05:53.376556 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Feb 13 16:05:53.376588 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Feb 13 16:05:53.376620 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Feb 13 16:05:53.376653 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). Feb 13 16:05:53.376685 systemd[1]: Reached target machines.target - Containers. Feb 13 16:05:53.376722 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Feb 13 16:05:53.376754 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 16:05:53.376785 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 16:05:53.376817 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Feb 13 16:05:53.376851 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 16:05:53.376882 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 16:05:53.376912 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 16:05:53.376941 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Feb 13 16:05:53.376977 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 16:05:53.377008 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Feb 13 16:05:53.377058 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Feb 13 16:05:53.377095 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Feb 13 16:05:53.377126 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Feb 13 16:05:53.377158 systemd[1]: Stopped systemd-fsck-usr.service. Feb 13 16:05:53.377192 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 16:05:53.377221 kernel: loop: module loaded Feb 13 16:05:53.377249 kernel: fuse: init (API version 7.39) Feb 13 16:05:53.377282 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 16:05:53.377313 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Feb 13 16:05:53.377346 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Feb 13 16:05:53.377378 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 16:05:53.377410 systemd[1]: verity-setup.service: Deactivated successfully. Feb 13 16:05:53.377441 systemd[1]: Stopped verity-setup.service. Feb 13 16:05:53.377470 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Feb 13 16:05:53.377500 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Feb 13 16:05:53.377529 systemd[1]: Mounted media.mount - External Media Directory. Feb 13 16:05:53.377564 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Feb 13 16:05:53.377596 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Feb 13 16:05:53.377629 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Feb 13 16:05:53.377659 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 16:05:53.377689 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Feb 13 16:05:53.377723 kernel: ACPI: bus type drm_connector registered Feb 13 16:05:53.377808 systemd-journald[1571]: Collecting audit messages is disabled. Feb 13 16:05:53.377858 systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 13 16:05:53.377889 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Feb 13 16:05:53.377921 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 16:05:53.377952 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 16:05:53.377983 systemd-journald[1571]: Journal started Feb 13 16:05:53.380157 systemd-journald[1571]: Runtime Journal (/run/log/journal/ec2d91d5cad511f1cf550691d7d61a44) is 8.0M, max 75.3M, 67.3M free. Feb 13 16:05:52.687019 systemd[1]: Queued start job for default target multi-user.target. Feb 13 16:05:52.763836 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Feb 13 16:05:52.764640 systemd[1]: systemd-journald.service: Deactivated successfully. Feb 13 16:05:53.389063 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 16:05:53.389584 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 16:05:53.390171 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 16:05:53.395789 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 16:05:53.396283 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 16:05:53.401654 systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 13 16:05:53.401945 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Feb 13 16:05:53.405427 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 16:05:53.405863 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 16:05:53.411141 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 16:05:53.417643 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Feb 13 16:05:53.423712 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Feb 13 16:05:53.458848 systemd[1]: Reached target network-pre.target - Preparation for Network. Feb 13 16:05:53.472276 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Feb 13 16:05:53.484245 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Feb 13 16:05:53.490610 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Feb 13 16:05:53.490689 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 16:05:53.500408 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Feb 13 16:05:53.517480 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Feb 13 16:05:53.531397 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Feb 13 16:05:53.535928 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 16:05:53.538582 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Feb 13 16:05:53.552707 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Feb 13 16:05:53.558337 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 16:05:53.567681 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Feb 13 16:05:53.572407 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 16:05:53.579396 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 16:05:53.594495 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Feb 13 16:05:53.602769 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Feb 13 16:05:53.612508 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 16:05:53.616910 systemd-journald[1571]: Time spent on flushing to /var/log/journal/ec2d91d5cad511f1cf550691d7d61a44 is 175.678ms for 892 entries. Feb 13 16:05:53.616910 systemd-journald[1571]: System Journal (/var/log/journal/ec2d91d5cad511f1cf550691d7d61a44) is 8.0M, max 195.6M, 187.6M free. Feb 13 16:05:53.833688 systemd-journald[1571]: Received client request to flush runtime journal. Feb 13 16:05:53.833998 kernel: loop0: detected capacity change from 0 to 52536 Feb 13 16:05:53.621287 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Feb 13 16:05:53.626932 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Feb 13 16:05:53.637795 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Feb 13 16:05:53.657865 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Feb 13 16:05:53.665810 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Feb 13 16:05:53.681375 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Feb 13 16:05:53.695708 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Feb 13 16:05:53.752601 udevadm[1624]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Feb 13 16:05:53.790609 systemd-tmpfiles[1616]: ACLs are not supported, ignoring. Feb 13 16:05:53.790634 systemd-tmpfiles[1616]: ACLs are not supported, ignoring. Feb 13 16:05:53.827634 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 16:05:53.841761 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Feb 13 16:05:53.848535 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 16:05:53.857502 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Feb 13 16:05:53.861113 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Feb 13 16:05:53.879528 systemd[1]: Starting systemd-sysusers.service - Create System Users... Feb 13 16:05:53.902072 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 13 16:05:53.946076 kernel: loop1: detected capacity change from 0 to 114328 Feb 13 16:05:53.973774 systemd[1]: Finished systemd-sysusers.service - Create System Users. Feb 13 16:05:53.991383 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 16:05:54.051401 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. Feb 13 16:05:54.051442 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. Feb 13 16:05:54.070127 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 16:05:54.091156 kernel: loop2: detected capacity change from 0 to 114432 Feb 13 16:05:54.200100 kernel: loop3: detected capacity change from 0 to 194512 Feb 13 16:05:54.251128 kernel: loop4: detected capacity change from 0 to 52536 Feb 13 16:05:54.270078 kernel: loop5: detected capacity change from 0 to 114328 Feb 13 16:05:54.283070 kernel: loop6: detected capacity change from 0 to 114432 Feb 13 16:05:54.305110 kernel: loop7: detected capacity change from 0 to 194512 Feb 13 16:05:54.323743 (sd-merge)[1643]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Feb 13 16:05:54.324803 (sd-merge)[1643]: Merged extensions into '/usr'. Feb 13 16:05:54.338415 systemd[1]: Reloading requested from client PID 1615 ('systemd-sysext') (unit systemd-sysext.service)... Feb 13 16:05:54.338447 systemd[1]: Reloading... Feb 13 16:05:54.527296 zram_generator::config[1666]: No configuration found. Feb 13 16:05:54.870741 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. Feb 13 16:05:54.987595 systemd[1]: Reloading finished in 647 ms. Feb 13 16:05:55.033600 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Feb 13 16:05:55.038538 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Feb 13 16:05:55.054353 systemd[1]: Starting ensure-sysext.service... Feb 13 16:05:55.059414 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 16:05:55.076647 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 16:05:55.101300 systemd[1]: Reloading requested from client PID 1721 ('systemctl') (unit ensure-sysext.service)... Feb 13 16:05:55.101331 systemd[1]: Reloading... Feb 13 16:05:55.134384 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Feb 13 16:05:55.135232 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Feb 13 16:05:55.138966 systemd-tmpfiles[1722]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Feb 13 16:05:55.140364 systemd-tmpfiles[1722]: ACLs are not supported, ignoring. Feb 13 16:05:55.140517 systemd-tmpfiles[1722]: ACLs are not supported, ignoring. Feb 13 16:05:55.148388 systemd-tmpfiles[1722]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 16:05:55.148416 systemd-tmpfiles[1722]: Skipping /boot Feb 13 16:05:55.175808 systemd-tmpfiles[1722]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 16:05:55.175838 systemd-tmpfiles[1722]: Skipping /boot Feb 13 16:05:55.206825 systemd-udevd[1723]: Using default interface naming scheme 'v255'. Feb 13 16:05:55.231097 zram_generator::config[1747]: No configuration found. Feb 13 16:05:55.492278 (udev-worker)[1762]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:05:55.689266 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. Feb 13 16:05:55.724665 ldconfig[1610]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Feb 13 16:05:55.783246 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1784) Feb 13 16:05:55.856837 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Feb 13 16:05:55.857229 systemd[1]: Reloading finished in 755 ms. Feb 13 16:05:55.892381 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 16:05:55.898533 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Feb 13 16:05:55.935313 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 16:05:55.994156 systemd[1]: Finished ensure-sysext.service. Feb 13 16:05:56.032532 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Feb 13 16:05:56.040549 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Feb 13 16:05:56.045662 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 16:05:56.047868 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 16:05:56.057410 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 16:05:56.085342 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 16:05:56.092532 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 16:05:56.097347 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 16:05:56.101051 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Feb 13 16:05:56.111527 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 16:05:56.121430 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 16:05:56.125826 systemd[1]: Reached target time-set.target - System Time Set. Feb 13 16:05:56.132525 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Feb 13 16:05:56.140965 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 16:05:56.146700 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 16:05:56.147174 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 16:05:56.165255 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 16:05:56.196784 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Feb 13 16:05:56.208141 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Feb 13 16:05:56.224900 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 16:05:56.225916 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 16:05:56.226786 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 16:05:56.227443 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 16:05:56.237882 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Feb 13 16:05:56.251711 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Feb 13 16:05:56.264675 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Feb 13 16:05:56.269804 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 16:05:56.272148 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 16:05:56.277966 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 16:05:56.316456 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Feb 13 16:05:56.326979 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Feb 13 16:05:56.332731 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). Feb 13 16:05:56.364128 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Feb 13 16:05:56.375295 systemd[1]: Starting systemd-update-done.service - Update is Completed... Feb 13 16:05:56.384341 lvm[1944]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 16:05:56.407164 augenrules[1960]: No rules Feb 13 16:05:56.406573 systemd[1]: Finished systemd-update-done.service - Update is Completed. Feb 13 16:05:56.410274 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Feb 13 16:05:56.418056 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Feb 13 16:05:56.468900 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Feb 13 16:05:56.469421 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 16:05:56.487578 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Feb 13 16:05:56.491310 systemd[1]: Started systemd-userdbd.service - User Database Manager. Feb 13 16:05:56.530100 lvm[1970]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 16:05:56.545813 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 16:05:56.581121 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Feb 13 16:05:56.636724 systemd-networkd[1933]: lo: Link UP Feb 13 16:05:56.636744 systemd-networkd[1933]: lo: Gained carrier Feb 13 16:05:56.637973 systemd-resolved[1934]: Positive Trust Anchors: Feb 13 16:05:56.638460 systemd-resolved[1934]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 16:05:56.638529 systemd-resolved[1934]: 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 Feb 13 16:05:56.639648 systemd-networkd[1933]: Enumeration completed Feb 13 16:05:56.639847 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 16:05:56.644967 systemd-networkd[1933]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 16:05:56.644989 systemd-networkd[1933]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 16:05:56.649272 systemd-networkd[1933]: eth0: Link UP Feb 13 16:05:56.649575 systemd-networkd[1933]: eth0: Gained carrier Feb 13 16:05:56.649610 systemd-networkd[1933]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 16:05:56.652126 systemd-resolved[1934]: Defaulting to hostname 'linux'. Feb 13 16:05:56.652377 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Feb 13 16:05:56.660674 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 16:05:56.665646 systemd[1]: Reached target network.target - Network. Feb 13 16:05:56.669871 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 16:05:56.673292 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 16:05:56.676357 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Feb 13 16:05:56.679448 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Feb 13 16:05:56.682565 systemd[1]: Started logrotate.timer - Daily rotation of log files. Feb 13 16:05:56.685273 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Feb 13 16:05:56.688124 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Feb 13 16:05:56.691071 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Feb 13 16:05:56.691124 systemd[1]: Reached target paths.target - Path Units. Feb 13 16:05:56.693198 systemd[1]: Reached target timers.target - Timer Units. Feb 13 16:05:56.695606 systemd-networkd[1933]: eth0: DHCPv4 address 172.31.21.70/20, gateway 172.31.16.1 acquired from 172.31.16.1 Feb 13 16:05:56.696985 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Feb 13 16:05:56.702743 systemd[1]: Starting docker.socket - Docker Socket for the API... Feb 13 16:05:56.712340 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Feb 13 16:05:56.716011 systemd[1]: Listening on docker.socket - Docker Socket for the API. Feb 13 16:05:56.718958 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 16:05:56.721769 systemd[1]: Reached target basic.target - Basic System. Feb 13 16:05:56.724170 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Feb 13 16:05:56.724224 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Feb 13 16:05:56.731325 systemd[1]: Starting containerd.service - containerd container runtime... Feb 13 16:05:56.742427 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Feb 13 16:05:56.751166 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Feb 13 16:05:56.756508 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Feb 13 16:05:56.769846 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Feb 13 16:05:56.774255 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). Feb 13 16:05:56.783395 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Feb 13 16:05:56.794789 jq[1987]: false Feb 13 16:05:56.793390 systemd[1]: Started ntpd.service - Network Time Service. Feb 13 16:05:56.802569 systemd[1]: Starting setup-oem.service - Setup OEM... Feb 13 16:05:56.806755 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Feb 13 16:05:56.812617 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Feb 13 16:05:56.820361 systemd[1]: Starting systemd-logind.service - User Login Management... Feb 13 16:05:56.823674 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Feb 13 16:05:56.824520 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Feb 13 16:05:56.827958 systemd[1]: Starting update-engine.service - Update Engine... Feb 13 16:05:56.833249 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Feb 13 16:05:56.839438 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Feb 13 16:05:56.839877 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Feb 13 16:05:56.914828 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Feb 13 16:05:56.919173 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Feb 13 16:05:56.963386 jq[1996]: true Feb 13 16:05:56.985349 dbus-daemon[1986]: [system] SELinux support is enabled Feb 13 16:05:56.988959 update_engine[1995]: I20250213 16:05:56.983955 1995 main.cc:92] Flatcar Update Engine starting Feb 13 16:05:56.991516 systemd[1]: Started dbus.service - D-Bus System Message Bus. Feb 13 16:05:56.995297 dbus-daemon[1986]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1933 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Feb 13 16:05:56.995747 update_engine[1995]: I20250213 16:05:56.994812 1995 update_check_scheduler.cc:74] Next update check in 9m16s Feb 13 16:05:57.001990 systemd[1]: motdgen.service: Deactivated successfully. Feb 13 16:05:57.004403 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Feb 13 16:05:57.008737 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). Feb 13 16:05:57.008842 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Feb 13 16:05:57.013700 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). Feb 13 16:05:57.013741 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Feb 13 16:05:57.016329 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 13 16:05:57.017903 extend-filesystems[1988]: Found loop4 Feb 13 16:05:57.021178 extend-filesystems[1988]: Found loop5 Feb 13 16:05:57.020887 (ntainerd)[2014]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: ntpd 4.2.8p17@1.4004-o Thu Feb 13 13:58:42 UTC 2025 (1): Starting Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: ---------------------------------------------------- Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: ntp-4 is maintained by Network Time Foundation, Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: corporation. Support and training for ntp-4 are Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: available at https://www.nwtime.org/support Feb 13 16:05:57.024662 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: ---------------------------------------------------- Feb 13 16:05:57.023057 ntpd[1990]: ntpd 4.2.8p17@1.4004-o Thu Feb 13 13:58:42 UTC 2025 (1): Starting Feb 13 16:05:57.023110 ntpd[1990]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Feb 13 16:05:57.023137 ntpd[1990]: ---------------------------------------------------- Feb 13 16:05:57.023157 ntpd[1990]: ntp-4 is maintained by Network Time Foundation, Feb 13 16:05:57.023176 ntpd[1990]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Feb 13 16:05:57.023194 ntpd[1990]: corporation. Support and training for ntp-4 are Feb 13 16:05:57.023212 ntpd[1990]: available at https://www.nwtime.org/support Feb 13 16:05:57.023230 ntpd[1990]: ---------------------------------------------------- Feb 13 16:05:57.032201 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: proto: precision = 0.096 usec (-23) Feb 13 16:05:57.032256 extend-filesystems[1988]: Found loop6 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found loop7 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p1 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p2 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p3 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found usr Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p4 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p6 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p7 Feb 13 16:05:57.032256 extend-filesystems[1988]: Found nvme0n1p9 Feb 13 16:05:57.032256 extend-filesystems[1988]: Checking size of /dev/nvme0n1p9 Feb 13 16:05:57.031923 ntpd[1990]: proto: precision = 0.096 usec (-23) Feb 13 16:05:57.037855 systemd[1]: Started update-engine.service - Update Engine. Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: basedate set to 2025-02-01 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: gps base set to 2025-02-02 (week 2352) Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listen and drop on 0 v6wildcard [::]:123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listen normally on 2 lo 127.0.0.1:123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listen normally on 3 eth0 172.31.21.70:123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listen normally on 4 lo [::1]:123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: bind(21) AF_INET6 fe80::4a9:c2ff:fedc:2cf5%2#123 flags 0x11 failed: Cannot assign requested address Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: unable to create socket on eth0 (5) for fe80::4a9:c2ff:fedc:2cf5%2#123 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: failed to init interface for address fe80::4a9:c2ff:fedc:2cf5%2 Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: Listening on routing socket on fd #21 for interface updates Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 13 16:05:57.088839 ntpd[1990]: 13 Feb 16:05:57 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 13 16:05:57.042764 ntpd[1990]: basedate set to 2025-02-01 Feb 13 16:05:57.058569 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Feb 13 16:05:57.042801 ntpd[1990]: gps base set to 2025-02-02 (week 2352) Feb 13 16:05:57.068204 systemd[1]: Started locksmithd.service - Cluster reboot manager. Feb 13 16:05:57.054425 ntpd[1990]: Listen and drop on 0 v6wildcard [::]:123 Feb 13 16:05:57.054503 ntpd[1990]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 13 16:05:57.059349 ntpd[1990]: Listen normally on 2 lo 127.0.0.1:123 Feb 13 16:05:57.059423 ntpd[1990]: Listen normally on 3 eth0 172.31.21.70:123 Feb 13 16:05:57.059490 ntpd[1990]: Listen normally on 4 lo [::1]:123 Feb 13 16:05:57.059576 ntpd[1990]: bind(21) AF_INET6 fe80::4a9:c2ff:fedc:2cf5%2#123 flags 0x11 failed: Cannot assign requested address Feb 13 16:05:57.059614 ntpd[1990]: unable to create socket on eth0 (5) for fe80::4a9:c2ff:fedc:2cf5%2#123 Feb 13 16:05:57.059641 ntpd[1990]: failed to init interface for address fe80::4a9:c2ff:fedc:2cf5%2 Feb 13 16:05:57.059698 ntpd[1990]: Listening on routing socket on fd #21 for interface updates Feb 13 16:05:57.081728 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 13 16:05:57.081787 ntpd[1990]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 13 16:05:57.115070 jq[2018]: true Feb 13 16:05:57.116272 coreos-metadata[1985]: Feb 13 16:05:57.116 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Feb 13 16:05:57.120011 coreos-metadata[1985]: Feb 13 16:05:57.119 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Feb 13 16:05:57.120011 coreos-metadata[1985]: Feb 13 16:05:57.119 INFO Fetch successful Feb 13 16:05:57.120011 coreos-metadata[1985]: Feb 13 16:05:57.119 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Feb 13 16:05:57.120848 coreos-metadata[1985]: Feb 13 16:05:57.120 INFO Fetch successful Feb 13 16:05:57.120848 coreos-metadata[1985]: Feb 13 16:05:57.120 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Feb 13 16:05:57.121579 coreos-metadata[1985]: Feb 13 16:05:57.121 INFO Fetch successful Feb 13 16:05:57.121579 coreos-metadata[1985]: Feb 13 16:05:57.121 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Feb 13 16:05:57.123129 coreos-metadata[1985]: Feb 13 16:05:57.123 INFO Fetch successful Feb 13 16:05:57.123316 coreos-metadata[1985]: Feb 13 16:05:57.123 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetch failed with 404: resource not found Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetch successful Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetch successful Feb 13 16:05:57.131081 coreos-metadata[1985]: Feb 13 16:05:57.129 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Feb 13 16:05:57.133198 coreos-metadata[1985]: Feb 13 16:05:57.133 INFO Fetch successful Feb 13 16:05:57.133198 coreos-metadata[1985]: Feb 13 16:05:57.133 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Feb 13 16:05:57.136108 coreos-metadata[1985]: Feb 13 16:05:57.135 INFO Fetch successful Feb 13 16:05:57.136108 coreos-metadata[1985]: Feb 13 16:05:57.136 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Feb 13 16:05:57.145200 coreos-metadata[1985]: Feb 13 16:05:57.136 INFO Fetch successful Feb 13 16:05:57.170793 systemd[1]: Finished setup-oem.service - Setup OEM. Feb 13 16:05:57.194271 extend-filesystems[1988]: Resized partition /dev/nvme0n1p9 Feb 13 16:05:57.236065 extend-filesystems[2044]: resize2fs 1.47.1 (20-May-2024) Feb 13 16:05:57.266063 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Feb 13 16:05:57.270296 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Feb 13 16:05:57.275794 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Feb 13 16:05:57.305427 systemd-logind[1994]: Watching system buttons on /dev/input/event0 (Power Button) Feb 13 16:05:57.307425 systemd-logind[1994]: Watching system buttons on /dev/input/event1 (Sleep Button) Feb 13 16:05:57.309376 systemd-logind[1994]: New seat seat0. Feb 13 16:05:57.313643 systemd[1]: Started systemd-logind.service - User Login Management. Feb 13 16:05:57.403188 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1784) Feb 13 16:05:57.413015 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 13 16:05:57.413430 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Feb 13 16:05:57.414104 dbus-daemon[1986]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=2025 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Feb 13 16:05:57.435173 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Feb 13 16:05:57.459776 systemd[1]: Starting polkit.service - Authorization Manager... Feb 13 16:05:57.468431 extend-filesystems[2044]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Feb 13 16:05:57.468431 extend-filesystems[2044]: old_desc_blocks = 1, new_desc_blocks = 1 Feb 13 16:05:57.468431 extend-filesystems[2044]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Feb 13 16:05:57.485638 extend-filesystems[1988]: Resized filesystem in /dev/nvme0n1p9 Feb 13 16:05:57.494833 bash[2062]: Updated "/home/core/.ssh/authorized_keys" Feb 13 16:05:57.496906 systemd[1]: extend-filesystems.service: Deactivated successfully. Feb 13 16:05:57.500176 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Feb 13 16:05:57.507413 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Feb 13 16:05:57.524490 polkitd[2071]: Started polkitd version 121 Feb 13 16:05:57.530961 systemd[1]: Starting sshkeys.service... Feb 13 16:05:57.551749 polkitd[2071]: Loading rules from directory /etc/polkit-1/rules.d Feb 13 16:05:57.553255 polkitd[2071]: Loading rules from directory /usr/share/polkit-1/rules.d Feb 13 16:05:57.554455 polkitd[2071]: Finished loading, compiling and executing 2 rules Feb 13 16:05:57.557171 dbus-daemon[1986]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 13 16:05:57.557613 systemd[1]: Started polkit.service - Authorization Manager. Feb 13 16:05:57.566395 polkitd[2071]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Feb 13 16:05:57.572065 locksmithd[2027]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Feb 13 16:05:57.645246 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Feb 13 16:05:57.658147 systemd-resolved[1934]: System hostname changed to 'ip-172-31-21-70'. Feb 13 16:05:57.658723 systemd-hostnamed[2025]: Hostname set to (transient) Feb 13 16:05:57.672043 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Feb 13 16:05:57.714803 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Feb 13 16:05:57.919282 coreos-metadata[2148]: Feb 13 16:05:57.919 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Feb 13 16:05:57.920598 coreos-metadata[2148]: Feb 13 16:05:57.920 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Feb 13 16:05:57.925306 coreos-metadata[2148]: Feb 13 16:05:57.925 INFO Fetch successful Feb 13 16:05:57.925306 coreos-metadata[2148]: Feb 13 16:05:57.925 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Feb 13 16:05:57.925997 coreos-metadata[2148]: Feb 13 16:05:57.925 INFO Fetch successful Feb 13 16:05:57.930217 unknown[2148]: wrote ssh authorized keys file for user: core Feb 13 16:05:57.938081 containerd[2014]: time="2025-02-13T16:05:57.935933113Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Feb 13 16:05:57.953185 systemd-networkd[1933]: eth0: Gained IPv6LL Feb 13 16:05:57.969316 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Feb 13 16:05:57.983610 systemd[1]: Reached target network-online.target - Network is Online. Feb 13 16:05:57.998774 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Feb 13 16:05:58.014842 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 16:05:58.025325 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Feb 13 16:05:58.032734 update-ssh-keys[2182]: Updated "/home/core/.ssh/authorized_keys" Feb 13 16:05:58.040653 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Feb 13 16:05:58.052232 systemd[1]: Finished sshkeys.service. Feb 13 16:05:58.061042 containerd[2014]: time="2025-02-13T16:05:58.060907654Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.070879 containerd[2014]: time="2025-02-13T16:05:58.070818730Z" 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.71-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Feb 13 16:05:58.071046 containerd[2014]: time="2025-02-13T16:05:58.070998538Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Feb 13 16:05:58.071171 containerd[2014]: time="2025-02-13T16:05:58.071141926Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Feb 13 16:05:58.072419 containerd[2014]: time="2025-02-13T16:05:58.072366226Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Feb 13 16:05:58.072608 containerd[2014]: time="2025-02-13T16:05:58.072577546Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.072870 containerd[2014]: time="2025-02-13T16:05:58.072830398Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 16:05:58.074061 containerd[2014]: time="2025-02-13T16:05:58.073667458Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.074391 containerd[2014]: time="2025-02-13T16:05:58.074329090Z" 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 Feb 13 16:05:58.075280 containerd[2014]: time="2025-02-13T16:05:58.075213226Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.075484690Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.075531778Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.075821842Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.076409530Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.076656358Z" 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 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.076692898Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Feb 13 16:05:58.078067 containerd[2014]: time="2025-02-13T16:05:58.076991758Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Feb 13 16:05:58.078712 containerd[2014]: time="2025-02-13T16:05:58.078653386Z" level=info msg="metadata content store policy set" policy=shared Feb 13 16:05:58.090186 containerd[2014]: time="2025-02-13T16:05:58.090125338Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Feb 13 16:05:58.091529 containerd[2014]: time="2025-02-13T16:05:58.091144438Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Feb 13 16:05:58.091697 containerd[2014]: time="2025-02-13T16:05:58.091661002Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Feb 13 16:05:58.091835 containerd[2014]: time="2025-02-13T16:05:58.091799938Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Feb 13 16:05:58.091978 containerd[2014]: time="2025-02-13T16:05:58.091939846Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Feb 13 16:05:58.092460 containerd[2014]: time="2025-02-13T16:05:58.092411374Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096342826Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096650674Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096685858Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096719038Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096750142Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096796822Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096828814Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096860722Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096894814Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096925090Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096954154Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.096981346Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.097021186Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.097717 containerd[2014]: time="2025-02-13T16:05:58.097089430Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097124074Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097156042Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097185274Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097218370Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097247206Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097276894Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097308850Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097344610Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097376290Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097404838Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097450402Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097486354Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097530502Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097560394Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.098395 containerd[2014]: time="2025-02-13T16:05:58.097587874Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099122650Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099335626Z" 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 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099363898Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099394714Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099418954Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099451330Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099475258Z" level=info msg="NRI interface is disabled by configuration." Feb 13 16:05:58.103144 containerd[2014]: time="2025-02-13T16:05:58.099500770Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Feb 13 16:05:58.103417 systemd[1]: Started containerd.service - containerd container runtime. Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.099988666Z" 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}" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.100117966Z" level=info msg="Connect containerd service" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.100183498Z" level=info msg="using legacy CRI server" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.100202302Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.100356826Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.101613250Z" 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" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.101867986Z" level=info msg="Start subscribing containerd event" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.101952634Z" level=info msg="Start recovering state" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.102111742Z" level=info msg="Start event monitor" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.102138958Z" level=info msg="Start snapshots syncer" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.102159706Z" level=info msg="Start cni network conf syncer for default" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.102179926Z" level=info msg="Start streaming server" Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.103055506Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.103161490Z" level=info msg=serving... address=/run/containerd/containerd.sock Feb 13 16:05:58.110613 containerd[2014]: time="2025-02-13T16:05:58.103293838Z" level=info msg="containerd successfully booted in 0.171944s" Feb 13 16:05:58.145616 amazon-ssm-agent[2185]: Initializing new seelog logger Feb 13 16:05:58.146367 amazon-ssm-agent[2185]: New Seelog Logger Creation Complete Feb 13 16:05:58.146525 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.146604 amazon-ssm-agent[2185]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.147416 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 processing appconfig overrides Feb 13 16:05:58.148042 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.148201 amazon-ssm-agent[2185]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.148403 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 processing appconfig overrides Feb 13 16:05:58.148747 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.148837 amazon-ssm-agent[2185]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.149092 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 processing appconfig overrides Feb 13 16:05:58.150044 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO Proxy environment variables: Feb 13 16:05:58.157064 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.157064 amazon-ssm-agent[2185]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Feb 13 16:05:58.157064 amazon-ssm-agent[2185]: 2025/02/13 16:05:58 processing appconfig overrides Feb 13 16:05:58.185419 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Feb 13 16:05:58.251192 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO https_proxy: Feb 13 16:05:58.353041 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO http_proxy: Feb 13 16:05:58.451362 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO no_proxy: Feb 13 16:05:58.550242 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO Checking if agent identity type OnPrem can be assumed Feb 13 16:05:58.649677 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO Checking if agent identity type EC2 can be assumed Feb 13 16:05:58.748494 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO Agent will take identity from EC2 Feb 13 16:05:58.765695 sshd_keygen[2024]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Feb 13 16:05:58.828152 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Feb 13 16:05:58.843463 systemd[1]: Starting issuegen.service - Generate /run/issue... Feb 13 16:05:58.852317 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] using named pipe channel for IPC Feb 13 16:05:58.855685 systemd[1]: Started sshd@0-172.31.21.70:22-139.178.68.195:51760.service - OpenSSH per-connection server daemon (139.178.68.195:51760). Feb 13 16:05:58.882751 systemd[1]: issuegen.service: Deactivated successfully. Feb 13 16:05:58.884471 systemd[1]: Finished issuegen.service - Generate /run/issue. Feb 13 16:05:58.893144 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] using named pipe channel for IPC Feb 13 16:05:58.893144 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] using named pipe channel for IPC Feb 13 16:05:58.893144 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Feb 13 16:05:58.893144 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Feb 13 16:05:58.893144 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] Starting Core Agent Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [amazon-ssm-agent] registrar detected. Attempting registration Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [Registrar] Starting registrar module Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [EC2Identity] EC2 registration was successful. Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [CredentialRefresher] credentialRefresher has started Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [CredentialRefresher] Starting credentials refresher loop Feb 13 16:05:58.893839 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO EC2RoleProvider Successfully connected with instance profile role credentials Feb 13 16:05:58.898982 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Feb 13 16:05:58.940783 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Feb 13 16:05:58.953575 amazon-ssm-agent[2185]: 2025-02-13 16:05:58 INFO [CredentialRefresher] Next credential rotation will be in 31.841647577066666 minutes Feb 13 16:05:58.955728 systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 13 16:05:58.970656 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Feb 13 16:05:58.971183 systemd[1]: Reached target getty.target - Login Prompts. Feb 13 16:05:59.161689 sshd[2218]: Accepted publickey for core from 139.178.68.195 port 51760 ssh2: RSA SHA256:ucMx2cSvTkGUIEkBWIRjoHjrp2OD2GS2ULysK2Q5fkU Feb 13 16:05:59.165971 sshd[2218]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 16:05:59.182958 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Feb 13 16:05:59.195677 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Feb 13 16:05:59.213697 systemd-logind[1994]: New session 1 of user core. Feb 13 16:05:59.230981 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Feb 13 16:05:59.246673 systemd[1]: Starting user@500.service - User Manager for UID 500... Feb 13 16:05:59.271801 (systemd)[2229]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Feb 13 16:05:59.371460 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 16:05:59.377843 systemd[1]: Reached target multi-user.target - Multi-User System. Feb 13 16:05:59.385882 (kubelet)[2240]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Feb 13 16:05:59.523230 systemd[2229]: Queued start job for default target default.target. Feb 13 16:05:59.541187 systemd[2229]: Created slice app.slice - User Application Slice. Feb 13 16:05:59.541252 systemd[2229]: Reached target paths.target - Paths. Feb 13 16:05:59.541286 systemd[2229]: Reached target timers.target - Timers. Feb 13 16:05:59.546276 systemd[2229]: Starting dbus.socket - D-Bus User Message Bus Socket... Feb 13 16:05:59.574332 systemd[2229]: Listening on dbus.socket - D-Bus User Message Bus Socket. Feb 13 16:05:59.574471 systemd[2229]: Reached target sockets.target - Sockets. Feb 13 16:05:59.574506 systemd[2229]: Reached target basic.target - Basic System. Feb 13 16:05:59.574606 systemd[2229]: Reached target default.target - Main User Target. Feb 13 16:05:59.574673 systemd[2229]: Startup finished in 289ms. Feb 13 16:05:59.575720 systemd[1]: Started user@500.service - User Manager for UID 500. Feb 13 16:05:59.586309 systemd[1]: Started session-1.scope - Session 1 of User core. Feb 13 16:05:59.590323 systemd[1]: Startup finished in 1.179s (kernel) + 9.425s (initrd) + 8.411s (userspace) = 19.016s. Feb 13 16:05:59.758655 systemd[1]: Started sshd@1-172.31.21.70:22-139.178.68.195:60150.service - OpenSSH per-connection server daemon (139.178.68.195:60150). Feb 13 16:05:59.926298 amazon-ssm-agent[2185]: 2025-02-13 16:05:59 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Feb 13 16:05:59.954088 sshd[2254]: Accepted publickey for core from 139.178.68.195 port 60150 ssh2: RSA SHA256:ucMx2cSvTkGUIEkBWIRjoHjrp2OD2GS2ULysK2Q5fkU Feb 13 16:05:59.957837 sshd[2254]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 16:05:59.971435 systemd-logind[1994]: New session 2 of user core. Feb 13 16:05:59.974432 systemd[1]: Started session-2.scope - Session 2 of User core. Feb 13 16:06:00.024732 ntpd[1990]: Listen normally on 6 eth0 [fe80::4a9:c2ff:fedc:2cf5%2]:123 Feb 13 16:06:00.025429 ntpd[1990]: 13 Feb 16:06:00 ntpd[1990]: Listen normally on 6 eth0 [fe80::4a9:c2ff:fedc:2cf5%2]:123 Feb 13 16:06:00.027981 amazon-ssm-agent[2185]: 2025-02-13 16:05:59 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2257) started Feb 13 16:06:00.112934 sshd[2254]: pam_unix(sshd:session): session closed for user core Feb 13 16:06:00.122466 systemd[1]: sshd@1-172.31.21.70:22-139.178.68.195:60150.service: Deactivated successfully. Feb 13 16:06:00.128019 amazon-ssm-agent[2185]: 2025-02-13 16:05:59 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Feb 13 16:06:00.127753 systemd[1]: session-2.scope: Deactivated successfully. Feb 13 16:06:00.136340 systemd-logind[1994]: Session 2 logged out. Waiting for processes to exit. Feb 13 16:06:00.157553 systemd[1]: Started sshd@2-172.31.21.70:22-139.178.68.195:60154.service - OpenSSH per-connection server daemon (139.178.68.195:60154). Feb 13 16:06:00.161206 systemd-logind[1994]: Removed session 2. Feb 13 16:06:00.345372 sshd[2270]: Accepted publickey for core from 139.178.68.195 port 60154 ssh2: RSA SHA256:ucMx2cSvTkGUIEkBWIRjoHjrp2OD2GS2ULysK2Q5fkU Feb 13 16:06:00.349210 sshd[2270]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 16:06:00.364040 systemd-logind[1994]: New session 3 of user core. Feb 13 16:06:00.368323 systemd[1]: Started session-3.scope - Session 3 of User core. Feb 13 16:06:00.398512 kubelet[2240]: E0213 16:06:00.398405 2240 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Feb 13 16:06:00.404993 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Feb 13 16:06:00.405355 systemd[1]: kubelet.service: Failed with result 'exit-code'. Feb 13 16:06:00.406151 systemd[1]: kubelet.service: Consumed 1.323s CPU time. Feb 13 16:06:00.491707 sshd[2270]: pam_unix(sshd:session): session closed for user core Feb 13 16:06:00.496718 systemd[1]: sshd@2-172.31.21.70:22-139.178.68.195:60154.service: Deactivated successfully. Feb 13 16:06:00.500644 systemd[1]: session-3.scope: Deactivated successfully. Feb 13 16:06:00.503261 systemd-logind[1994]: Session 3 logged out. Waiting for processes to exit. Feb 13 16:06:00.504995 systemd-logind[1994]: Removed session 3. Feb 13 16:06:00.530534 systemd[1]: Started sshd@3-172.31.21.70:22-139.178.68.195:60162.service - OpenSSH per-connection server daemon (139.178.68.195:60162). Feb 13 16:06:00.701353 sshd[2282]: Accepted publickey for core from 139.178.68.195 port 60162 ssh2: RSA SHA256:ucMx2cSvTkGUIEkBWIRjoHjrp2OD2GS2ULysK2Q5fkU Feb 13 16:06:00.704362 sshd[2282]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 16:06:00.712773 systemd-logind[1994]: New session 4 of user core. Feb 13 16:06:00.722327 systemd[1]: Started session-4.scope - Session 4 of User core. Feb 13 16:06:00.849294 sshd[2282]: pam_unix(sshd:session): session closed for user core Feb 13 16:06:00.854970 systemd[1]: sshd@3-172.31.21.70:22-139.178.68.195:60162.service: Deactivated successfully. Feb 13 16:06:00.855483 systemd-logind[1994]: Session 4 logged out. Waiting for processes to exit. Feb 13 16:06:00.858915 systemd[1]: session-4.scope: Deactivated successfully. Feb 13 16:06:00.862389 systemd-logind[1994]: Removed session 4. Feb 13 16:06:00.890568 systemd[1]: Started sshd@4-172.31.21.70:22-139.178.68.195:60172.service - OpenSSH per-connection server daemon (139.178.68.195:60172). Feb 13 16:06:01.066312 sshd[2289]: Accepted publickey for core from 139.178.68.195 port 60172 ssh2: RSA SHA256:ucMx2cSvTkGUIEkBWIRjoHjrp2OD2GS2ULysK2Q5fkU Feb 13 16:06:01.068549 sshd[2289]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 16:06:01.078156 systemd-logind[1994]: New session 5 of user core. Feb 13 16:06:01.087298 systemd[1]: Started session-5.scope - Session 5 of User core. Feb 13 16:06:01.235068 sudo[2292]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Feb 13 16:06:01.235720 sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Feb 13 16:06:02.290903 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 16:06:02.291302 systemd[1]: kubelet.service: Consumed 1.323s CPU time. Feb 13 16:06:02.301581 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 16:06:02.351802 systemd[1]: Reloading requested from client PID 2331 ('systemctl') (unit session-5.scope)... Feb 13 16:06:02.351836 systemd[1]: Reloading... Feb 13 16:06:02.566063 zram_generator::config[2372]: No configuration found. Feb 13 16:06:02.832312 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. Feb 13 16:06:03.008805 systemd[1]: Reloading finished in 656 ms. Feb 13 16:06:03.106397 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Feb 13 16:06:03.106588 systemd[1]: kubelet.service: Failed with result 'signal'. Feb 13 16:06:03.107312 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 16:06:03.115680 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 16:06:03.453384 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 16:06:03.465586 (kubelet)[2433]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Feb 13 16:06:03.577849 kubelet[2433]: 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. Feb 13 16:06:03.577849 kubelet[2433]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Feb 13 16:06:03.577849 kubelet[2433]: 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. Feb 13 16:06:03.578498 kubelet[2433]: I0213 16:06:03.577934 2433 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Feb 13 16:06:03.763006 systemd-resolved[1934]: Clock change detected. Flushing caches. Feb 13 16:06:04.793978 kubelet[2433]: I0213 16:06:04.793544 2433 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Feb 13 16:06:04.796007 kubelet[2433]: I0213 16:06:04.795626 2433 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Feb 13 16:06:04.796634 kubelet[2433]: I0213 16:06:04.796598 2433 server.go:919] "Client rotation is on, will bootstrap in background" Feb 13 16:06:04.829197 kubelet[2433]: I0213 16:06:04.829125 2433 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Feb 13 16:06:04.848300 kubelet[2433]: I0213 16:06:04.848239 2433 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Feb 13 16:06:04.848833 kubelet[2433]: I0213 16:06:04.848802 2433 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Feb 13 16:06:04.849303 kubelet[2433]: I0213 16:06:04.849227 2433 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Feb 13 16:06:04.849303 kubelet[2433]: I0213 16:06:04.849286 2433 topology_manager.go:138] "Creating topology manager with none policy" Feb 13 16:06:04.849303 kubelet[2433]: I0213 16:06:04.849312 2433 container_manager_linux.go:301] "Creating device plugin manager" Feb 13 16:06:04.849747 kubelet[2433]: I0213 16:06:04.849640 2433 state_mem.go:36] "Initialized new in-memory state store" Feb 13 16:06:04.853759 kubelet[2433]: I0213 16:06:04.853346 2433 kubelet.go:396] "Attempting to sync node with API server" Feb 13 16:06:04.853759 kubelet[2433]: I0213 16:06:04.853424 2433 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Feb 13 16:06:04.853759 kubelet[2433]: I0213 16:06:04.853476 2433 kubelet.go:312] "Adding apiserver pod source" Feb 13 16:06:04.853759 kubelet[2433]: I0213 16:06:04.853510 2433 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Feb 13 16:06:04.854189 kubelet[2433]: E0213 16:06:04.853926 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:04.854189 kubelet[2433]: E0213 16:06:04.853988 2433 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:04.856576 kubelet[2433]: I0213 16:06:04.856535 2433 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Feb 13 16:06:04.857161 kubelet[2433]: I0213 16:06:04.857127 2433 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Feb 13 16:06:04.858051 kubelet[2433]: W0213 16:06:04.857987 2433 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Feb 13 16:06:04.859551 kubelet[2433]: I0213 16:06:04.859195 2433 server.go:1256] "Started kubelet" Feb 13 16:06:04.862563 kubelet[2433]: I0213 16:06:04.862493 2433 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Feb 13 16:06:04.876657 kubelet[2433]: I0213 16:06:04.876399 2433 volume_manager.go:291] "Starting Kubelet Volume Manager" Feb 13 16:06:04.879635 kubelet[2433]: I0213 16:06:04.877838 2433 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Feb 13 16:06:04.879635 kubelet[2433]: I0213 16:06:04.879203 2433 server.go:461] "Adding debug handlers to kubelet server" Feb 13 16:06:04.880765 kubelet[2433]: I0213 16:06:04.880612 2433 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Feb 13 16:06:04.881179 kubelet[2433]: I0213 16:06:04.880931 2433 reconciler_new.go:29] "Reconciler: start to sync state" Feb 13 16:06:04.881179 kubelet[2433]: I0213 16:06:04.881031 2433 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Feb 13 16:06:04.881333 kubelet[2433]: I0213 16:06:04.881298 2433 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Feb 13 16:06:04.887100 kubelet[2433]: W0213 16:06:04.886870 2433 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Feb 13 16:06:04.887469 kubelet[2433]: E0213 16:06:04.887444 2433 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Feb 13 16:06:04.888807 kubelet[2433]: I0213 16:06:04.888775 2433 factory.go:221] Registration of the systemd container factory successfully Feb 13 16:06:04.889345 kubelet[2433]: I0213 16:06:04.889277 2433 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 Feb 13 16:06:04.891863 kubelet[2433]: W0213 16:06:04.891549 2433 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Feb 13 16:06:04.891863 kubelet[2433]: E0213 16:06:04.891600 2433 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Feb 13 16:06:04.892833 kubelet[2433]: W0213 16:06:04.892255 2433 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Node: nodes "172.31.21.70" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Feb 13 16:06:04.892833 kubelet[2433]: E0213 16:06:04.892301 2433 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.21.70" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Feb 13 16:06:04.892833 kubelet[2433]: E0213 16:06:04.892317 2433 event.go:346] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.21.70.1823d02b2772c8b1 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.21.70,UID:172.31.21.70,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.21.70,},FirstTimestamp:2025-02-13 16:06:04.859148465 +0000 UTC m=+1.644787088,LastTimestamp:2025-02-13 16:06:04.859148465 +0000 UTC m=+1.644787088,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.21.70,}" Feb 13 16:06:04.895715 kubelet[2433]: E0213 16:06:04.895644 2433 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"172.31.21.70\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Feb 13 16:06:04.897223 kubelet[2433]: E0213 16:06:04.897041 2433 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Feb 13 16:06:04.897223 kubelet[2433]: I0213 16:06:04.897181 2433 factory.go:221] Registration of the containerd container factory successfully Feb 13 16:06:04.936742 kubelet[2433]: I0213 16:06:04.936622 2433 cpu_manager.go:214] "Starting CPU manager" policy="none" Feb 13 16:06:04.936742 kubelet[2433]: I0213 16:06:04.936665 2433 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Feb 13 16:06:04.936742 kubelet[2433]: I0213 16:06:04.936701 2433 state_mem.go:36] "Initialized new in-memory state store" Feb 13 16:06:04.940571 kubelet[2433]: I0213 16:06:04.940508 2433 policy_none.go:49] "None policy: Start" Feb 13 16:06:04.943170 kubelet[2433]: I0213 16:06:04.943059 2433 memory_manager.go:170] "Starting memorymanager" policy="None" Feb 13 16:06:04.943170 kubelet[2433]: I0213 16:06:04.943142 2433 state_mem.go:35] "Initializing new in-memory state store" Feb 13 16:06:04.953965 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Feb 13 16:06:04.972552 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Feb 13 16:06:04.982055 kubelet[2433]: I0213 16:06:04.981658 2433 kubelet_node_status.go:73] "Attempting to register node" node="172.31.21.70" Feb 13 16:06:04.983869 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Feb 13 16:06:04.991175 kubelet[2433]: I0213 16:06:04.991111 2433 kubelet_node_status.go:76] "Successfully registered node" node="172.31.21.70" Feb 13 16:06:04.995126 kubelet[2433]: I0213 16:06:04.994833 2433 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Feb 13 16:06:04.995603 kubelet[2433]: I0213 16:06:04.995562 2433 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Feb 13 16:06:05.001601 kubelet[2433]: E0213 16:06:05.001543 2433 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.21.70\" not found" Feb 13 16:06:05.033657 kubelet[2433]: E0213 16:06:05.033586 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.085557 kubelet[2433]: I0213 16:06:05.085284 2433 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Feb 13 16:06:05.088276 kubelet[2433]: I0213 16:06:05.087765 2433 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Feb 13 16:06:05.088276 kubelet[2433]: I0213 16:06:05.087822 2433 status_manager.go:217] "Starting to sync pod status with apiserver" Feb 13 16:06:05.088276 kubelet[2433]: I0213 16:06:05.087860 2433 kubelet.go:2329] "Starting kubelet main sync loop" Feb 13 16:06:05.088276 kubelet[2433]: E0213 16:06:05.087932 2433 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Feb 13 16:06:05.134316 kubelet[2433]: E0213 16:06:05.134243 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.235154 kubelet[2433]: E0213 16:06:05.235080 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.336143 kubelet[2433]: E0213 16:06:05.336001 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.436669 kubelet[2433]: E0213 16:06:05.436616 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.537580 kubelet[2433]: E0213 16:06:05.537504 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.638462 kubelet[2433]: E0213 16:06:05.638264 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.737461 sudo[2292]: pam_unix(sudo:session): session closed for user root Feb 13 16:06:05.739032 kubelet[2433]: E0213 16:06:05.738970 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.762964 sshd[2289]: pam_unix(sshd:session): session closed for user core Feb 13 16:06:05.768252 systemd-logind[1994]: Session 5 logged out. Waiting for processes to exit. Feb 13 16:06:05.769612 systemd[1]: sshd@4-172.31.21.70:22-139.178.68.195:60172.service: Deactivated successfully. Feb 13 16:06:05.773153 systemd[1]: session-5.scope: Deactivated successfully. Feb 13 16:06:05.778637 systemd-logind[1994]: Removed session 5. Feb 13 16:06:05.804666 kubelet[2433]: I0213 16:06:05.804607 2433 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Feb 13 16:06:05.805296 kubelet[2433]: W0213 16:06:05.804868 2433 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Feb 13 16:06:05.805296 kubelet[2433]: W0213 16:06:05.804925 2433 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Feb 13 16:06:05.839477 kubelet[2433]: E0213 16:06:05.839415 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:05.854824 kubelet[2433]: E0213 16:06:05.854753 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:05.940340 kubelet[2433]: E0213 16:06:05.940158 2433 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.21.70\" not found" Feb 13 16:06:06.041680 kubelet[2433]: I0213 16:06:06.041622 2433 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Feb 13 16:06:06.042150 containerd[2014]: time="2025-02-13T16:06:06.042073635Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Feb 13 16:06:06.043238 kubelet[2433]: I0213 16:06:06.042924 2433 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Feb 13 16:06:06.855167 kubelet[2433]: E0213 16:06:06.855100 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:06.855167 kubelet[2433]: I0213 16:06:06.855108 2433 apiserver.go:52] "Watching apiserver" Feb 13 16:06:06.863402 kubelet[2433]: I0213 16:06:06.863287 2433 topology_manager.go:215] "Topology Admit Handler" podUID="db1c3d69-2d2e-4f1f-9234-377ab05fc6f0" podNamespace="kube-flannel" podName="kube-flannel-ds-96ll4" Feb 13 16:06:06.864464 kubelet[2433]: I0213 16:06:06.863594 2433 topology_manager.go:215] "Topology Admit Handler" podUID="d17a7739-b4a7-4084-8732-60cb309b47b9" podNamespace="kube-system" podName="kube-proxy-f2q7z" Feb 13 16:06:06.878187 systemd[1]: Created slice kubepods-burstable-poddb1c3d69_2d2e_4f1f_9234_377ab05fc6f0.slice - libcontainer container kubepods-burstable-poddb1c3d69_2d2e_4f1f_9234_377ab05fc6f0.slice. Feb 13 16:06:06.882585 kubelet[2433]: I0213 16:06:06.882512 2433 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Feb 13 16:06:06.895183 kubelet[2433]: I0213 16:06:06.894559 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lxcxb\" (UniqueName: \"kubernetes.io/projected/d17a7739-b4a7-4084-8732-60cb309b47b9-kube-api-access-lxcxb\") pod \"kube-proxy-f2q7z\" (UID: \"d17a7739-b4a7-4084-8732-60cb309b47b9\") " pod="kube-system/kube-proxy-f2q7z" Feb 13 16:06:06.895183 kubelet[2433]: I0213 16:06:06.894637 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-run\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.895183 kubelet[2433]: I0213 16:06:06.894689 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-cni\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.895183 kubelet[2433]: I0213 16:06:06.894737 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d17a7739-b4a7-4084-8732-60cb309b47b9-xtables-lock\") pod \"kube-proxy-f2q7z\" (UID: \"d17a7739-b4a7-4084-8732-60cb309b47b9\") " pod="kube-system/kube-proxy-f2q7z" Feb 13 16:06:06.895183 kubelet[2433]: I0213 16:06:06.894788 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d17a7739-b4a7-4084-8732-60cb309b47b9-lib-modules\") pod \"kube-proxy-f2q7z\" (UID: \"d17a7739-b4a7-4084-8732-60cb309b47b9\") " pod="kube-system/kube-proxy-f2q7z" Feb 13 16:06:06.895683 kubelet[2433]: I0213 16:06:06.894853 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d17a7739-b4a7-4084-8732-60cb309b47b9-kube-proxy\") pod \"kube-proxy-f2q7z\" (UID: \"d17a7739-b4a7-4084-8732-60cb309b47b9\") " pod="kube-system/kube-proxy-f2q7z" Feb 13 16:06:06.895683 kubelet[2433]: I0213 16:06:06.894903 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-cni-plugin\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.895683 kubelet[2433]: I0213 16:06:06.894951 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-flannel-cfg\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.895683 kubelet[2433]: I0213 16:06:06.895025 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-xtables-lock\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.895683 kubelet[2433]: I0213 16:06:06.895080 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sd6cj\" (UniqueName: \"kubernetes.io/projected/db1c3d69-2d2e-4f1f-9234-377ab05fc6f0-kube-api-access-sd6cj\") pod \"kube-flannel-ds-96ll4\" (UID: \"db1c3d69-2d2e-4f1f-9234-377ab05fc6f0\") " pod="kube-flannel/kube-flannel-ds-96ll4" Feb 13 16:06:06.908139 systemd[1]: Created slice kubepods-besteffort-podd17a7739_b4a7_4084_8732_60cb309b47b9.slice - libcontainer container kubepods-besteffort-podd17a7739_b4a7_4084_8732_60cb309b47b9.slice. Feb 13 16:06:07.200930 containerd[2014]: time="2025-02-13T16:06:07.200725721Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-96ll4,Uid:db1c3d69-2d2e-4f1f-9234-377ab05fc6f0,Namespace:kube-flannel,Attempt:0,}" Feb 13 16:06:07.224209 containerd[2014]: time="2025-02-13T16:06:07.223595957Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-f2q7z,Uid:d17a7739-b4a7-4084-8732-60cb309b47b9,Namespace:kube-system,Attempt:0,}" Feb 13 16:06:07.847243 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1370850977.mount: Deactivated successfully. Feb 13 16:06:07.856135 kubelet[2433]: E0213 16:06:07.856078 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:07.870408 containerd[2014]: time="2025-02-13T16:06:07.869062148Z" 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\"}" Feb 13 16:06:07.870994 containerd[2014]: time="2025-02-13T16:06:07.870947636Z" 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\"}" Feb 13 16:06:07.873025 containerd[2014]: time="2025-02-13T16:06:07.872965808Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Feb 13 16:06:07.873853 containerd[2014]: time="2025-02-13T16:06:07.873783584Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Feb 13 16:06:07.875216 containerd[2014]: time="2025-02-13T16:06:07.875114444Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Feb 13 16:06:07.881180 containerd[2014]: time="2025-02-13T16:06:07.881100512Z" 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\"}" Feb 13 16:06:07.883828 containerd[2014]: time="2025-02-13T16:06:07.883147952Z" 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 659.438127ms" Feb 13 16:06:07.888839 containerd[2014]: time="2025-02-13T16:06:07.888662576Z" 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 686.701743ms" Feb 13 16:06:08.224195 containerd[2014]: time="2025-02-13T16:06:08.223483782Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 16:06:08.224195 containerd[2014]: time="2025-02-13T16:06:08.223631370Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 16:06:08.224195 containerd[2014]: time="2025-02-13T16:06:08.223673442Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:08.227665 containerd[2014]: time="2025-02-13T16:06:08.227099502Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:08.239486 containerd[2014]: time="2025-02-13T16:06:08.238347690Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 16:06:08.243574 containerd[2014]: time="2025-02-13T16:06:08.243402018Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 16:06:08.243574 containerd[2014]: time="2025-02-13T16:06:08.243488550Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:08.243828 containerd[2014]: time="2025-02-13T16:06:08.243702810Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:08.413714 systemd[1]: Started cri-containerd-aa4f8b7b9f293f3a5dc29a01007aed9d22f647659be7a397146fc7c586e63e64.scope - libcontainer container aa4f8b7b9f293f3a5dc29a01007aed9d22f647659be7a397146fc7c586e63e64. Feb 13 16:06:08.418995 systemd[1]: Started cri-containerd-e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93.scope - libcontainer container e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93. Feb 13 16:06:08.497327 containerd[2014]: time="2025-02-13T16:06:08.497107063Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-f2q7z,Uid:d17a7739-b4a7-4084-8732-60cb309b47b9,Namespace:kube-system,Attempt:0,} returns sandbox id \"aa4f8b7b9f293f3a5dc29a01007aed9d22f647659be7a397146fc7c586e63e64\"" Feb 13 16:06:08.502565 containerd[2014]: time="2025-02-13T16:06:08.502495063Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.14\"" Feb 13 16:06:08.515829 containerd[2014]: time="2025-02-13T16:06:08.515750359Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-96ll4,Uid:db1c3d69-2d2e-4f1f-9234-377ab05fc6f0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\"" Feb 13 16:06:08.857109 kubelet[2433]: E0213 16:06:08.856928 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:09.857582 kubelet[2433]: E0213 16:06:09.857513 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:09.858317 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2933512294.mount: Deactivated successfully. Feb 13 16:06:10.342449 containerd[2014]: time="2025-02-13T16:06:10.342256880Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.14\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:10.343964 containerd[2014]: time="2025-02-13T16:06:10.343879316Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.14: active requests=0, bytes read=25273375" Feb 13 16:06:10.345242 containerd[2014]: time="2025-02-13T16:06:10.345163412Z" level=info msg="ImageCreate event name:\"sha256:8acaac6288aef2fbe5821a7539f95a6043513e648e6ffaf6a545a93fa77fe8c8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:10.349508 containerd[2014]: time="2025-02-13T16:06:10.349428416Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:197988595a902751e4e570a5e4d74182f12d83c1d175c1e79aa020f358f6535b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:10.351109 containerd[2014]: time="2025-02-13T16:06:10.350865668Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.14\" with image id \"sha256:8acaac6288aef2fbe5821a7539f95a6043513e648e6ffaf6a545a93fa77fe8c8\", repo tag \"registry.k8s.io/kube-proxy:v1.29.14\", repo digest \"registry.k8s.io/kube-proxy@sha256:197988595a902751e4e570a5e4d74182f12d83c1d175c1e79aa020f358f6535b\", size \"25272394\" in 1.848299445s" Feb 13 16:06:10.351109 containerd[2014]: time="2025-02-13T16:06:10.350924144Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.14\" returns image reference \"sha256:8acaac6288aef2fbe5821a7539f95a6043513e648e6ffaf6a545a93fa77fe8c8\"" Feb 13 16:06:10.352909 containerd[2014]: time="2025-02-13T16:06:10.352567148Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Feb 13 16:06:10.354592 containerd[2014]: time="2025-02-13T16:06:10.354307820Z" level=info msg="CreateContainer within sandbox \"aa4f8b7b9f293f3a5dc29a01007aed9d22f647659be7a397146fc7c586e63e64\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Feb 13 16:06:10.391144 containerd[2014]: time="2025-02-13T16:06:10.390929444Z" level=info msg="CreateContainer within sandbox \"aa4f8b7b9f293f3a5dc29a01007aed9d22f647659be7a397146fc7c586e63e64\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f7a11a7470337609e273f22eb3c684639589e9c5868e87c4e69a9b319a3f0c4e\"" Feb 13 16:06:10.394401 containerd[2014]: time="2025-02-13T16:06:10.392438120Z" level=info msg="StartContainer for \"f7a11a7470337609e273f22eb3c684639589e9c5868e87c4e69a9b319a3f0c4e\"" Feb 13 16:06:10.450693 systemd[1]: Started cri-containerd-f7a11a7470337609e273f22eb3c684639589e9c5868e87c4e69a9b319a3f0c4e.scope - libcontainer container f7a11a7470337609e273f22eb3c684639589e9c5868e87c4e69a9b319a3f0c4e. Feb 13 16:06:10.510709 containerd[2014]: time="2025-02-13T16:06:10.510637233Z" level=info msg="StartContainer for \"f7a11a7470337609e273f22eb3c684639589e9c5868e87c4e69a9b319a3f0c4e\" returns successfully" Feb 13 16:06:10.858666 kubelet[2433]: E0213 16:06:10.858568 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:11.131634 kubelet[2433]: I0213 16:06:11.131458 2433 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-f2q7z" podStartSLOduration=4.281230159 podStartE2EDuration="6.131349764s" podCreationTimestamp="2025-02-13 16:06:05 +0000 UTC" firstStartedPulling="2025-02-13 16:06:08.501541627 +0000 UTC m=+5.287180226" lastFinishedPulling="2025-02-13 16:06:10.351661244 +0000 UTC m=+7.137299831" observedRunningTime="2025-02-13 16:06:11.131318096 +0000 UTC m=+7.916956815" watchObservedRunningTime="2025-02-13 16:06:11.131349764 +0000 UTC m=+7.916988363" Feb 13 16:06:11.859538 kubelet[2433]: E0213 16:06:11.859457 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:12.860008 kubelet[2433]: E0213 16:06:12.859964 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:13.860514 kubelet[2433]: E0213 16:06:13.860445 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:14.861259 kubelet[2433]: E0213 16:06:14.861185 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:15.862434 kubelet[2433]: E0213 16:06:15.862359 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:16.863540 kubelet[2433]: E0213 16:06:16.863477 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:17.119011 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3755659252.mount: Deactivated successfully. Feb 13 16:06:17.188016 containerd[2014]: time="2025-02-13T16:06:17.187521890Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:17.189401 containerd[2014]: time="2025-02-13T16:06:17.189288962Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Feb 13 16:06:17.191104 containerd[2014]: time="2025-02-13T16:06:17.191012294Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:17.195710 containerd[2014]: time="2025-02-13T16:06:17.195603890Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:17.197884 containerd[2014]: time="2025-02-13T16:06:17.197612606Z" 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 6.844979182s" Feb 13 16:06:17.197884 containerd[2014]: time="2025-02-13T16:06:17.197689274Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Feb 13 16:06:17.201622 containerd[2014]: time="2025-02-13T16:06:17.201533738Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Feb 13 16:06:17.243206 containerd[2014]: time="2025-02-13T16:06:17.243070574Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861\"" Feb 13 16:06:17.244272 containerd[2014]: time="2025-02-13T16:06:17.244184258Z" level=info msg="StartContainer for \"80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861\"" Feb 13 16:06:17.292709 systemd[1]: Started cri-containerd-80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861.scope - libcontainer container 80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861. Feb 13 16:06:17.337254 containerd[2014]: time="2025-02-13T16:06:17.337182891Z" level=info msg="StartContainer for \"80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861\" returns successfully" Feb 13 16:06:17.338756 systemd[1]: cri-containerd-80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861.scope: Deactivated successfully. Feb 13 16:06:17.863693 kubelet[2433]: E0213 16:06:17.863619 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:17.944773 containerd[2014]: time="2025-02-13T16:06:17.944682630Z" level=info msg="shim disconnected" id=80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861 namespace=k8s.io Feb 13 16:06:17.944773 containerd[2014]: time="2025-02-13T16:06:17.944759658Z" level=warning msg="cleaning up after shim disconnected" id=80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861 namespace=k8s.io Feb 13 16:06:17.945188 containerd[2014]: time="2025-02-13T16:06:17.944781474Z" level=info msg="cleaning up dead shim" namespace=k8s.io Feb 13 16:06:17.980127 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-80b1e30cb5afeb4095d9968408f75d6e7ae9405bfcf4ac5e13400ba4416f3861-rootfs.mount: Deactivated successfully. Feb 13 16:06:18.136431 containerd[2014]: time="2025-02-13T16:06:18.135528075Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Feb 13 16:06:18.864612 kubelet[2433]: E0213 16:06:18.864553 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:19.865178 kubelet[2433]: E0213 16:06:19.865128 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:20.655988 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2134574278.mount: Deactivated successfully. Feb 13 16:06:20.865300 kubelet[2433]: E0213 16:06:20.865230 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:21.779948 containerd[2014]: time="2025-02-13T16:06:21.779845881Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:21.782196 containerd[2014]: time="2025-02-13T16:06:21.782096973Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Feb 13 16:06:21.783788 containerd[2014]: time="2025-02-13T16:06:21.783697233Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:21.790519 containerd[2014]: time="2025-02-13T16:06:21.790437825Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:21.793254 containerd[2014]: time="2025-02-13T16:06:21.793189941Z" 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 3.65758929s" Feb 13 16:06:21.793430 containerd[2014]: time="2025-02-13T16:06:21.793252725Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Feb 13 16:06:21.799948 containerd[2014]: time="2025-02-13T16:06:21.799878849Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Feb 13 16:06:21.848210 containerd[2014]: time="2025-02-13T16:06:21.848146449Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8\"" Feb 13 16:06:21.850060 containerd[2014]: time="2025-02-13T16:06:21.849988089Z" level=info msg="StartContainer for \"55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8\"" Feb 13 16:06:21.865825 kubelet[2433]: E0213 16:06:21.865784 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:21.902690 systemd[1]: Started cri-containerd-55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8.scope - libcontainer container 55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8. Feb 13 16:06:21.947614 systemd[1]: cri-containerd-55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8.scope: Deactivated successfully. Feb 13 16:06:21.951053 containerd[2014]: time="2025-02-13T16:06:21.950587834Z" level=info msg="StartContainer for \"55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8\" returns successfully" Feb 13 16:06:22.002561 kubelet[2433]: I0213 16:06:22.002502 2433 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Feb 13 16:06:22.542608 containerd[2014]: time="2025-02-13T16:06:22.542489613Z" level=info msg="shim disconnected" id=55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8 namespace=k8s.io Feb 13 16:06:22.542608 containerd[2014]: time="2025-02-13T16:06:22.542564649Z" level=warning msg="cleaning up after shim disconnected" id=55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8 namespace=k8s.io Feb 13 16:06:22.542608 containerd[2014]: time="2025-02-13T16:06:22.542588169Z" level=info msg="cleaning up dead shim" namespace=k8s.io Feb 13 16:06:22.562579 containerd[2014]: time="2025-02-13T16:06:22.562508013Z" level=warning msg="cleanup warnings time=\"2025-02-13T16:06:22Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Feb 13 16:06:22.820503 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-55df36b9992425bdcbe4939cad78900a803851cc47360f3cb59a0649257f36f8-rootfs.mount: Deactivated successfully. Feb 13 16:06:22.869616 kubelet[2433]: E0213 16:06:22.869552 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:23.164224 containerd[2014]: time="2025-02-13T16:06:23.163679000Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Feb 13 16:06:23.189101 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2401142084.mount: Deactivated successfully. Feb 13 16:06:23.193890 containerd[2014]: time="2025-02-13T16:06:23.193816388Z" level=info msg="CreateContainer within sandbox \"e0b2e029f5e694116d7551c3abeee839ddaa68c9ffcb5653ff69dfd049da7a93\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b\"" Feb 13 16:06:23.196290 containerd[2014]: time="2025-02-13T16:06:23.196220264Z" level=info msg="StartContainer for \"5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b\"" Feb 13 16:06:23.256778 systemd[1]: Started cri-containerd-5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b.scope - libcontainer container 5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b. Feb 13 16:06:23.295076 kubelet[2433]: I0213 16:06:23.294933 2433 topology_manager.go:215] "Topology Admit Handler" podUID="a4ac8e51-0419-4e48-addc-04305cc83e26" podNamespace="default" podName="nginx-deployment-6d5f899847-jwzmm" Feb 13 16:06:23.313582 systemd[1]: Created slice kubepods-besteffort-poda4ac8e51_0419_4e48_addc_04305cc83e26.slice - libcontainer container kubepods-besteffort-poda4ac8e51_0419_4e48_addc_04305cc83e26.slice. Feb 13 16:06:23.331899 containerd[2014]: time="2025-02-13T16:06:23.331818417Z" level=info msg="StartContainer for \"5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b\" returns successfully" Feb 13 16:06:23.394426 kubelet[2433]: I0213 16:06:23.394332 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hdjcb\" (UniqueName: \"kubernetes.io/projected/a4ac8e51-0419-4e48-addc-04305cc83e26-kube-api-access-hdjcb\") pod \"nginx-deployment-6d5f899847-jwzmm\" (UID: \"a4ac8e51-0419-4e48-addc-04305cc83e26\") " pod="default/nginx-deployment-6d5f899847-jwzmm" Feb 13 16:06:23.632519 containerd[2014]: time="2025-02-13T16:06:23.632361682Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jwzmm,Uid:a4ac8e51-0419-4e48-addc-04305cc83e26,Namespace:default,Attempt:0,}" Feb 13 16:06:23.686073 containerd[2014]: time="2025-02-13T16:06:23.685931470Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jwzmm,Uid:a4ac8e51-0419-4e48-addc-04305cc83e26,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"223101594434c8a3099eb59096e4c6165186175fb06d9af6d7193afa29d5f956\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Feb 13 16:06:23.686964 kubelet[2433]: E0213 16:06:23.686911 2433 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"223101594434c8a3099eb59096e4c6165186175fb06d9af6d7193afa29d5f956\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Feb 13 16:06:23.687124 kubelet[2433]: E0213 16:06:23.686999 2433 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"223101594434c8a3099eb59096e4c6165186175fb06d9af6d7193afa29d5f956\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-jwzmm" Feb 13 16:06:23.687124 kubelet[2433]: E0213 16:06:23.687036 2433 kuberuntime_manager.go:1172] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"223101594434c8a3099eb59096e4c6165186175fb06d9af6d7193afa29d5f956\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-jwzmm" Feb 13 16:06:23.687264 kubelet[2433]: E0213 16:06:23.687126 2433 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-6d5f899847-jwzmm_default(a4ac8e51-0419-4e48-addc-04305cc83e26)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-6d5f899847-jwzmm_default(a4ac8e51-0419-4e48-addc-04305cc83e26)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"223101594434c8a3099eb59096e4c6165186175fb06d9af6d7193afa29d5f956\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-6d5f899847-jwzmm" podUID="a4ac8e51-0419-4e48-addc-04305cc83e26" Feb 13 16:06:23.823144 systemd[1]: run-containerd-runc-k8s.io-5e558ba57f0dcd0480c0d532f382d3733e880fd2d6c0b71699ab94aaacda588b-runc.BH1OIj.mount: Deactivated successfully. Feb 13 16:06:23.870217 kubelet[2433]: E0213 16:06:23.870141 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:24.181763 kubelet[2433]: I0213 16:06:24.181441 2433 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-96ll4" podStartSLOduration=6.905678495 podStartE2EDuration="20.181336989s" podCreationTimestamp="2025-02-13 16:06:04 +0000 UTC" firstStartedPulling="2025-02-13 16:06:08.518003023 +0000 UTC m=+5.303641622" lastFinishedPulling="2025-02-13 16:06:21.793661517 +0000 UTC m=+18.579300116" observedRunningTime="2025-02-13 16:06:24.180993273 +0000 UTC m=+20.966631884" watchObservedRunningTime="2025-02-13 16:06:24.181336989 +0000 UTC m=+20.966975600" Feb 13 16:06:24.418834 (udev-worker)[2943]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:06:24.437193 systemd-networkd[1933]: flannel.1: Link UP Feb 13 16:06:24.437208 systemd-networkd[1933]: flannel.1: Gained carrier Feb 13 16:06:24.854287 kubelet[2433]: E0213 16:06:24.854222 2433 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:24.870483 kubelet[2433]: E0213 16:06:24.870429 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:25.527702 systemd-networkd[1933]: flannel.1: Gained IPv6LL Feb 13 16:06:25.871243 kubelet[2433]: E0213 16:06:25.870910 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:26.872026 kubelet[2433]: E0213 16:06:26.871956 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:27.415566 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 13 16:06:27.762083 ntpd[1990]: Listen normally on 7 flannel.1 192.168.1.0:123 Feb 13 16:06:27.762849 ntpd[1990]: 13 Feb 16:06:27 ntpd[1990]: Listen normally on 7 flannel.1 192.168.1.0:123 Feb 13 16:06:27.762849 ntpd[1990]: 13 Feb 16:06:27 ntpd[1990]: Listen normally on 8 flannel.1 [fe80::200e:beff:fe2d:9489%3]:123 Feb 13 16:06:27.762200 ntpd[1990]: Listen normally on 8 flannel.1 [fe80::200e:beff:fe2d:9489%3]:123 Feb 13 16:06:27.872450 kubelet[2433]: E0213 16:06:27.872360 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:28.873112 kubelet[2433]: E0213 16:06:28.873008 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:29.874236 kubelet[2433]: E0213 16:06:29.874159 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:30.874722 kubelet[2433]: E0213 16:06:30.874660 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:31.874935 kubelet[2433]: E0213 16:06:31.874866 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:32.875735 kubelet[2433]: E0213 16:06:32.875664 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:33.876869 kubelet[2433]: E0213 16:06:33.876769 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:34.877457 kubelet[2433]: E0213 16:06:34.877398 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:35.093947 containerd[2014]: time="2025-02-13T16:06:35.093355963Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jwzmm,Uid:a4ac8e51-0419-4e48-addc-04305cc83e26,Namespace:default,Attempt:0,}" Feb 13 16:06:35.131098 systemd-networkd[1933]: cni0: Link UP Feb 13 16:06:35.131119 systemd-networkd[1933]: cni0: Gained carrier Feb 13 16:06:35.139075 (udev-worker)[3084]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:06:35.139932 systemd-networkd[1933]: cni0: Lost carrier Feb 13 16:06:35.159689 systemd-networkd[1933]: veth0549b97b: Link UP Feb 13 16:06:35.162429 kernel: cni0: port 1(veth0549b97b) entered blocking state Feb 13 16:06:35.162549 kernel: cni0: port 1(veth0549b97b) entered disabled state Feb 13 16:06:35.162587 kernel: veth0549b97b: entered allmulticast mode Feb 13 16:06:35.165274 kernel: veth0549b97b: entered promiscuous mode Feb 13 16:06:35.165431 kernel: cni0: port 1(veth0549b97b) entered blocking state Feb 13 16:06:35.166996 kernel: cni0: port 1(veth0549b97b) entered forwarding state Feb 13 16:06:35.168548 kernel: cni0: port 1(veth0549b97b) entered disabled state Feb 13 16:06:35.169262 (udev-worker)[3087]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:06:35.178541 kernel: cni0: port 1(veth0549b97b) entered blocking state Feb 13 16:06:35.178619 kernel: cni0: port 1(veth0549b97b) entered forwarding state Feb 13 16:06:35.177935 systemd-networkd[1933]: veth0549b97b: Gained carrier Feb 13 16:06:35.178667 systemd-networkd[1933]: cni0: Gained carrier Feb 13 16:06:35.183072 containerd[2014]: 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"} Feb 13 16:06:35.183072 containerd[2014]: delegateAdd: netconf sent to delegate plugin: Feb 13 16:06:35.219193 containerd[2014]: {"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-02-13T16:06:35.219038816Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 16:06:35.219566 containerd[2014]: time="2025-02-13T16:06:35.219410600Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 16:06:35.219566 containerd[2014]: time="2025-02-13T16:06:35.219490364Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:35.220054 containerd[2014]: time="2025-02-13T16:06:35.219855980Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:35.253989 systemd[1]: run-containerd-runc-k8s.io-b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342-runc.WwbeZb.mount: Deactivated successfully. Feb 13 16:06:35.264723 systemd[1]: Started cri-containerd-b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342.scope - libcontainer container b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342. Feb 13 16:06:35.323056 containerd[2014]: time="2025-02-13T16:06:35.322951088Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-jwzmm,Uid:a4ac8e51-0419-4e48-addc-04305cc83e26,Namespace:default,Attempt:0,} returns sandbox id \"b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342\"" Feb 13 16:06:35.326319 containerd[2014]: time="2025-02-13T16:06:35.326241872Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Feb 13 16:06:35.877817 kubelet[2433]: E0213 16:06:35.877749 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:36.279594 systemd-networkd[1933]: cni0: Gained IPv6LL Feb 13 16:06:36.878152 kubelet[2433]: E0213 16:06:36.878095 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:37.111705 systemd-networkd[1933]: veth0549b97b: Gained IPv6LL Feb 13 16:06:37.879020 kubelet[2433]: E0213 16:06:37.878947 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:38.880498 kubelet[2433]: E0213 16:06:38.880439 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:39.020806 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2775708878.mount: Deactivated successfully. Feb 13 16:06:39.762283 ntpd[1990]: Listen normally on 9 cni0 192.168.1.1:123 Feb 13 16:06:39.763577 ntpd[1990]: 13 Feb 16:06:39 ntpd[1990]: Listen normally on 9 cni0 192.168.1.1:123 Feb 13 16:06:39.763577 ntpd[1990]: 13 Feb 16:06:39 ntpd[1990]: Listen normally on 10 cni0 [fe80::141c:f2ff:fe99:22e4%4]:123 Feb 13 16:06:39.763577 ntpd[1990]: 13 Feb 16:06:39 ntpd[1990]: Listen normally on 11 veth0549b97b [fe80::6c44:3eff:fe13:3a0d%5]:123 Feb 13 16:06:39.762621 ntpd[1990]: Listen normally on 10 cni0 [fe80::141c:f2ff:fe99:22e4%4]:123 Feb 13 16:06:39.762712 ntpd[1990]: Listen normally on 11 veth0549b97b [fe80::6c44:3eff:fe13:3a0d%5]:123 Feb 13 16:06:39.881402 kubelet[2433]: E0213 16:06:39.880890 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:40.575553 containerd[2014]: time="2025-02-13T16:06:40.575490110Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:40.578718 containerd[2014]: time="2025-02-13T16:06:40.578596046Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69693086" Feb 13 16:06:40.580605 containerd[2014]: time="2025-02-13T16:06:40.580536482Z" level=info msg="ImageCreate event name:\"sha256:dfbfd726d38a926d7664f4738c165e3d91dd9fc1d33959787a30835bf39a461b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:40.586989 containerd[2014]: time="2025-02-13T16:06:40.586881326Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:40.589772 containerd[2014]: time="2025-02-13T16:06:40.589446614Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:dfbfd726d38a926d7664f4738c165e3d91dd9fc1d33959787a30835bf39a461b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\", size \"69692964\" in 5.263129406s" Feb 13 16:06:40.589772 containerd[2014]: time="2025-02-13T16:06:40.589539242Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:dfbfd726d38a926d7664f4738c165e3d91dd9fc1d33959787a30835bf39a461b\"" Feb 13 16:06:40.593766 containerd[2014]: time="2025-02-13T16:06:40.593714954Z" level=info msg="CreateContainer within sandbox \"b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Feb 13 16:06:40.617562 containerd[2014]: time="2025-02-13T16:06:40.616709043Z" level=info msg="CreateContainer within sandbox \"b3dba05c2345db903bbff38b641fc47cfae559b8a4e4c91d6ea63f57ab456342\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"f8c3d60d6a7141cba360a8518baf9479c698e525896729819747b8059db348e3\"" Feb 13 16:06:40.618799 containerd[2014]: time="2025-02-13T16:06:40.618046419Z" level=info msg="StartContainer for \"f8c3d60d6a7141cba360a8518baf9479c698e525896729819747b8059db348e3\"" Feb 13 16:06:40.669718 systemd[1]: Started cri-containerd-f8c3d60d6a7141cba360a8518baf9479c698e525896729819747b8059db348e3.scope - libcontainer container f8c3d60d6a7141cba360a8518baf9479c698e525896729819747b8059db348e3. Feb 13 16:06:40.715544 containerd[2014]: time="2025-02-13T16:06:40.715322715Z" level=info msg="StartContainer for \"f8c3d60d6a7141cba360a8518baf9479c698e525896729819747b8059db348e3\" returns successfully" Feb 13 16:06:40.882113 kubelet[2433]: E0213 16:06:40.881919 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:41.220499 kubelet[2433]: I0213 16:06:41.220276 2433 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-jwzmm" podStartSLOduration=12.955506744000001 podStartE2EDuration="18.220217642s" podCreationTimestamp="2025-02-13 16:06:23 +0000 UTC" firstStartedPulling="2025-02-13 16:06:35.325484732 +0000 UTC m=+32.111123331" lastFinishedPulling="2025-02-13 16:06:40.59019563 +0000 UTC m=+37.375834229" observedRunningTime="2025-02-13 16:06:41.21985667 +0000 UTC m=+38.005495281" watchObservedRunningTime="2025-02-13 16:06:41.220217642 +0000 UTC m=+38.005856265" Feb 13 16:06:41.882914 kubelet[2433]: E0213 16:06:41.882849 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:42.313534 update_engine[1995]: I20250213 16:06:42.313436 1995 update_attempter.cc:509] Updating boot flags... Feb 13 16:06:42.391952 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3261) Feb 13 16:06:42.665437 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3260) Feb 13 16:06:42.883280 kubelet[2433]: E0213 16:06:42.883187 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:42.910506 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3260) Feb 13 16:06:43.883791 kubelet[2433]: E0213 16:06:43.883724 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:44.853853 kubelet[2433]: E0213 16:06:44.853783 2433 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:44.883996 kubelet[2433]: E0213 16:06:44.883902 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:45.884271 kubelet[2433]: E0213 16:06:45.884201 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:46.884516 kubelet[2433]: E0213 16:06:46.884450 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:47.885515 kubelet[2433]: E0213 16:06:47.885438 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:48.886196 kubelet[2433]: E0213 16:06:48.886114 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:48.902146 kubelet[2433]: I0213 16:06:48.902038 2433 topology_manager.go:215] "Topology Admit Handler" podUID="62c49feb-f6c8-42f8-aedb-b9403d7296ce" podNamespace="default" podName="nfs-server-provisioner-0" Feb 13 16:06:48.914467 systemd[1]: Created slice kubepods-besteffort-pod62c49feb_f6c8_42f8_aedb_b9403d7296ce.slice - libcontainer container kubepods-besteffort-pod62c49feb_f6c8_42f8_aedb_b9403d7296ce.slice. Feb 13 16:06:48.951685 kubelet[2433]: I0213 16:06:48.951507 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/62c49feb-f6c8-42f8-aedb-b9403d7296ce-data\") pod \"nfs-server-provisioner-0\" (UID: \"62c49feb-f6c8-42f8-aedb-b9403d7296ce\") " pod="default/nfs-server-provisioner-0" Feb 13 16:06:48.951685 kubelet[2433]: I0213 16:06:48.951587 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zc4f9\" (UniqueName: \"kubernetes.io/projected/62c49feb-f6c8-42f8-aedb-b9403d7296ce-kube-api-access-zc4f9\") pod \"nfs-server-provisioner-0\" (UID: \"62c49feb-f6c8-42f8-aedb-b9403d7296ce\") " pod="default/nfs-server-provisioner-0" Feb 13 16:06:49.221532 containerd[2014]: time="2025-02-13T16:06:49.221239077Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:62c49feb-f6c8-42f8-aedb-b9403d7296ce,Namespace:default,Attempt:0,}" Feb 13 16:06:49.272283 (udev-worker)[3540]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:06:49.274952 systemd-networkd[1933]: veth1cc192d7: Link UP Feb 13 16:06:49.279011 kernel: cni0: port 2(veth1cc192d7) entered blocking state Feb 13 16:06:49.279131 kernel: cni0: port 2(veth1cc192d7) entered disabled state Feb 13 16:06:49.279175 kernel: veth1cc192d7: entered allmulticast mode Feb 13 16:06:49.280731 kernel: veth1cc192d7: entered promiscuous mode Feb 13 16:06:49.290612 kernel: cni0: port 2(veth1cc192d7) entered blocking state Feb 13 16:06:49.296552 kernel: cni0: port 2(veth1cc192d7) entered forwarding state Feb 13 16:06:49.294445 systemd-networkd[1933]: veth1cc192d7: Gained carrier Feb 13 16:06:49.303079 containerd[2014]: 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"} Feb 13 16:06:49.303079 containerd[2014]: delegateAdd: netconf sent to delegate plugin: Feb 13 16:06:49.336875 containerd[2014]: {"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-02-13T16:06:49.336274474Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 16:06:49.336875 containerd[2014]: time="2025-02-13T16:06:49.336423322Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 16:06:49.336875 containerd[2014]: time="2025-02-13T16:06:49.336469954Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:49.336875 containerd[2014]: time="2025-02-13T16:06:49.336645862Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:06:49.380719 systemd[1]: Started cri-containerd-40d7508ca2a8a5d1285f99683940c912597bf5b4becb725755187d01ae34638c.scope - libcontainer container 40d7508ca2a8a5d1285f99683940c912597bf5b4becb725755187d01ae34638c. Feb 13 16:06:49.442335 containerd[2014]: time="2025-02-13T16:06:49.442243114Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:62c49feb-f6c8-42f8-aedb-b9403d7296ce,Namespace:default,Attempt:0,} returns sandbox id \"40d7508ca2a8a5d1285f99683940c912597bf5b4becb725755187d01ae34638c\"" Feb 13 16:06:49.446437 containerd[2014]: time="2025-02-13T16:06:49.446356618Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Feb 13 16:06:49.886871 kubelet[2433]: E0213 16:06:49.886815 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:50.808674 systemd-networkd[1933]: veth1cc192d7: Gained IPv6LL Feb 13 16:06:50.889193 kubelet[2433]: E0213 16:06:50.888132 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:51.889004 kubelet[2433]: E0213 16:06:51.888935 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:52.396893 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1521329130.mount: Deactivated successfully. Feb 13 16:06:52.889541 kubelet[2433]: E0213 16:06:52.889465 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:53.762206 ntpd[1990]: Listen normally on 12 veth1cc192d7 [fe80::d4f3:3bff:fede:44ba%6]:123 Feb 13 16:06:53.762878 ntpd[1990]: 13 Feb 16:06:53 ntpd[1990]: Listen normally on 12 veth1cc192d7 [fe80::d4f3:3bff:fede:44ba%6]:123 Feb 13 16:06:53.889799 kubelet[2433]: E0213 16:06:53.889721 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:54.890318 kubelet[2433]: E0213 16:06:54.890249 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:55.890872 kubelet[2433]: E0213 16:06:55.890804 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:56.891912 kubelet[2433]: E0213 16:06:56.891841 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:57.545524 containerd[2014]: time="2025-02-13T16:06:57.544584991Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:57.547086 containerd[2014]: time="2025-02-13T16:06:57.547011847Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Feb 13 16:06:57.549136 containerd[2014]: time="2025-02-13T16:06:57.549047695Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:57.554409 containerd[2014]: time="2025-02-13T16:06:57.554307691Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:06:57.557612 containerd[2014]: time="2025-02-13T16:06:57.556985155Z" 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 8.110350101s" Feb 13 16:06:57.557612 containerd[2014]: time="2025-02-13T16:06:57.557056387Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Feb 13 16:06:57.561570 containerd[2014]: time="2025-02-13T16:06:57.561495199Z" level=info msg="CreateContainer within sandbox \"40d7508ca2a8a5d1285f99683940c912597bf5b4becb725755187d01ae34638c\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Feb 13 16:06:57.584798 containerd[2014]: time="2025-02-13T16:06:57.584659615Z" level=info msg="CreateContainer within sandbox \"40d7508ca2a8a5d1285f99683940c912597bf5b4becb725755187d01ae34638c\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"d8733090c83a79e445c64b44c51d33e5d19c32301e6d1cb4578af38d7a0efd81\"" Feb 13 16:06:57.585608 containerd[2014]: time="2025-02-13T16:06:57.585356563Z" level=info msg="StartContainer for \"d8733090c83a79e445c64b44c51d33e5d19c32301e6d1cb4578af38d7a0efd81\"" Feb 13 16:06:57.637706 systemd[1]: Started cri-containerd-d8733090c83a79e445c64b44c51d33e5d19c32301e6d1cb4578af38d7a0efd81.scope - libcontainer container d8733090c83a79e445c64b44c51d33e5d19c32301e6d1cb4578af38d7a0efd81. Feb 13 16:06:57.693537 containerd[2014]: time="2025-02-13T16:06:57.693341227Z" level=info msg="StartContainer for \"d8733090c83a79e445c64b44c51d33e5d19c32301e6d1cb4578af38d7a0efd81\" returns successfully" Feb 13 16:06:57.893196 kubelet[2433]: E0213 16:06:57.893024 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:58.894102 kubelet[2433]: E0213 16:06:58.894028 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:06:59.894528 kubelet[2433]: E0213 16:06:59.894463 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:00.895211 kubelet[2433]: E0213 16:07:00.895143 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:01.896232 kubelet[2433]: E0213 16:07:01.896163 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:02.897226 kubelet[2433]: E0213 16:07:02.897157 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:03.897982 kubelet[2433]: E0213 16:07:03.897912 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:04.853834 kubelet[2433]: E0213 16:07:04.853760 2433 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:04.899197 kubelet[2433]: E0213 16:07:04.899064 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:05.899728 kubelet[2433]: E0213 16:07:05.899639 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:06.900725 kubelet[2433]: E0213 16:07:06.900655 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:07.900955 kubelet[2433]: E0213 16:07:07.900877 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:08.028828 kubelet[2433]: I0213 16:07:08.028740 2433 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.91655233 podStartE2EDuration="20.028644363s" podCreationTimestamp="2025-02-13 16:06:48 +0000 UTC" firstStartedPulling="2025-02-13 16:06:49.445343494 +0000 UTC m=+46.230982093" lastFinishedPulling="2025-02-13 16:06:57.557435539 +0000 UTC m=+54.343074126" observedRunningTime="2025-02-13 16:06:58.266699778 +0000 UTC m=+55.052338401" watchObservedRunningTime="2025-02-13 16:07:08.028644363 +0000 UTC m=+64.814282986" Feb 13 16:07:08.029137 kubelet[2433]: I0213 16:07:08.029092 2433 topology_manager.go:215] "Topology Admit Handler" podUID="495739db-41fb-4cf0-85a0-c760c24468c5" podNamespace="default" podName="test-pod-1" Feb 13 16:07:08.041528 systemd[1]: Created slice kubepods-besteffort-pod495739db_41fb_4cf0_85a0_c760c24468c5.slice - libcontainer container kubepods-besteffort-pod495739db_41fb_4cf0_85a0_c760c24468c5.slice. Feb 13 16:07:08.070588 kubelet[2433]: I0213 16:07:08.070206 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9sjsx\" (UniqueName: \"kubernetes.io/projected/495739db-41fb-4cf0-85a0-c760c24468c5-kube-api-access-9sjsx\") pod \"test-pod-1\" (UID: \"495739db-41fb-4cf0-85a0-c760c24468c5\") " pod="default/test-pod-1" Feb 13 16:07:08.070588 kubelet[2433]: I0213 16:07:08.070284 2433 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-8c12c402-b810-4666-b940-e44b33519b31\" (UniqueName: \"kubernetes.io/nfs/495739db-41fb-4cf0-85a0-c760c24468c5-pvc-8c12c402-b810-4666-b940-e44b33519b31\") pod \"test-pod-1\" (UID: \"495739db-41fb-4cf0-85a0-c760c24468c5\") " pod="default/test-pod-1" Feb 13 16:07:08.234442 kernel: FS-Cache: Loaded Feb 13 16:07:08.287584 kernel: RPC: Registered named UNIX socket transport module. Feb 13 16:07:08.287733 kernel: RPC: Registered udp transport module. Feb 13 16:07:08.288742 kernel: RPC: Registered tcp transport module. Feb 13 16:07:08.288823 kernel: RPC: Registered tcp-with-tls transport module. Feb 13 16:07:08.289729 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 13 16:07:08.626219 kernel: NFS: Registering the id_resolver key type Feb 13 16:07:08.626387 kernel: Key type id_resolver registered Feb 13 16:07:08.626437 kernel: Key type id_legacy registered Feb 13 16:07:08.703179 nfsidmap[3818]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Feb 13 16:07:08.709571 nfsidmap[3819]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Feb 13 16:07:08.901621 kubelet[2433]: E0213 16:07:08.901552 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:08.948752 containerd[2014]: time="2025-02-13T16:07:08.948677131Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:495739db-41fb-4cf0-85a0-c760c24468c5,Namespace:default,Attempt:0,}" Feb 13 16:07:08.997193 (udev-worker)[3807]: Network interface NamePolicy= disabled on kernel command line. Feb 13 16:07:08.998398 systemd-networkd[1933]: vethc9d63d95: Link UP Feb 13 16:07:09.002426 kernel: cni0: port 3(vethc9d63d95) entered blocking state Feb 13 16:07:09.002613 kernel: cni0: port 3(vethc9d63d95) entered disabled state Feb 13 16:07:09.002680 kernel: vethc9d63d95: entered allmulticast mode Feb 13 16:07:09.002792 kernel: vethc9d63d95: entered promiscuous mode Feb 13 16:07:09.013640 kernel: cni0: port 3(vethc9d63d95) entered blocking state Feb 13 16:07:09.013804 kernel: cni0: port 3(vethc9d63d95) entered forwarding state Feb 13 16:07:09.013772 systemd-networkd[1933]: vethc9d63d95: Gained carrier Feb 13 16:07:09.020355 containerd[2014]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000106628), "name":"cbr0", "type":"bridge"} Feb 13 16:07:09.020355 containerd[2014]: delegateAdd: netconf sent to delegate plugin: Feb 13 16:07:09.056034 containerd[2014]: {"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-02-13T16:07:09.055833232Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 16:07:09.056034 containerd[2014]: time="2025-02-13T16:07:09.055946656Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 16:07:09.056654 containerd[2014]: time="2025-02-13T16:07:09.055991824Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:07:09.056654 containerd[2014]: time="2025-02-13T16:07:09.056174932Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 16:07:09.094688 systemd[1]: Started cri-containerd-0d840c20a3b9ff65dc4d21020503943ab04f253b3b6115400865a41c73e58993.scope - libcontainer container 0d840c20a3b9ff65dc4d21020503943ab04f253b3b6115400865a41c73e58993. Feb 13 16:07:09.156520 containerd[2014]: time="2025-02-13T16:07:09.156360664Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:495739db-41fb-4cf0-85a0-c760c24468c5,Namespace:default,Attempt:0,} returns sandbox id \"0d840c20a3b9ff65dc4d21020503943ab04f253b3b6115400865a41c73e58993\"" Feb 13 16:07:09.159888 containerd[2014]: time="2025-02-13T16:07:09.159835696Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Feb 13 16:07:09.816824 containerd[2014]: time="2025-02-13T16:07:09.816756020Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 16:07:09.818209 containerd[2014]: time="2025-02-13T16:07:09.818142584Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Feb 13 16:07:09.824562 containerd[2014]: time="2025-02-13T16:07:09.824487092Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:dfbfd726d38a926d7664f4738c165e3d91dd9fc1d33959787a30835bf39a461b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\", size \"69692964\" in 664.379176ms" Feb 13 16:07:09.824562 containerd[2014]: time="2025-02-13T16:07:09.824557628Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:dfbfd726d38a926d7664f4738c165e3d91dd9fc1d33959787a30835bf39a461b\"" Feb 13 16:07:09.827877 containerd[2014]: time="2025-02-13T16:07:09.827605964Z" level=info msg="CreateContainer within sandbox \"0d840c20a3b9ff65dc4d21020503943ab04f253b3b6115400865a41c73e58993\" for container &ContainerMetadata{Name:test,Attempt:0,}" Feb 13 16:07:09.850036 containerd[2014]: time="2025-02-13T16:07:09.849951704Z" level=info msg="CreateContainer within sandbox \"0d840c20a3b9ff65dc4d21020503943ab04f253b3b6115400865a41c73e58993\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"71fd5d0d35df6c6f79ccfac246da5efbc83ac7da9b15e3ccc4c9ab97c6b04bbe\"" Feb 13 16:07:09.851058 containerd[2014]: time="2025-02-13T16:07:09.850976216Z" level=info msg="StartContainer for \"71fd5d0d35df6c6f79ccfac246da5efbc83ac7da9b15e3ccc4c9ab97c6b04bbe\"" Feb 13 16:07:09.902093 kubelet[2433]: E0213 16:07:09.902053 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:09.908608 systemd[1]: Started cri-containerd-71fd5d0d35df6c6f79ccfac246da5efbc83ac7da9b15e3ccc4c9ab97c6b04bbe.scope - libcontainer container 71fd5d0d35df6c6f79ccfac246da5efbc83ac7da9b15e3ccc4c9ab97c6b04bbe. Feb 13 16:07:09.995872 containerd[2014]: time="2025-02-13T16:07:09.995674676Z" level=info msg="StartContainer for \"71fd5d0d35df6c6f79ccfac246da5efbc83ac7da9b15e3ccc4c9ab97c6b04bbe\" returns successfully" Feb 13 16:07:10.903586 kubelet[2433]: E0213 16:07:10.903525 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:11.031679 systemd-networkd[1933]: vethc9d63d95: Gained IPv6LL Feb 13 16:07:11.904333 kubelet[2433]: E0213 16:07:11.904260 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:12.904558 kubelet[2433]: E0213 16:07:12.904468 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 16:07:13.762286 ntpd[1990]: Listen normally on 13 vethc9d63d95 [fe80::1c01:8dff:fe95:b3c7%7]:123 Feb 13 16:07:13.762908 ntpd[1990]: 13 Feb 16:07:13 ntpd[1990]: Listen normally on 13 vethc9d63d95 [fe80::1c01:8dff:fe95:b3c7%7]:123 Feb 13 16:07:13.905050 kubelet[2433]: E0213 16:07:13.904982 2433 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"