May 15 23:41:14.177282 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] May 15 23:41:14.177326 kernel: Linux version 6.6.90-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Thu May 15 22:19:24 -00 2025 May 15 23:41:14.177350 kernel: KASLR disabled due to lack of seed May 15 23:41:14.177366 kernel: efi: EFI v2.7 by EDK II May 15 23:41:14.177382 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b000a98 MEMRESERVE=0x78503d98 May 15 23:41:14.177397 kernel: secureboot: Secure boot disabled May 15 23:41:14.177414 kernel: ACPI: Early table checksum verification disabled May 15 23:41:14.177429 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) May 15 23:41:14.177445 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) May 15 23:41:14.177460 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) May 15 23:41:14.177479 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) May 15 23:41:14.177495 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) May 15 23:41:14.177510 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) May 15 23:41:14.177525 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) May 15 23:41:14.177543 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) May 15 23:41:14.177563 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) May 15 23:41:14.177579 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) May 15 23:41:14.177596 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) May 15 23:41:14.177611 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 May 15 23:41:14.177627 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') May 15 23:41:14.177643 kernel: printk: bootconsole [uart0] enabled May 15 23:41:14.177659 kernel: NUMA: Failed to initialise from firmware May 15 23:41:14.177675 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] May 15 23:41:14.177692 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] May 15 23:41:14.177708 kernel: Zone ranges: May 15 23:41:14.179980 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] May 15 23:41:14.180036 kernel: DMA32 empty May 15 23:41:14.180054 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] May 15 23:41:14.180071 kernel: Movable zone start for each node May 15 23:41:14.180087 kernel: Early memory node ranges May 15 23:41:14.180103 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] May 15 23:41:14.180119 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] May 15 23:41:14.180135 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] May 15 23:41:14.180151 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] May 15 23:41:14.180167 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] May 15 23:41:14.180183 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] May 15 23:41:14.180199 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] May 15 23:41:14.180215 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] May 15 23:41:14.180235 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] May 15 23:41:14.180252 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges May 15 23:41:14.180275 kernel: psci: probing for conduit method from ACPI. May 15 23:41:14.180292 kernel: psci: PSCIv1.0 detected in firmware. May 15 23:41:14.180309 kernel: psci: Using standard PSCI v0.2 function IDs May 15 23:41:14.180329 kernel: psci: Trusted OS migration not required May 15 23:41:14.180347 kernel: psci: SMC Calling Convention v1.1 May 15 23:41:14.180363 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 15 23:41:14.180380 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 15 23:41:14.180398 kernel: pcpu-alloc: [0] 0 [0] 1 May 15 23:41:14.180415 kernel: Detected PIPT I-cache on CPU0 May 15 23:41:14.180432 kernel: CPU features: detected: GIC system register CPU interface May 15 23:41:14.180449 kernel: CPU features: detected: Spectre-v2 May 15 23:41:14.180465 kernel: CPU features: detected: Spectre-v3a May 15 23:41:14.180482 kernel: CPU features: detected: Spectre-BHB May 15 23:41:14.180499 kernel: CPU features: detected: ARM erratum 1742098 May 15 23:41:14.180515 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 May 15 23:41:14.180536 kernel: alternatives: applying boot alternatives May 15 23:41:14.180555 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=a39d79b1d2ff9998339b60958cf17b8dfae5bd16f05fb844c0e06a5d7107915a May 15 23:41:14.180574 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 23:41:14.180591 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 23:41:14.180608 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 23:41:14.180625 kernel: Fallback order for Node 0: 0 May 15 23:41:14.180642 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 May 15 23:41:14.180658 kernel: Policy zone: Normal May 15 23:41:14.180675 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 23:41:14.180692 kernel: software IO TLB: area num 2. May 15 23:41:14.180713 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) May 15 23:41:14.180731 kernel: Memory: 3819896K/4030464K available (10240K kernel code, 2186K rwdata, 8108K rodata, 39744K init, 897K bss, 210568K reserved, 0K cma-reserved) May 15 23:41:14.180748 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 15 23:41:14.180765 kernel: rcu: Preemptible hierarchical RCU implementation. May 15 23:41:14.180783 kernel: rcu: RCU event tracing is enabled. May 15 23:41:14.180800 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 15 23:41:14.180818 kernel: Trampoline variant of Tasks RCU enabled. May 15 23:41:14.180835 kernel: Tracing variant of Tasks RCU enabled. May 15 23:41:14.180852 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 23:41:14.180917 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 15 23:41:14.180937 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 15 23:41:14.180959 kernel: GICv3: 96 SPIs implemented May 15 23:41:14.180977 kernel: GICv3: 0 Extended SPIs implemented May 15 23:41:14.180994 kernel: Root IRQ handler: gic_handle_irq May 15 23:41:14.181011 kernel: GICv3: GICv3 features: 16 PPIs May 15 23:41:14.181028 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 May 15 23:41:14.181044 kernel: ITS [mem 0x10080000-0x1009ffff] May 15 23:41:14.181061 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) May 15 23:41:14.181079 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) May 15 23:41:14.181096 kernel: GICv3: using LPI property table @0x00000004000d0000 May 15 23:41:14.181112 kernel: ITS: Using hypervisor restricted LPI range [128] May 15 23:41:14.181129 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 May 15 23:41:14.181146 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 15 23:41:14.181167 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). May 15 23:41:14.181184 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns May 15 23:41:14.181201 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns May 15 23:41:14.181218 kernel: Console: colour dummy device 80x25 May 15 23:41:14.181236 kernel: printk: console [tty1] enabled May 15 23:41:14.181253 kernel: ACPI: Core revision 20230628 May 15 23:41:14.181271 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) May 15 23:41:14.181288 kernel: pid_max: default: 32768 minimum: 301 May 15 23:41:14.181306 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 15 23:41:14.181323 kernel: landlock: Up and running. May 15 23:41:14.181344 kernel: SELinux: Initializing. May 15 23:41:14.181362 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 23:41:14.181379 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 23:41:14.181397 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 15 23:41:14.181415 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 15 23:41:14.181434 kernel: rcu: Hierarchical SRCU implementation. May 15 23:41:14.181452 kernel: rcu: Max phase no-delay instances is 400. May 15 23:41:14.181470 kernel: Platform MSI: ITS@0x10080000 domain created May 15 23:41:14.181491 kernel: PCI/MSI: ITS@0x10080000 domain created May 15 23:41:14.181509 kernel: Remapping and enabling EFI services. May 15 23:41:14.181526 kernel: smp: Bringing up secondary CPUs ... May 15 23:41:14.181544 kernel: Detected PIPT I-cache on CPU1 May 15 23:41:14.181561 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 May 15 23:41:14.181578 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 May 15 23:41:14.181596 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] May 15 23:41:14.181613 kernel: smp: Brought up 1 node, 2 CPUs May 15 23:41:14.181630 kernel: SMP: Total of 2 processors activated. May 15 23:41:14.181647 kernel: CPU features: detected: 32-bit EL0 Support May 15 23:41:14.181668 kernel: CPU features: detected: 32-bit EL1 Support May 15 23:41:14.181686 kernel: CPU features: detected: CRC32 instructions May 15 23:41:14.181714 kernel: CPU: All CPU(s) started at EL1 May 15 23:41:14.181736 kernel: alternatives: applying system-wide alternatives May 15 23:41:14.181754 kernel: devtmpfs: initialized May 15 23:41:14.181772 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 23:41:14.181790 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 15 23:41:14.181807 kernel: pinctrl core: initialized pinctrl subsystem May 15 23:41:14.181825 kernel: SMBIOS 3.0.0 present. May 15 23:41:14.181847 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 May 15 23:41:14.186100 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 23:41:14.186138 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 15 23:41:14.186172 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 15 23:41:14.186196 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 15 23:41:14.186215 kernel: audit: initializing netlink subsys (disabled) May 15 23:41:14.186234 kernel: audit: type=2000 audit(0.219:1): state=initialized audit_enabled=0 res=1 May 15 23:41:14.186263 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 23:41:14.186282 kernel: cpuidle: using governor menu May 15 23:41:14.186300 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 15 23:41:14.186319 kernel: ASID allocator initialised with 65536 entries May 15 23:41:14.186337 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 23:41:14.186355 kernel: Serial: AMBA PL011 UART driver May 15 23:41:14.186373 kernel: Modules: 17424 pages in range for non-PLT usage May 15 23:41:14.186391 kernel: Modules: 508944 pages in range for PLT usage May 15 23:41:14.186409 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 15 23:41:14.186431 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 15 23:41:14.186450 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 15 23:41:14.186468 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 15 23:41:14.186486 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 15 23:41:14.186504 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 15 23:41:14.186522 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 15 23:41:14.186540 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 15 23:41:14.186558 kernel: ACPI: Added _OSI(Module Device) May 15 23:41:14.186576 kernel: ACPI: Added _OSI(Processor Device) May 15 23:41:14.186598 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 23:41:14.186616 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 23:41:14.186634 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 23:41:14.186652 kernel: ACPI: Interpreter enabled May 15 23:41:14.186670 kernel: ACPI: Using GIC for interrupt routing May 15 23:41:14.186688 kernel: ACPI: MCFG table detected, 1 entries May 15 23:41:14.186706 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) May 15 23:41:14.187034 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 15 23:41:14.187247 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 15 23:41:14.187451 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 15 23:41:14.187650 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 May 15 23:41:14.187848 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] May 15 23:41:14.189982 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] May 15 23:41:14.190014 kernel: acpiphp: Slot [1] registered May 15 23:41:14.190033 kernel: acpiphp: Slot [2] registered May 15 23:41:14.190051 kernel: acpiphp: Slot [3] registered May 15 23:41:14.190079 kernel: acpiphp: Slot [4] registered May 15 23:41:14.190097 kernel: acpiphp: Slot [5] registered May 15 23:41:14.190115 kernel: acpiphp: Slot [6] registered May 15 23:41:14.190133 kernel: acpiphp: Slot [7] registered May 15 23:41:14.190151 kernel: acpiphp: Slot [8] registered May 15 23:41:14.190190 kernel: acpiphp: Slot [9] registered May 15 23:41:14.190209 kernel: acpiphp: Slot [10] registered May 15 23:41:14.190228 kernel: acpiphp: Slot [11] registered May 15 23:41:14.190246 kernel: acpiphp: Slot [12] registered May 15 23:41:14.190265 kernel: acpiphp: Slot [13] registered May 15 23:41:14.190290 kernel: acpiphp: Slot [14] registered May 15 23:41:14.190309 kernel: acpiphp: Slot [15] registered May 15 23:41:14.190328 kernel: acpiphp: Slot [16] registered May 15 23:41:14.190347 kernel: acpiphp: Slot [17] registered May 15 23:41:14.190365 kernel: acpiphp: Slot [18] registered May 15 23:41:14.190383 kernel: acpiphp: Slot [19] registered May 15 23:41:14.190401 kernel: acpiphp: Slot [20] registered May 15 23:41:14.190418 kernel: acpiphp: Slot [21] registered May 15 23:41:14.190436 kernel: acpiphp: Slot [22] registered May 15 23:41:14.190458 kernel: acpiphp: Slot [23] registered May 15 23:41:14.190477 kernel: acpiphp: Slot [24] registered May 15 23:41:14.190494 kernel: acpiphp: Slot [25] registered May 15 23:41:14.190512 kernel: acpiphp: Slot [26] registered May 15 23:41:14.190530 kernel: acpiphp: Slot [27] registered May 15 23:41:14.190547 kernel: acpiphp: Slot [28] registered May 15 23:41:14.190565 kernel: acpiphp: Slot [29] registered May 15 23:41:14.190583 kernel: acpiphp: Slot [30] registered May 15 23:41:14.190601 kernel: acpiphp: Slot [31] registered May 15 23:41:14.190619 kernel: PCI host bridge to bus 0000:00 May 15 23:41:14.193045 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] May 15 23:41:14.193277 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 15 23:41:14.193454 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] May 15 23:41:14.193629 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] May 15 23:41:14.194927 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 May 15 23:41:14.195347 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 May 15 23:41:14.195573 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] May 15 23:41:14.195798 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 May 15 23:41:14.196046 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] May 15 23:41:14.196259 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold May 15 23:41:14.196486 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 May 15 23:41:14.196688 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] May 15 23:41:14.200409 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] May 15 23:41:14.200684 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] May 15 23:41:14.200948 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold May 15 23:41:14.201166 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] May 15 23:41:14.201379 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] May 15 23:41:14.201594 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] May 15 23:41:14.201801 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] May 15 23:41:14.204032 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] May 15 23:41:14.204252 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] May 15 23:41:14.204429 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 15 23:41:14.204604 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] May 15 23:41:14.204630 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 15 23:41:14.204650 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 15 23:41:14.204668 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 15 23:41:14.204687 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 15 23:41:14.204705 kernel: iommu: Default domain type: Translated May 15 23:41:14.204728 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 15 23:41:14.204747 kernel: efivars: Registered efivars operations May 15 23:41:14.204765 kernel: vgaarb: loaded May 15 23:41:14.204783 kernel: clocksource: Switched to clocksource arch_sys_counter May 15 23:41:14.204800 kernel: VFS: Disk quotas dquot_6.6.0 May 15 23:41:14.204819 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 23:41:14.204837 kernel: pnp: PnP ACPI init May 15 23:41:14.205128 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved May 15 23:41:14.205164 kernel: pnp: PnP ACPI: found 1 devices May 15 23:41:14.205183 kernel: NET: Registered PF_INET protocol family May 15 23:41:14.205202 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 15 23:41:14.205220 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 15 23:41:14.205239 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 23:41:14.205257 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 15 23:41:14.205275 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 15 23:41:14.205294 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 15 23:41:14.205312 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 23:41:14.205334 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 23:41:14.205352 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 23:41:14.205370 kernel: PCI: CLS 0 bytes, default 64 May 15 23:41:14.205389 kernel: kvm [1]: HYP mode not available May 15 23:41:14.205407 kernel: Initialise system trusted keyrings May 15 23:41:14.205425 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 15 23:41:14.205443 kernel: Key type asymmetric registered May 15 23:41:14.205461 kernel: Asymmetric key parser 'x509' registered May 15 23:41:14.205479 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 15 23:41:14.205501 kernel: io scheduler mq-deadline registered May 15 23:41:14.205519 kernel: io scheduler kyber registered May 15 23:41:14.205537 kernel: io scheduler bfq registered May 15 23:41:14.205743 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered May 15 23:41:14.205771 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 15 23:41:14.205789 kernel: ACPI: button: Power Button [PWRB] May 15 23:41:14.205808 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 May 15 23:41:14.205826 kernel: ACPI: button: Sleep Button [SLPB] May 15 23:41:14.205849 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 23:41:14.206642 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 May 15 23:41:14.207020 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) May 15 23:41:14.207049 kernel: printk: console [ttyS0] disabled May 15 23:41:14.207068 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A May 15 23:41:14.207087 kernel: printk: console [ttyS0] enabled May 15 23:41:14.207105 kernel: printk: bootconsole [uart0] disabled May 15 23:41:14.207123 kernel: thunder_xcv, ver 1.0 May 15 23:41:14.207141 kernel: thunder_bgx, ver 1.0 May 15 23:41:14.207159 kernel: nicpf, ver 1.0 May 15 23:41:14.207184 kernel: nicvf, ver 1.0 May 15 23:41:14.207400 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 15 23:41:14.207617 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-15T23:41:13 UTC (1747352473) May 15 23:41:14.207643 kernel: hid: raw HID events driver (C) Jiri Kosina May 15 23:41:14.207662 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available May 15 23:41:14.207681 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 15 23:41:14.207699 kernel: watchdog: Hard watchdog permanently disabled May 15 23:41:14.207723 kernel: NET: Registered PF_INET6 protocol family May 15 23:41:14.207741 kernel: Segment Routing with IPv6 May 15 23:41:14.207759 kernel: In-situ OAM (IOAM) with IPv6 May 15 23:41:14.207777 kernel: NET: Registered PF_PACKET protocol family May 15 23:41:14.207795 kernel: Key type dns_resolver registered May 15 23:41:14.207812 kernel: registered taskstats version 1 May 15 23:41:14.207830 kernel: Loading compiled-in X.509 certificates May 15 23:41:14.207849 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.90-flatcar: c5ee9c587519d4ef57ff0de9630e786a4c7faded' May 15 23:41:14.210611 kernel: Key type .fscrypt registered May 15 23:41:14.210645 kernel: Key type fscrypt-provisioning registered May 15 23:41:14.210674 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 23:41:14.210693 kernel: ima: Allocated hash algorithm: sha1 May 15 23:41:14.210711 kernel: ima: No architecture policies found May 15 23:41:14.210730 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 15 23:41:14.210748 kernel: clk: Disabling unused clocks May 15 23:41:14.210766 kernel: Freeing unused kernel memory: 39744K May 15 23:41:14.210784 kernel: Run /init as init process May 15 23:41:14.210803 kernel: with arguments: May 15 23:41:14.210821 kernel: /init May 15 23:41:14.210843 kernel: with environment: May 15 23:41:14.211802 kernel: HOME=/ May 15 23:41:14.211841 kernel: TERM=linux May 15 23:41:14.211883 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 23:41:14.211911 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) May 15 23:41:14.211936 systemd[1]: Detected virtualization amazon. May 15 23:41:14.211957 systemd[1]: Detected architecture arm64. May 15 23:41:14.211984 systemd[1]: Running in initrd. May 15 23:41:14.212003 systemd[1]: No hostname configured, using default hostname. May 15 23:41:14.212022 systemd[1]: Hostname set to . May 15 23:41:14.212042 systemd[1]: Initializing machine ID from VM UUID. May 15 23:41:14.212062 systemd[1]: Queued start job for default target initrd.target. May 15 23:41:14.212082 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:41:14.212103 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:41:14.212125 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 15 23:41:14.212150 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 23:41:14.212170 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 15 23:41:14.212191 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 15 23:41:14.212214 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 15 23:41:14.212234 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 15 23:41:14.212254 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:41:14.212274 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 23:41:14.212298 systemd[1]: Reached target paths.target - Path Units. May 15 23:41:14.212319 systemd[1]: Reached target slices.target - Slice Units. May 15 23:41:14.212338 systemd[1]: Reached target swap.target - Swaps. May 15 23:41:14.212358 systemd[1]: Reached target timers.target - Timer Units. May 15 23:41:14.212378 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 15 23:41:14.212398 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 23:41:14.212418 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 15 23:41:14.212437 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 15 23:41:14.212457 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 23:41:14.212482 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 23:41:14.212502 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:41:14.212522 systemd[1]: Reached target sockets.target - Socket Units. May 15 23:41:14.212541 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 15 23:41:14.212561 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 23:41:14.212581 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 15 23:41:14.212601 systemd[1]: Starting systemd-fsck-usr.service... May 15 23:41:14.212621 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 23:41:14.212646 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 23:41:14.212666 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:41:14.212686 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 15 23:41:14.212705 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:41:14.212725 systemd[1]: Finished systemd-fsck-usr.service. May 15 23:41:14.212796 systemd-journald[252]: Collecting audit messages is disabled. May 15 23:41:14.212845 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 23:41:14.212889 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 15 23:41:14.212911 kernel: Bridge firewalling registered May 15 23:41:14.212939 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 23:41:14.212959 systemd-journald[252]: Journal started May 15 23:41:14.212996 systemd-journald[252]: Runtime Journal (/run/log/journal/ec2a0843d5a7d78167b98d1959c9ea75) is 8.0M, max 75.3M, 67.3M free. May 15 23:41:14.172857 systemd-modules-load[253]: Inserted module 'overlay' May 15 23:41:14.202920 systemd-modules-load[253]: Inserted module 'br_netfilter' May 15 23:41:14.223941 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 23:41:14.229257 systemd[1]: Started systemd-journald.service - Journal Service. May 15 23:41:14.230195 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:41:14.235822 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:41:14.247258 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:41:14.259286 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 23:41:14.264186 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 23:41:14.282495 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 23:41:14.302021 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:41:14.309308 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:41:14.325314 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 23:41:14.328422 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:41:14.352126 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 15 23:41:14.380894 dracut-cmdline[291]: dracut-dracut-053 May 15 23:41:14.387131 dracut-cmdline[291]: 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=a39d79b1d2ff9998339b60958cf17b8dfae5bd16f05fb844c0e06a5d7107915a May 15 23:41:14.396794 systemd-resolved[288]: Positive Trust Anchors: May 15 23:41:14.396873 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 23:41:14.396939 systemd-resolved[288]: 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 May 15 23:41:14.536899 kernel: SCSI subsystem initialized May 15 23:41:14.543901 kernel: Loading iSCSI transport class v2.0-870. May 15 23:41:14.556905 kernel: iscsi: registered transport (tcp) May 15 23:41:14.578935 kernel: iscsi: registered transport (qla4xxx) May 15 23:41:14.579010 kernel: QLogic iSCSI HBA Driver May 15 23:41:14.642899 kernel: random: crng init done May 15 23:41:14.643312 systemd-resolved[288]: Defaulting to hostname 'linux'. May 15 23:41:14.646698 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 23:41:14.662399 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 23:41:14.672694 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 15 23:41:14.684228 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 15 23:41:14.719146 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 23:41:14.719221 kernel: device-mapper: uevent: version 1.0.3 May 15 23:41:14.720899 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 15 23:41:14.785909 kernel: raid6: neonx8 gen() 6736 MB/s May 15 23:41:14.802896 kernel: raid6: neonx4 gen() 6581 MB/s May 15 23:41:14.819895 kernel: raid6: neonx2 gen() 5484 MB/s May 15 23:41:14.836894 kernel: raid6: neonx1 gen() 3974 MB/s May 15 23:41:14.853894 kernel: raid6: int64x8 gen() 3829 MB/s May 15 23:41:14.870894 kernel: raid6: int64x4 gen() 3728 MB/s May 15 23:41:14.887895 kernel: raid6: int64x2 gen() 3604 MB/s May 15 23:41:14.905682 kernel: raid6: int64x1 gen() 2762 MB/s May 15 23:41:14.905717 kernel: raid6: using algorithm neonx8 gen() 6736 MB/s May 15 23:41:14.923679 kernel: raid6: .... xor() 4886 MB/s, rmw enabled May 15 23:41:14.923714 kernel: raid6: using neon recovery algorithm May 15 23:41:14.932128 kernel: xor: measuring software checksum speed May 15 23:41:14.932185 kernel: 8regs : 10965 MB/sec May 15 23:41:14.933239 kernel: 32regs : 11949 MB/sec May 15 23:41:14.934421 kernel: arm64_neon : 9580 MB/sec May 15 23:41:14.934452 kernel: xor: using function: 32regs (11949 MB/sec) May 15 23:41:15.017905 kernel: Btrfs loaded, zoned=no, fsverity=no May 15 23:41:15.036989 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 15 23:41:15.047238 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:41:15.090648 systemd-udevd[472]: Using default interface naming scheme 'v255'. May 15 23:41:15.099418 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:41:15.113177 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 15 23:41:15.150838 dracut-pre-trigger[478]: rd.md=0: removing MD RAID activation May 15 23:41:15.207272 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 15 23:41:15.219167 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 23:41:15.339215 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:41:15.352997 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 15 23:41:15.403087 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 15 23:41:15.407795 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 15 23:41:15.422330 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:41:15.424650 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 23:41:15.444149 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 15 23:41:15.489101 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 15 23:41:15.546292 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 15 23:41:15.546356 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) May 15 23:41:15.552797 kernel: ena 0000:00:05.0: ENA device version: 0.10 May 15 23:41:15.553153 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 May 15 23:41:15.558890 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:f2:15:46:17:01 May 15 23:41:15.561080 (udev-worker)[515]: Network interface NamePolicy= disabled on kernel command line. May 15 23:41:15.561360 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 23:41:15.563846 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:41:15.576476 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:41:15.580815 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 23:41:15.581118 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:41:15.583399 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:41:15.618109 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 May 15 23:41:15.618170 kernel: nvme nvme0: pci function 0000:00:04.0 May 15 23:41:15.611699 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:41:15.629319 kernel: nvme nvme0: 2/0/0 default/read/poll queues May 15 23:41:15.643933 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 15 23:41:15.644003 kernel: GPT:9289727 != 16777215 May 15 23:41:15.644028 kernel: GPT:Alternate GPT header not at the end of the disk. May 15 23:41:15.644053 kernel: GPT:9289727 != 16777215 May 15 23:41:15.645877 kernel: GPT: Use GNU Parted to correct GPT errors. May 15 23:41:15.645914 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:41:15.648283 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:41:15.661213 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:41:15.699428 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:41:15.773961 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (528) May 15 23:41:15.796908 kernel: BTRFS: device fsid 462ff9f1-7a02-4839-b355-edf30dab0598 devid 1 transid 39 /dev/nvme0n1p3 scanned by (udev-worker) (520) May 15 23:41:15.835167 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. May 15 23:41:15.863800 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. May 15 23:41:15.906207 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. May 15 23:41:15.908773 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. May 15 23:41:15.924776 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 15 23:41:15.935302 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 15 23:41:15.951519 disk-uuid[661]: Primary Header is updated. May 15 23:41:15.951519 disk-uuid[661]: Secondary Entries is updated. May 15 23:41:15.951519 disk-uuid[661]: Secondary Header is updated. May 15 23:41:15.962022 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:41:15.973909 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:41:16.983915 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:41:16.984696 disk-uuid[662]: The operation has completed successfully. May 15 23:41:17.162224 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 23:41:17.162443 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 15 23:41:17.211172 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 15 23:41:17.222009 sh[921]: Success May 15 23:41:17.247899 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 15 23:41:17.362038 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 15 23:41:17.378167 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 15 23:41:17.388971 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 15 23:41:17.414202 kernel: BTRFS info (device dm-0): first mount of filesystem 462ff9f1-7a02-4839-b355-edf30dab0598 May 15 23:41:17.414267 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 15 23:41:17.414294 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 15 23:41:17.415885 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 15 23:41:17.417110 kernel: BTRFS info (device dm-0): using free space tree May 15 23:41:17.549910 kernel: BTRFS info (device dm-0): enabling ssd optimizations May 15 23:41:17.597067 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 15 23:41:17.601047 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 15 23:41:17.611164 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 15 23:41:17.619161 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 15 23:41:17.652350 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:41:17.652436 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:41:17.653605 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:41:17.660906 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:41:17.678194 systemd[1]: mnt-oem.mount: Deactivated successfully. May 15 23:41:17.681907 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:41:17.693305 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 15 23:41:17.707270 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 15 23:41:17.798807 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 23:41:17.812192 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 23:41:17.867358 systemd-networkd[1113]: lo: Link UP May 15 23:41:17.867382 systemd-networkd[1113]: lo: Gained carrier May 15 23:41:17.871493 systemd-networkd[1113]: Enumeration completed May 15 23:41:17.872221 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:41:17.872228 systemd-networkd[1113]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 23:41:17.873786 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 23:41:17.883252 systemd[1]: Reached target network.target - Network. May 15 23:41:17.885566 systemd-networkd[1113]: eth0: Link UP May 15 23:41:17.885573 systemd-networkd[1113]: eth0: Gained carrier May 15 23:41:17.885591 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:41:17.911943 systemd-networkd[1113]: eth0: DHCPv4 address 172.31.22.89/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 15 23:41:18.112728 ignition[1030]: Ignition 2.20.0 May 15 23:41:18.112757 ignition[1030]: Stage: fetch-offline May 15 23:41:18.113227 ignition[1030]: no configs at "/usr/lib/ignition/base.d" May 15 23:41:18.113252 ignition[1030]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:18.120060 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 15 23:41:18.114640 ignition[1030]: Ignition finished successfully May 15 23:41:18.132190 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 15 23:41:18.159237 ignition[1125]: Ignition 2.20.0 May 15 23:41:18.159268 ignition[1125]: Stage: fetch May 15 23:41:18.160897 ignition[1125]: no configs at "/usr/lib/ignition/base.d" May 15 23:41:18.160955 ignition[1125]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:18.161990 ignition[1125]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:18.193458 ignition[1125]: PUT result: OK May 15 23:41:18.198059 ignition[1125]: parsed url from cmdline: "" May 15 23:41:18.198084 ignition[1125]: no config URL provided May 15 23:41:18.198100 ignition[1125]: reading system config file "/usr/lib/ignition/user.ign" May 15 23:41:18.198164 ignition[1125]: no config at "/usr/lib/ignition/user.ign" May 15 23:41:18.198201 ignition[1125]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:18.201776 ignition[1125]: PUT result: OK May 15 23:41:18.202542 ignition[1125]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 May 15 23:41:18.206846 ignition[1125]: GET result: OK May 15 23:41:18.208377 ignition[1125]: parsing config with SHA512: 517c94cf146092d4d1e2dcc2ddf5a2d7d5ac0ac3ea0c65bcacd12d44e3d10230132f38ab8e21931c187fa0021aa9d96a921eed2d0e5e4cb73c4363a13ec1c850 May 15 23:41:18.215644 unknown[1125]: fetched base config from "system" May 15 23:41:18.215665 unknown[1125]: fetched base config from "system" May 15 23:41:18.216301 ignition[1125]: fetch: fetch complete May 15 23:41:18.215679 unknown[1125]: fetched user config from "aws" May 15 23:41:18.216313 ignition[1125]: fetch: fetch passed May 15 23:41:18.216393 ignition[1125]: Ignition finished successfully May 15 23:41:18.228926 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 15 23:41:18.242130 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 15 23:41:18.267222 ignition[1131]: Ignition 2.20.0 May 15 23:41:18.267251 ignition[1131]: Stage: kargs May 15 23:41:18.268806 ignition[1131]: no configs at "/usr/lib/ignition/base.d" May 15 23:41:18.268831 ignition[1131]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:18.269014 ignition[1131]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:18.274203 ignition[1131]: PUT result: OK May 15 23:41:18.280363 ignition[1131]: kargs: kargs passed May 15 23:41:18.280469 ignition[1131]: Ignition finished successfully May 15 23:41:18.283884 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 15 23:41:18.300302 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 15 23:41:18.324821 ignition[1137]: Ignition 2.20.0 May 15 23:41:18.325349 ignition[1137]: Stage: disks May 15 23:41:18.325974 ignition[1137]: no configs at "/usr/lib/ignition/base.d" May 15 23:41:18.325999 ignition[1137]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:18.326200 ignition[1137]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:18.328688 ignition[1137]: PUT result: OK May 15 23:41:18.337954 ignition[1137]: disks: disks passed May 15 23:41:18.338049 ignition[1137]: Ignition finished successfully May 15 23:41:18.342411 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 15 23:41:18.347688 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 15 23:41:18.351887 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 15 23:41:18.354207 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 23:41:18.356112 systemd[1]: Reached target sysinit.target - System Initialization. May 15 23:41:18.358055 systemd[1]: Reached target basic.target - Basic System. May 15 23:41:18.382440 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 15 23:41:18.437482 systemd-fsck[1145]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 15 23:41:18.445331 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 15 23:41:18.457079 systemd[1]: Mounting sysroot.mount - /sysroot... May 15 23:41:18.552155 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 759e3456-2e58-4307-81e1-19f20d3141c2 r/w with ordered data mode. Quota mode: none. May 15 23:41:18.553131 systemd[1]: Mounted sysroot.mount - /sysroot. May 15 23:41:18.555332 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 15 23:41:18.569173 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 23:41:18.576101 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 15 23:41:18.577553 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 15 23:41:18.577635 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 23:41:18.577685 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 15 23:41:18.599579 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 15 23:41:18.609985 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1164) May 15 23:41:18.610435 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 15 23:41:18.620164 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:41:18.620212 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:41:18.620238 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:41:18.635974 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:41:18.638132 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 23:41:19.046746 initrd-setup-root[1188]: cut: /sysroot/etc/passwd: No such file or directory May 15 23:41:19.058624 initrd-setup-root[1195]: cut: /sysroot/etc/group: No such file or directory May 15 23:41:19.067147 initrd-setup-root[1202]: cut: /sysroot/etc/shadow: No such file or directory May 15 23:41:19.075808 initrd-setup-root[1209]: cut: /sysroot/etc/gshadow: No such file or directory May 15 23:41:19.349995 systemd-networkd[1113]: eth0: Gained IPv6LL May 15 23:41:19.424422 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 15 23:41:19.432083 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 15 23:41:19.446153 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 15 23:41:19.461054 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 15 23:41:19.463356 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:41:19.499944 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 15 23:41:19.505080 ignition[1277]: INFO : Ignition 2.20.0 May 15 23:41:19.505080 ignition[1277]: INFO : Stage: mount May 15 23:41:19.508238 ignition[1277]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:41:19.508238 ignition[1277]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:19.508238 ignition[1277]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:19.514914 ignition[1277]: INFO : PUT result: OK May 15 23:41:19.518401 ignition[1277]: INFO : mount: mount passed May 15 23:41:19.521044 ignition[1277]: INFO : Ignition finished successfully May 15 23:41:19.523957 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 15 23:41:19.532154 systemd[1]: Starting ignition-files.service - Ignition (files)... May 15 23:41:19.563218 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 23:41:19.595906 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1288) May 15 23:41:19.596192 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:41:19.599437 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:41:19.599476 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:41:19.606907 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:41:19.608538 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 23:41:19.643932 ignition[1305]: INFO : Ignition 2.20.0 May 15 23:41:19.643932 ignition[1305]: INFO : Stage: files May 15 23:41:19.643932 ignition[1305]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:41:19.643932 ignition[1305]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:19.643932 ignition[1305]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:19.654809 ignition[1305]: INFO : PUT result: OK May 15 23:41:19.658073 ignition[1305]: DEBUG : files: compiled without relabeling support, skipping May 15 23:41:19.661393 ignition[1305]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 23:41:19.661393 ignition[1305]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 23:41:19.695051 ignition[1305]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 23:41:19.697715 ignition[1305]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 23:41:19.700739 unknown[1305]: wrote ssh authorized keys file for user: core May 15 23:41:19.703017 ignition[1305]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 23:41:19.707418 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" May 15 23:41:19.714791 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 May 15 23:41:20.558551 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 15 23:41:20.971057 ignition[1305]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" May 15 23:41:20.975694 ignition[1305]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 23:41:20.975694 ignition[1305]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 23:41:20.975694 ignition[1305]: INFO : files: files passed May 15 23:41:20.975694 ignition[1305]: INFO : Ignition finished successfully May 15 23:41:20.985492 systemd[1]: Finished ignition-files.service - Ignition (files). May 15 23:41:21.007184 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 15 23:41:21.014113 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 15 23:41:21.022530 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 23:41:21.022747 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 15 23:41:21.049157 initrd-setup-root-after-ignition[1334]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 23:41:21.049157 initrd-setup-root-after-ignition[1334]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 15 23:41:21.057107 initrd-setup-root-after-ignition[1338]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 23:41:21.062438 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 23:41:21.068405 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 15 23:41:21.086252 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 15 23:41:21.133077 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 23:41:21.135506 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 15 23:41:21.142691 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 15 23:41:21.146941 systemd[1]: Reached target initrd.target - Initrd Default Target. May 15 23:41:21.149578 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 15 23:41:21.163243 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 15 23:41:21.188790 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 23:41:21.207809 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 15 23:41:21.229383 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 15 23:41:21.234121 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:41:21.238517 systemd[1]: Stopped target timers.target - Timer Units. May 15 23:41:21.240377 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 23:41:21.240604 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 23:41:21.243674 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 15 23:41:21.252228 systemd[1]: Stopped target basic.target - Basic System. May 15 23:41:21.254097 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 15 23:41:21.256248 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 15 23:41:21.258947 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 15 23:41:21.262748 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 15 23:41:21.266263 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 15 23:41:21.270300 systemd[1]: Stopped target sysinit.target - System Initialization. May 15 23:41:21.279798 systemd[1]: Stopped target local-fs.target - Local File Systems. May 15 23:41:21.285194 systemd[1]: Stopped target swap.target - Swaps. May 15 23:41:21.287589 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 23:41:21.287948 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 15 23:41:21.294639 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 15 23:41:21.297581 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:41:21.303902 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 15 23:41:21.307974 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:41:21.313128 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 23:41:21.313813 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 15 23:41:21.319690 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 23:41:21.320422 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 23:41:21.327071 systemd[1]: ignition-files.service: Deactivated successfully. May 15 23:41:21.327289 systemd[1]: Stopped ignition-files.service - Ignition (files). May 15 23:41:21.341288 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 15 23:41:21.349340 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 15 23:41:21.354354 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 23:41:21.357079 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:41:21.361784 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 23:41:21.363198 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 15 23:41:21.382856 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 23:41:21.387161 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 15 23:41:21.396661 ignition[1358]: INFO : Ignition 2.20.0 May 15 23:41:21.396661 ignition[1358]: INFO : Stage: umount May 15 23:41:21.401536 ignition[1358]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:41:21.401536 ignition[1358]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:41:21.401536 ignition[1358]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:41:21.409627 ignition[1358]: INFO : PUT result: OK May 15 23:41:21.428410 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 15 23:41:21.431514 ignition[1358]: INFO : umount: umount passed May 15 23:41:21.431514 ignition[1358]: INFO : Ignition finished successfully May 15 23:41:21.436721 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 23:41:21.438697 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 15 23:41:21.443081 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 23:41:21.443260 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 15 23:41:21.445681 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 23:41:21.446949 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 15 23:41:21.455127 systemd[1]: ignition-fetch.service: Deactivated successfully. May 15 23:41:21.455226 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 15 23:41:21.457239 systemd[1]: Stopped target network.target - Network. May 15 23:41:21.458964 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 23:41:21.459059 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 15 23:41:21.462062 systemd[1]: Stopped target paths.target - Path Units. May 15 23:41:21.468062 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 23:41:21.472037 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:41:21.474633 systemd[1]: Stopped target slices.target - Slice Units. May 15 23:41:21.476610 systemd[1]: Stopped target sockets.target - Socket Units. May 15 23:41:21.487992 systemd[1]: iscsid.socket: Deactivated successfully. May 15 23:41:21.488079 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 15 23:41:21.490606 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 23:41:21.490684 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 23:41:21.498261 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 23:41:21.498358 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 15 23:41:21.503028 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 15 23:41:21.503650 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 15 23:41:21.510803 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 15 23:41:21.513167 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 15 23:41:21.520277 systemd-networkd[1113]: eth0: DHCPv6 lease lost May 15 23:41:21.525099 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 23:41:21.528051 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 15 23:41:21.532162 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 23:41:21.533253 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 15 23:41:21.547220 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 23:41:21.547643 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 15 23:41:21.563892 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 15 23:41:21.567831 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 23:41:21.567974 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 23:41:21.570761 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 23:41:21.570852 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 15 23:41:21.599087 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 23:41:21.599192 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 15 23:41:21.601288 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 15 23:41:21.601368 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:41:21.611478 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:41:21.631606 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 23:41:21.632941 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 15 23:41:21.643563 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 23:41:21.643830 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:41:21.653406 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 23:41:21.653545 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 15 23:41:21.660358 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 23:41:21.660444 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:41:21.662522 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 23:41:21.662616 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 15 23:41:21.665211 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 23:41:21.665310 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 15 23:41:21.674116 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 23:41:21.674229 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:41:21.681435 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 23:41:21.681532 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 15 23:41:21.698138 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 15 23:41:21.702160 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 15 23:41:21.702276 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:41:21.705017 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 15 23:41:21.705095 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:41:21.707713 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 23:41:21.707789 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:41:21.710351 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 23:41:21.710426 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:41:21.713496 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 23:41:21.713831 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 15 23:41:21.763491 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 23:41:21.765236 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 15 23:41:21.771286 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 15 23:41:21.782267 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 15 23:41:21.797213 systemd[1]: Switching root. May 15 23:41:21.834161 systemd-journald[252]: Journal stopped May 15 23:41:24.271832 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). May 15 23:41:24.271992 kernel: SELinux: policy capability network_peer_controls=1 May 15 23:41:24.272038 kernel: SELinux: policy capability open_perms=1 May 15 23:41:24.276333 kernel: SELinux: policy capability extended_socket_class=1 May 15 23:41:24.276392 kernel: SELinux: policy capability always_check_network=0 May 15 23:41:24.276423 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 23:41:24.276458 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 23:41:24.276488 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 23:41:24.276520 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 23:41:24.276560 kernel: audit: type=1403 audit(1747352482.387:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 23:41:24.276606 systemd[1]: Successfully loaded SELinux policy in 85.208ms. May 15 23:41:24.276655 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.096ms. May 15 23:41:24.276708 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) May 15 23:41:24.276742 systemd[1]: Detected virtualization amazon. May 15 23:41:24.276775 systemd[1]: Detected architecture arm64. May 15 23:41:24.276811 systemd[1]: Detected first boot. May 15 23:41:24.276844 systemd[1]: Initializing machine ID from VM UUID. May 15 23:41:24.276928 zram_generator::config[1401]: No configuration found. May 15 23:41:24.276965 systemd[1]: Populated /etc with preset unit settings. May 15 23:41:24.276999 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 23:41:24.277028 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 15 23:41:24.277059 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 23:41:24.277089 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 15 23:41:24.277122 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 15 23:41:24.277155 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 15 23:41:24.277184 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 15 23:41:24.277214 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 15 23:41:24.277245 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 15 23:41:24.277280 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 15 23:41:24.277311 systemd[1]: Created slice user.slice - User and Session Slice. May 15 23:41:24.277343 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:41:24.277373 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:41:24.277403 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 15 23:41:24.277436 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 15 23:41:24.277466 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 15 23:41:24.277495 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 23:41:24.277528 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 15 23:41:24.277560 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:41:24.277589 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 15 23:41:24.277617 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 15 23:41:24.277648 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 15 23:41:24.277688 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 15 23:41:24.277718 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:41:24.277750 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 23:41:24.277784 systemd[1]: Reached target slices.target - Slice Units. May 15 23:41:24.277822 systemd[1]: Reached target swap.target - Swaps. May 15 23:41:24.277851 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 15 23:41:24.278440 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 15 23:41:24.278477 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 23:41:24.278506 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 23:41:24.278536 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:41:24.278565 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 15 23:41:24.278596 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 15 23:41:24.278628 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 15 23:41:24.278664 systemd[1]: Mounting media.mount - External Media Directory... May 15 23:41:24.278694 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 15 23:41:24.278724 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 15 23:41:24.278755 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 15 23:41:24.278787 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). May 15 23:41:24.278815 systemd[1]: Reached target machines.target - Containers. May 15 23:41:24.278846 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 15 23:41:24.278907 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:41:24.278945 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 23:41:24.278974 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 15 23:41:24.279003 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 23:41:24.279034 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 23:41:24.279063 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 23:41:24.279093 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 15 23:41:24.279130 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 23:41:24.279161 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 23:41:24.279190 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 23:41:24.279223 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 15 23:41:24.279251 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 23:41:24.279280 systemd[1]: Stopped systemd-fsck-usr.service. May 15 23:41:24.279308 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 23:41:24.279337 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 23:41:24.279365 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 23:41:24.279394 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 15 23:41:24.279423 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 23:41:24.279452 systemd[1]: verity-setup.service: Deactivated successfully. May 15 23:41:24.279484 systemd[1]: Stopped verity-setup.service. May 15 23:41:24.279512 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 15 23:41:24.279541 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 15 23:41:24.279569 systemd[1]: Mounted media.mount - External Media Directory. May 15 23:41:24.279598 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 15 23:41:24.279627 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 15 23:41:24.279660 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 15 23:41:24.279689 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:41:24.279717 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 23:41:24.279749 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 15 23:41:24.279781 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 23:41:24.279813 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 23:41:24.279843 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 23:41:24.279914 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 23:41:24.279946 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 23:41:24.279975 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 15 23:41:24.280004 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 23:41:24.280036 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 23:41:24.280072 kernel: fuse: init (API version 7.39) May 15 23:41:24.280101 kernel: ACPI: bus type drm_connector registered May 15 23:41:24.280127 kernel: loop: module loaded May 15 23:41:24.280157 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 15 23:41:24.280187 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 23:41:24.285914 systemd-journald[1486]: Collecting audit messages is disabled. May 15 23:41:24.285988 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 23:41:24.286023 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 15 23:41:24.286079 systemd-journald[1486]: Journal started May 15 23:41:24.286133 systemd-journald[1486]: Runtime Journal (/run/log/journal/ec2a0843d5a7d78167b98d1959c9ea75) is 8.0M, max 75.3M, 67.3M free. May 15 23:41:23.643310 systemd[1]: Queued start job for default target multi-user.target. May 15 23:41:23.698427 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. May 15 23:41:23.699294 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 23:41:24.300929 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 15 23:41:24.315002 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 15 23:41:24.322134 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:41:24.340151 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 15 23:41:24.340250 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 23:41:24.352576 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 15 23:41:24.366905 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 23:41:24.381924 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 15 23:41:24.398435 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 23:41:24.406892 systemd[1]: Started systemd-journald.service - Journal Service. May 15 23:41:24.410297 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 15 23:41:24.413846 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 23:41:24.416293 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 23:41:24.419774 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 23:41:24.420984 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 15 23:41:24.423758 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 23:41:24.424219 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 23:41:24.426819 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 15 23:41:24.432038 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 15 23:41:24.438238 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 15 23:41:24.451812 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 23:41:24.502017 kernel: loop0: detected capacity change from 0 to 53784 May 15 23:41:24.503607 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 15 23:41:24.516142 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 15 23:41:24.525141 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 15 23:41:24.537137 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 15 23:41:24.539490 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 23:41:24.547999 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 15 23:41:24.580006 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 15 23:41:24.611903 kernel: loop1: detected capacity change from 0 to 211168 May 15 23:41:24.612515 systemd-journald[1486]: Time spent on flushing to /var/log/journal/ec2a0843d5a7d78167b98d1959c9ea75 is 82.559ms for 899 entries. May 15 23:41:24.612515 systemd-journald[1486]: System Journal (/var/log/journal/ec2a0843d5a7d78167b98d1959c9ea75) is 8.0M, max 195.6M, 187.6M free. May 15 23:41:24.726973 systemd-journald[1486]: Received client request to flush runtime journal. May 15 23:41:24.728545 kernel: loop2: detected capacity change from 0 to 113536 May 15 23:41:24.618638 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 23:41:24.623448 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 15 23:41:24.634738 systemd-tmpfiles[1512]: ACLs are not supported, ignoring. May 15 23:41:24.634762 systemd-tmpfiles[1512]: ACLs are not supported, ignoring. May 15 23:41:24.647985 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:41:24.669386 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 15 23:41:24.673832 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:41:24.687696 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 15 23:41:24.738998 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 15 23:41:24.765373 udevadm[1548]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 15 23:41:24.796677 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 15 23:41:24.810349 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 23:41:24.855965 kernel: loop3: detected capacity change from 0 to 116808 May 15 23:41:24.871153 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. May 15 23:41:24.871186 systemd-tmpfiles[1554]: ACLs are not supported, ignoring. May 15 23:41:24.882976 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:41:24.976406 kernel: loop4: detected capacity change from 0 to 53784 May 15 23:41:25.001082 kernel: loop5: detected capacity change from 0 to 211168 May 15 23:41:25.029950 kernel: loop6: detected capacity change from 0 to 113536 May 15 23:41:25.045903 kernel: loop7: detected capacity change from 0 to 116808 May 15 23:41:25.058517 (sd-merge)[1558]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. May 15 23:41:25.059482 (sd-merge)[1558]: Merged extensions into '/usr'. May 15 23:41:25.067473 systemd[1]: Reloading requested from client PID 1511 ('systemd-sysext') (unit systemd-sysext.service)... May 15 23:41:25.067635 systemd[1]: Reloading... May 15 23:41:25.230921 zram_generator::config[1581]: No configuration found. May 15 23:41:25.584010 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. May 15 23:41:25.701361 systemd[1]: Reloading finished in 631 ms. May 15 23:41:25.734034 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 15 23:41:25.737160 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 15 23:41:25.752239 systemd[1]: Starting ensure-sysext.service... May 15 23:41:25.766094 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 23:41:25.773260 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:41:25.802885 systemd[1]: Reloading requested from client PID 1636 ('systemctl') (unit ensure-sysext.service)... May 15 23:41:25.802917 systemd[1]: Reloading... May 15 23:41:25.835986 systemd-tmpfiles[1637]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 23:41:25.836640 systemd-tmpfiles[1637]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 15 23:41:25.841616 systemd-tmpfiles[1637]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 23:41:25.842233 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. May 15 23:41:25.842395 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. May 15 23:41:25.856109 systemd-tmpfiles[1637]: Detected autofs mount point /boot during canonicalization of boot. May 15 23:41:25.856133 systemd-tmpfiles[1637]: Skipping /boot May 15 23:41:25.859746 systemd-udevd[1638]: Using default interface naming scheme 'v255'. May 15 23:41:25.895260 systemd-tmpfiles[1637]: Detected autofs mount point /boot during canonicalization of boot. May 15 23:41:25.895289 systemd-tmpfiles[1637]: Skipping /boot May 15 23:41:26.027897 zram_generator::config[1684]: No configuration found. May 15 23:41:26.136969 ldconfig[1507]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 23:41:26.190133 (udev-worker)[1680]: Network interface NamePolicy= disabled on kernel command line. May 15 23:41:26.418692 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. May 15 23:41:26.512909 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (1673) May 15 23:41:26.571607 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 15 23:41:26.572507 systemd[1]: Reloading finished in 768 ms. May 15 23:41:26.608232 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:41:26.611731 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 15 23:41:26.616817 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:41:26.672316 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 23:41:26.678598 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 15 23:41:26.688726 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 15 23:41:26.696528 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 23:41:26.703376 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 23:41:26.712375 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 15 23:41:26.717390 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:41:26.738670 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:41:26.742977 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 23:41:26.750469 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 23:41:26.764527 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 23:41:26.766664 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:41:26.777082 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:41:26.777621 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:41:26.784364 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 15 23:41:26.797235 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:41:26.825693 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 23:41:26.828269 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:41:26.828688 systemd[1]: Reached target time-set.target - System Time Set. May 15 23:41:26.841014 systemd[1]: Finished ensure-sysext.service. May 15 23:41:26.910934 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 15 23:41:26.951756 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 23:41:26.953941 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 23:41:26.956908 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 23:41:26.958682 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 23:41:26.963570 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 23:41:26.972272 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 15 23:41:26.999584 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 15 23:41:27.003172 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 15 23:41:27.011438 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 23:41:27.011831 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 23:41:27.017673 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 23:41:27.018772 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 23:41:27.038502 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 23:41:27.038608 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). May 15 23:41:27.052809 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 15 23:41:27.056208 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 15 23:41:27.094156 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 15 23:41:27.097820 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:41:27.115781 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 15 23:41:27.120001 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 15 23:41:27.133160 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 15 23:41:27.141620 augenrules[1887]: No rules May 15 23:41:27.147432 systemd[1]: audit-rules.service: Deactivated successfully. May 15 23:41:27.147825 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 23:41:27.185562 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 15 23:41:27.196900 lvm[1888]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 23:41:27.231252 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 15 23:41:27.235487 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 23:41:27.248162 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 15 23:41:27.263784 systemd-networkd[1828]: lo: Link UP May 15 23:41:27.264015 systemd-networkd[1828]: lo: Gained carrier May 15 23:41:27.267116 systemd-networkd[1828]: Enumeration completed May 15 23:41:27.270149 systemd-resolved[1829]: Positive Trust Anchors: May 15 23:41:27.270197 systemd-resolved[1829]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 23:41:27.270259 systemd-resolved[1829]: 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 May 15 23:41:27.279294 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 23:41:27.281934 systemd-networkd[1828]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:41:27.281942 systemd-networkd[1828]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 23:41:27.284372 systemd-networkd[1828]: eth0: Link UP May 15 23:41:27.285303 systemd-resolved[1829]: Defaulting to hostname 'linux'. May 15 23:41:27.285890 systemd-networkd[1828]: eth0: Gained carrier May 15 23:41:27.287211 systemd-networkd[1828]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:41:27.290269 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 15 23:41:27.294452 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 23:41:27.296739 systemd[1]: Reached target network.target - Network. May 15 23:41:27.298513 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 23:41:27.300799 systemd[1]: Reached target sysinit.target - System Initialization. May 15 23:41:27.303209 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 15 23:41:27.321741 lvm[1897]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 23:41:27.308545 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 15 23:41:27.311144 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 15 23:41:27.313298 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 15 23:41:27.315587 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 15 23:41:27.317828 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 23:41:27.317901 systemd[1]: Reached target paths.target - Path Units. May 15 23:41:27.321808 systemd[1]: Reached target timers.target - Timer Units. May 15 23:41:27.325843 systemd-networkd[1828]: eth0: DHCPv4 address 172.31.22.89/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 15 23:41:27.327338 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 15 23:41:27.335824 systemd[1]: Starting docker.socket - Docker Socket for the API... May 15 23:41:27.348064 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 15 23:41:27.352153 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 15 23:41:27.355018 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 15 23:41:27.360473 systemd[1]: Reached target sockets.target - Socket Units. May 15 23:41:27.362648 systemd[1]: Reached target basic.target - Basic System. May 15 23:41:27.364717 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 15 23:41:27.364777 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 15 23:41:27.380035 systemd[1]: Starting containerd.service - containerd container runtime... May 15 23:41:27.385180 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 15 23:41:27.395264 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 15 23:41:27.400785 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 15 23:41:27.416299 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 15 23:41:27.419597 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). May 15 23:41:27.424658 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 15 23:41:27.431231 systemd[1]: Started ntpd.service - Network Time Service. May 15 23:41:27.442410 systemd[1]: Starting setup-oem.service - Setup OEM... May 15 23:41:27.449273 jq[1906]: false May 15 23:41:27.452167 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 15 23:41:27.460465 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 15 23:41:27.478217 systemd[1]: Starting systemd-logind.service - User Login Management... May 15 23:41:27.481100 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 23:41:27.481952 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 23:41:27.488842 systemd[1]: Starting update-engine.service - Update Engine... May 15 23:41:27.495102 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 15 23:41:27.504953 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 23:41:27.505318 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 15 23:41:27.606336 systemd[1]: motdgen.service: Deactivated successfully. May 15 23:41:27.608763 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 15 23:41:27.611751 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 23:41:27.612810 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 15 23:41:27.621220 dbus-daemon[1905]: [system] SELinux support is enabled May 15 23:41:27.630164 extend-filesystems[1907]: Found loop4 May 15 23:41:27.630164 extend-filesystems[1907]: Found loop5 May 15 23:41:27.668914 jq[1917]: true May 15 23:41:27.627691 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 15 23:41:27.641475 dbus-daemon[1905]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1828 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") May 15 23:41:27.669408 update_engine[1916]: I20250515 23:41:27.651252 1916 main.cc:92] Flatcar Update Engine starting May 15 23:41:27.669408 update_engine[1916]: I20250515 23:41:27.654039 1916 update_check_scheduler.cc:74] Next update check in 6m52s May 15 23:41:27.669846 extend-filesystems[1907]: Found loop6 May 15 23:41:27.669846 extend-filesystems[1907]: Found loop7 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p1 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p2 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p3 May 15 23:41:27.669846 extend-filesystems[1907]: Found usr May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p4 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p6 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p7 May 15 23:41:27.669846 extend-filesystems[1907]: Found nvme0n1p9 May 15 23:41:27.669846 extend-filesystems[1907]: Checking size of /dev/nvme0n1p9 May 15 23:41:27.638703 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). May 15 23:41:27.642665 dbus-daemon[1905]: [system] Successfully activated service 'org.freedesktop.systemd1' May 15 23:41:27.638799 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 15 23:41:27.712606 jq[1938]: true May 15 23:41:27.642960 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). May 15 23:41:27.643009 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 15 23:41:27.659492 systemd[1]: Started update-engine.service - Update Engine. May 15 23:41:27.674430 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 15 23:41:27.699137 (ntainerd)[1930]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 15 23:41:27.718226 ntpd[1909]: ntpd 4.2.8p17@1.4004-o Thu May 15 21:40:26 UTC 2025 (1): Starting May 15 23:41:27.713442 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: ntpd 4.2.8p17@1.4004-o Thu May 15 21:40:26 UTC 2025 (1): Starting May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: ---------------------------------------------------- May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: ntp-4 is maintained by Network Time Foundation, May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: corporation. Support and training for ntp-4 are May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: available at https://www.nwtime.org/support May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: ---------------------------------------------------- May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: proto: precision = 0.096 usec (-23) May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: basedate set to 2025-05-03 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: gps base set to 2025-05-04 (week 2365) May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listen and drop on 0 v6wildcard [::]:123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listen normally on 2 lo 127.0.0.1:123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listen normally on 3 eth0 172.31.22.89:123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listen normally on 4 lo [::1]:123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: bind(21) AF_INET6 fe80::4f2:15ff:fe46:1701%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: unable to create socket on eth0 (5) for fe80::4f2:15ff:fe46:1701%2#123 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: failed to init interface for address fe80::4f2:15ff:fe46:1701%2 May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: Listening on routing socket on fd #21 for interface updates May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:41:27.759685 ntpd[1909]: 15 May 23:41:27 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:41:27.718271 ntpd[1909]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 15 23:41:27.718292 ntpd[1909]: ---------------------------------------------------- May 15 23:41:27.718310 ntpd[1909]: ntp-4 is maintained by Network Time Foundation, May 15 23:41:27.718328 ntpd[1909]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 15 23:41:27.718346 ntpd[1909]: corporation. Support and training for ntp-4 are May 15 23:41:27.718365 ntpd[1909]: available at https://www.nwtime.org/support May 15 23:41:27.718383 ntpd[1909]: ---------------------------------------------------- May 15 23:41:27.721218 ntpd[1909]: proto: precision = 0.096 usec (-23) May 15 23:41:27.722263 ntpd[1909]: basedate set to 2025-05-03 May 15 23:41:27.722295 ntpd[1909]: gps base set to 2025-05-04 (week 2365) May 15 23:41:27.724944 ntpd[1909]: Listen and drop on 0 v6wildcard [::]:123 May 15 23:41:27.725021 ntpd[1909]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 15 23:41:27.725303 ntpd[1909]: Listen normally on 2 lo 127.0.0.1:123 May 15 23:41:27.725367 ntpd[1909]: Listen normally on 3 eth0 172.31.22.89:123 May 15 23:41:27.725442 ntpd[1909]: Listen normally on 4 lo [::1]:123 May 15 23:41:27.725514 ntpd[1909]: bind(21) AF_INET6 fe80::4f2:15ff:fe46:1701%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:41:27.725552 ntpd[1909]: unable to create socket on eth0 (5) for fe80::4f2:15ff:fe46:1701%2#123 May 15 23:41:27.725584 ntpd[1909]: failed to init interface for address fe80::4f2:15ff:fe46:1701%2 May 15 23:41:27.725658 ntpd[1909]: Listening on routing socket on fd #21 for interface updates May 15 23:41:27.728051 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:41:27.728098 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:41:27.775477 extend-filesystems[1907]: Resized partition /dev/nvme0n1p9 May 15 23:41:27.791294 extend-filesystems[1952]: resize2fs 1.47.1 (20-May-2024) May 15 23:41:27.821911 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks May 15 23:41:27.862085 coreos-metadata[1904]: May 15 23:41:27.861 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 15 23:41:27.865951 coreos-metadata[1904]: May 15 23:41:27.863 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 May 15 23:41:27.871695 coreos-metadata[1904]: May 15 23:41:27.871 INFO Fetch successful May 15 23:41:27.871695 coreos-metadata[1904]: May 15 23:41:27.871 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 May 15 23:41:27.872535 coreos-metadata[1904]: May 15 23:41:27.872 INFO Fetch successful May 15 23:41:27.872535 coreos-metadata[1904]: May 15 23:41:27.872 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetch successful May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetch successful May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetch failed with 404: resource not found May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetch successful May 15 23:41:27.884604 coreos-metadata[1904]: May 15 23:41:27.884 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 May 15 23:41:27.893907 coreos-metadata[1904]: May 15 23:41:27.889 INFO Fetch successful May 15 23:41:27.893907 coreos-metadata[1904]: May 15 23:41:27.889 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 May 15 23:41:27.893907 coreos-metadata[1904]: May 15 23:41:27.889 INFO Fetch successful May 15 23:41:27.893907 coreos-metadata[1904]: May 15 23:41:27.889 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 May 15 23:41:27.896963 coreos-metadata[1904]: May 15 23:41:27.894 INFO Fetch successful May 15 23:41:27.896963 coreos-metadata[1904]: May 15 23:41:27.894 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 May 15 23:41:27.896963 coreos-metadata[1904]: May 15 23:41:27.894 INFO Fetch successful May 15 23:41:27.904759 systemd[1]: Finished setup-oem.service - Setup OEM. May 15 23:41:27.912082 systemd-logind[1915]: Watching system buttons on /dev/input/event0 (Power Button) May 15 23:41:27.912119 systemd-logind[1915]: Watching system buttons on /dev/input/event1 (Sleep Button) May 15 23:41:27.921985 systemd-logind[1915]: New seat seat0. May 15 23:41:27.969510 systemd[1]: Started systemd-logind.service - User Login Management. May 15 23:41:27.979904 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 May 15 23:41:28.001211 extend-filesystems[1952]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required May 15 23:41:28.001211 extend-filesystems[1952]: old_desc_blocks = 1, new_desc_blocks = 1 May 15 23:41:28.001211 extend-filesystems[1952]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. May 15 23:41:28.023265 extend-filesystems[1907]: Resized filesystem in /dev/nvme0n1p9 May 15 23:41:28.006430 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 23:41:28.010202 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 15 23:41:28.038009 bash[1978]: Updated "/home/core/.ssh/authorized_keys" May 15 23:41:28.039718 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 15 23:41:28.058053 systemd[1]: Starting sshkeys.service... May 15 23:41:28.083838 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 15 23:41:28.086645 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 15 23:41:28.126725 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 15 23:41:28.144918 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (1679) May 15 23:41:28.166705 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 15 23:41:28.183500 dbus-daemon[1905]: [system] Successfully activated service 'org.freedesktop.hostname1' May 15 23:41:28.185412 systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 15 23:41:28.191117 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 15 23:41:28.203055 dbus-daemon[1905]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1940 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") May 15 23:41:28.211986 systemd[1]: Starting polkit.service - Authorization Manager... May 15 23:41:28.263707 polkitd[2008]: Started polkitd version 121 May 15 23:41:28.298669 polkitd[2008]: Loading rules from directory /etc/polkit-1/rules.d May 15 23:41:28.305067 polkitd[2008]: Loading rules from directory /usr/share/polkit-1/rules.d May 15 23:41:28.307725 polkitd[2008]: Finished loading, compiling and executing 2 rules May 15 23:41:28.317081 dbus-daemon[1905]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 15 23:41:28.317533 polkitd[2008]: Acquired the name org.freedesktop.PolicyKit1 on the system bus May 15 23:41:28.317658 systemd[1]: Started polkit.service - Authorization Manager. May 15 23:41:28.354084 containerd[1930]: time="2025-05-15T23:41:28.353937285Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 15 23:41:28.370560 systemd-hostnamed[1940]: Hostname set to (transient) May 15 23:41:28.370603 systemd-resolved[1829]: System hostname changed to 'ip-172-31-22-89'. May 15 23:41:28.478393 locksmithd[1942]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 23:41:28.489166 coreos-metadata[1990]: May 15 23:41:28.489 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 15 23:41:28.493154 coreos-metadata[1990]: May 15 23:41:28.493 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 May 15 23:41:28.493879 coreos-metadata[1990]: May 15 23:41:28.493 INFO Fetch successful May 15 23:41:28.493879 coreos-metadata[1990]: May 15 23:41:28.493 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 May 15 23:41:28.494607 coreos-metadata[1990]: May 15 23:41:28.494 INFO Fetch successful May 15 23:41:28.500779 unknown[1990]: wrote ssh authorized keys file for user: core May 15 23:41:28.543630 containerd[1930]: time="2025-05-15T23:41:28.543565126Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551233462Z" 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.90-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551292898Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551328550Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551668918Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551702566Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551827714Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.551856202Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.552201250Z" 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 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.552232750Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.552264730Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 15 23:41:28.554090 containerd[1930]: time="2025-05-15T23:41:28.552288238Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.554577 containerd[1930]: time="2025-05-15T23:41:28.552457030Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.557814 containerd[1930]: time="2025-05-15T23:41:28.552849874Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 15 23:41:28.559386 containerd[1930]: time="2025-05-15T23:41:28.558297814Z" 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 May 15 23:41:28.559386 containerd[1930]: time="2025-05-15T23:41:28.558341890Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 15 23:41:28.559386 containerd[1930]: time="2025-05-15T23:41:28.558562222Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 15 23:41:28.559386 containerd[1930]: time="2025-05-15T23:41:28.558662518Z" level=info msg="metadata content store policy set" policy=shared May 15 23:41:28.569274 containerd[1930]: time="2025-05-15T23:41:28.569202418Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 15 23:41:28.569630 containerd[1930]: time="2025-05-15T23:41:28.569598646Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 15 23:41:28.569774 containerd[1930]: time="2025-05-15T23:41:28.569748142Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.570032302Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.571108942Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.571464958Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.572492506Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.572747170Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.572787586Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 15 23:41:28.573951 containerd[1930]: time="2025-05-15T23:41:28.572841394Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 15 23:41:28.575367 containerd[1930]: time="2025-05-15T23:41:28.575282326Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575463 containerd[1930]: time="2025-05-15T23:41:28.575379286Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575463 containerd[1930]: time="2025-05-15T23:41:28.575428042Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575747 containerd[1930]: time="2025-05-15T23:41:28.575473678Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575747 containerd[1930]: time="2025-05-15T23:41:28.575520418Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575747 containerd[1930]: time="2025-05-15T23:41:28.575566438Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 15 23:41:28.575747 containerd[1930]: time="2025-05-15T23:41:28.575607406Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 15 23:41:28.576111 update-ssh-keys[2084]: Updated "/home/core/.ssh/authorized_keys" May 15 23:41:28.579309 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 15 23:41:28.582159 containerd[1930]: time="2025-05-15T23:41:28.575645986Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 15 23:41:28.582248 containerd[1930]: time="2025-05-15T23:41:28.582201874Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582296 containerd[1930]: time="2025-05-15T23:41:28.582251206Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582379 containerd[1930]: time="2025-05-15T23:41:28.582286510Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582379 containerd[1930]: time="2025-05-15T23:41:28.582332014Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582476 containerd[1930]: time="2025-05-15T23:41:28.582372862Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582476 containerd[1930]: time="2025-05-15T23:41:28.582415954Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582476 containerd[1930]: time="2025-05-15T23:41:28.582455602Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582616 containerd[1930]: time="2025-05-15T23:41:28.582497254Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582616 containerd[1930]: time="2025-05-15T23:41:28.582541498Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582616 containerd[1930]: time="2025-05-15T23:41:28.582592666Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582735 containerd[1930]: time="2025-05-15T23:41:28.582633562Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582735 containerd[1930]: time="2025-05-15T23:41:28.582675730Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 15 23:41:28.582735 containerd[1930]: time="2025-05-15T23:41:28.582716086Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 15 23:41:28.583221 containerd[1930]: time="2025-05-15T23:41:28.582770014Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 15 23:41:28.583221 containerd[1930]: time="2025-05-15T23:41:28.582833542Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593062222Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593141038Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593314138Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593365306Z" 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 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593401030Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 15 23:41:28.593518 containerd[1930]: time="2025-05-15T23:41:28.593443018Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 15 23:41:28.595906 containerd[1930]: time="2025-05-15T23:41:28.593469118Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 15 23:41:28.595906 containerd[1930]: time="2025-05-15T23:41:28.594064522Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 15 23:41:28.595906 containerd[1930]: time="2025-05-15T23:41:28.594126214Z" level=info msg="NRI interface is disabled by configuration." May 15 23:41:28.595906 containerd[1930]: time="2025-05-15T23:41:28.594167026Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 15 23:41:28.596179 containerd[1930]: time="2025-05-15T23:41:28.594788398Z" 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}" May 15 23:41:28.596179 containerd[1930]: time="2025-05-15T23:41:28.594911218Z" level=info msg="Connect containerd service" May 15 23:41:28.596179 containerd[1930]: time="2025-05-15T23:41:28.594989242Z" level=info msg="using legacy CRI server" May 15 23:41:28.596179 containerd[1930]: time="2025-05-15T23:41:28.595017058Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 15 23:41:28.596179 containerd[1930]: time="2025-05-15T23:41:28.595302502Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 15 23:41:28.596966 systemd[1]: Finished sshkeys.service. May 15 23:41:28.611613 containerd[1930]: time="2025-05-15T23:41:28.611312363Z" 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" May 15 23:41:28.612052 containerd[1930]: time="2025-05-15T23:41:28.611983811Z" level=info msg="Start subscribing containerd event" May 15 23:41:28.612118 containerd[1930]: time="2025-05-15T23:41:28.612075503Z" level=info msg="Start recovering state" May 15 23:41:28.612236 containerd[1930]: time="2025-05-15T23:41:28.612196499Z" level=info msg="Start event monitor" May 15 23:41:28.612308 containerd[1930]: time="2025-05-15T23:41:28.612231695Z" level=info msg="Start snapshots syncer" May 15 23:41:28.612308 containerd[1930]: time="2025-05-15T23:41:28.612258755Z" level=info msg="Start cni network conf syncer for default" May 15 23:41:28.612308 containerd[1930]: time="2025-05-15T23:41:28.612281567Z" level=info msg="Start streaming server" May 15 23:41:28.620522 containerd[1930]: time="2025-05-15T23:41:28.617382683Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 23:41:28.620522 containerd[1930]: time="2025-05-15T23:41:28.617632631Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 23:41:28.620522 containerd[1930]: time="2025-05-15T23:41:28.617812043Z" level=info msg="containerd successfully booted in 0.266342s" May 15 23:41:28.617985 systemd[1]: Started containerd.service - containerd container runtime. May 15 23:41:28.719040 ntpd[1909]: bind(24) AF_INET6 fe80::4f2:15ff:fe46:1701%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:41:28.719707 ntpd[1909]: 15 May 23:41:28 ntpd[1909]: bind(24) AF_INET6 fe80::4f2:15ff:fe46:1701%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:41:28.719707 ntpd[1909]: 15 May 23:41:28 ntpd[1909]: unable to create socket on eth0 (6) for fe80::4f2:15ff:fe46:1701%2#123 May 15 23:41:28.719707 ntpd[1909]: 15 May 23:41:28 ntpd[1909]: failed to init interface for address fe80::4f2:15ff:fe46:1701%2 May 15 23:41:28.719102 ntpd[1909]: unable to create socket on eth0 (6) for fe80::4f2:15ff:fe46:1701%2#123 May 15 23:41:28.719130 ntpd[1909]: failed to init interface for address fe80::4f2:15ff:fe46:1701%2 May 15 23:41:28.823394 sshd_keygen[1941]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 23:41:28.862952 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 15 23:41:28.870379 systemd[1]: Starting issuegen.service - Generate /run/issue... May 15 23:41:28.876430 systemd[1]: Started sshd@0-172.31.22.89:22-147.75.109.163:52086.service - OpenSSH per-connection server daemon (147.75.109.163:52086). May 15 23:41:28.897646 systemd[1]: issuegen.service: Deactivated successfully. May 15 23:41:28.899960 systemd[1]: Finished issuegen.service - Generate /run/issue. May 15 23:41:28.913275 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 15 23:41:28.936095 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 15 23:41:28.947389 systemd[1]: Started getty@tty1.service - Getty on tty1. May 15 23:41:28.958507 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 15 23:41:28.961081 systemd[1]: Reached target getty.target - Login Prompts. May 15 23:41:29.106326 sshd[2115]: Accepted publickey for core from 147.75.109.163 port 52086 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:29.109579 sshd-session[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:29.124904 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 15 23:41:29.137314 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 15 23:41:29.143234 systemd-logind[1915]: New session 1 of user core. May 15 23:41:29.168322 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 15 23:41:29.190390 systemd[1]: Starting user@500.service - User Manager for UID 500... May 15 23:41:29.199017 (systemd)[2126]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 23:41:29.206131 systemd-networkd[1828]: eth0: Gained IPv6LL May 15 23:41:29.210952 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 15 23:41:29.216023 systemd[1]: Reached target network-online.target - Network is Online. May 15 23:41:29.227240 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. May 15 23:41:29.235543 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 23:41:29.244371 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 15 23:41:29.319050 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 15 23:41:29.348934 amazon-ssm-agent[2129]: Initializing new seelog logger May 15 23:41:29.348934 amazon-ssm-agent[2129]: New Seelog Logger Creation Complete May 15 23:41:29.349485 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.349485 amazon-ssm-agent[2129]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 processing appconfig overrides May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.351830 amazon-ssm-agent[2129]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 processing appconfig overrides May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.351830 amazon-ssm-agent[2129]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 processing appconfig overrides May 15 23:41:29.351830 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO Proxy environment variables: May 15 23:41:29.355900 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.355900 amazon-ssm-agent[2129]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:41:29.355900 amazon-ssm-agent[2129]: 2025/05/15 23:41:29 processing appconfig overrides May 15 23:41:29.451356 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO https_proxy: May 15 23:41:29.504252 systemd[2126]: Queued start job for default target default.target. May 15 23:41:29.511671 systemd[2126]: Created slice app.slice - User Application Slice. May 15 23:41:29.511742 systemd[2126]: Reached target paths.target - Paths. May 15 23:41:29.511777 systemd[2126]: Reached target timers.target - Timers. May 15 23:41:29.523481 systemd[2126]: Starting dbus.socket - D-Bus User Message Bus Socket... May 15 23:41:29.551780 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO http_proxy: May 15 23:41:29.555341 systemd[2126]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 15 23:41:29.555582 systemd[2126]: Reached target sockets.target - Sockets. May 15 23:41:29.555614 systemd[2126]: Reached target basic.target - Basic System. May 15 23:41:29.555707 systemd[2126]: Reached target default.target - Main User Target. May 15 23:41:29.555774 systemd[2126]: Startup finished in 342ms. May 15 23:41:29.555784 systemd[1]: Started user@500.service - User Manager for UID 500. May 15 23:41:29.567203 systemd[1]: Started session-1.scope - Session 1 of User core. May 15 23:41:29.650269 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO no_proxy: May 15 23:41:29.738370 systemd[1]: Started sshd@1-172.31.22.89:22-147.75.109.163:46018.service - OpenSSH per-connection server daemon (147.75.109.163:46018). May 15 23:41:29.758578 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO Checking if agent identity type OnPrem can be assumed May 15 23:41:29.852007 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO Checking if agent identity type EC2 can be assumed May 15 23:41:29.950397 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO Agent will take identity from EC2 May 15 23:41:30.007402 sshd[2156]: Accepted publickey for core from 147.75.109.163 port 46018 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:30.009377 sshd-session[2156]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:30.021158 systemd-logind[1915]: New session 2 of user core. May 15 23:41:30.028142 systemd[1]: Started session-2.scope - Session 2 of User core. May 15 23:41:30.050073 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:41:30.150728 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:41:30.175635 sshd[2158]: Connection closed by 147.75.109.163 port 46018 May 15 23:41:30.176499 sshd-session[2156]: pam_unix(sshd:session): session closed for user core May 15 23:41:30.184977 systemd-logind[1915]: Session 2 logged out. Waiting for processes to exit. May 15 23:41:30.186687 systemd[1]: sshd@1-172.31.22.89:22-147.75.109.163:46018.service: Deactivated successfully. May 15 23:41:30.191767 systemd[1]: session-2.scope: Deactivated successfully. May 15 23:41:30.215179 systemd-logind[1915]: Removed session 2. May 15 23:41:30.216122 systemd[1]: Started sshd@2-172.31.22.89:22-147.75.109.163:46020.service - OpenSSH per-connection server daemon (147.75.109.163:46020). May 15 23:41:30.249921 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:41:30.348471 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 May 15 23:41:30.385717 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 May 15 23:41:30.386482 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] Starting Core Agent May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [amazon-ssm-agent] registrar detected. Attempting registration May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [Registrar] Starting registrar module May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:29 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:30 INFO [EC2Identity] EC2 registration was successful. May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:30 INFO [CredentialRefresher] credentialRefresher has started May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:30 INFO [CredentialRefresher] Starting credentials refresher loop May 15 23:41:30.386891 amazon-ssm-agent[2129]: 2025-05-15 23:41:30 INFO EC2RoleProvider Successfully connected with instance profile role credentials May 15 23:41:30.419142 sshd[2163]: Accepted publickey for core from 147.75.109.163 port 46020 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:30.423220 sshd-session[2163]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:30.434642 systemd-logind[1915]: New session 3 of user core. May 15 23:41:30.438201 systemd[1]: Started session-3.scope - Session 3 of User core. May 15 23:41:30.448443 amazon-ssm-agent[2129]: 2025-05-15 23:41:30 INFO [CredentialRefresher] Next credential rotation will be in 31.9499756574 minutes May 15 23:41:30.572480 sshd[2165]: Connection closed by 147.75.109.163 port 46020 May 15 23:41:30.572374 sshd-session[2163]: pam_unix(sshd:session): session closed for user core May 15 23:41:30.581452 systemd[1]: sshd@2-172.31.22.89:22-147.75.109.163:46020.service: Deactivated successfully. May 15 23:41:30.588422 systemd[1]: session-3.scope: Deactivated successfully. May 15 23:41:30.590295 systemd-logind[1915]: Session 3 logged out. Waiting for processes to exit. May 15 23:41:30.592853 systemd-logind[1915]: Removed session 3. May 15 23:41:30.993973 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 23:41:30.997382 systemd[1]: Reached target multi-user.target - Multi-User System. May 15 23:41:31.002126 systemd[1]: Startup finished in 1.085s (kernel) + 8.555s (initrd) + 8.697s (userspace) = 18.339s. May 15 23:41:31.010682 (kubelet)[2174]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 15 23:41:31.428234 amazon-ssm-agent[2129]: 2025-05-15 23:41:31 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process May 15 23:41:31.529530 amazon-ssm-agent[2129]: 2025-05-15 23:41:31 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2184) started May 15 23:41:31.630314 amazon-ssm-agent[2129]: 2025-05-15 23:41:31 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds May 15 23:41:31.719005 ntpd[1909]: Listen normally on 7 eth0 [fe80::4f2:15ff:fe46:1701%2]:123 May 15 23:41:31.720595 ntpd[1909]: 15 May 23:41:31 ntpd[1909]: Listen normally on 7 eth0 [fe80::4f2:15ff:fe46:1701%2]:123 May 15 23:41:31.941510 kubelet[2174]: E0515 23:41:31.941406 2174 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 15 23:41:31.946044 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 15 23:41:31.946408 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 15 23:41:31.947176 systemd[1]: kubelet.service: Consumed 1.374s CPU time. May 15 23:41:34.517418 systemd-resolved[1829]: Clock change detected. Flushing caches. May 15 23:41:40.409766 systemd[1]: Started sshd@3-172.31.22.89:22-147.75.109.163:39706.service - OpenSSH per-connection server daemon (147.75.109.163:39706). May 15 23:41:40.597041 sshd[2199]: Accepted publickey for core from 147.75.109.163 port 39706 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:40.599429 sshd-session[2199]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:40.609042 systemd-logind[1915]: New session 4 of user core. May 15 23:41:40.614574 systemd[1]: Started session-4.scope - Session 4 of User core. May 15 23:41:40.739888 sshd[2201]: Connection closed by 147.75.109.163 port 39706 May 15 23:41:40.740567 sshd-session[2199]: pam_unix(sshd:session): session closed for user core May 15 23:41:40.747439 systemd[1]: sshd@3-172.31.22.89:22-147.75.109.163:39706.service: Deactivated successfully. May 15 23:41:40.750607 systemd[1]: session-4.scope: Deactivated successfully. May 15 23:41:40.751885 systemd-logind[1915]: Session 4 logged out. Waiting for processes to exit. May 15 23:41:40.753823 systemd-logind[1915]: Removed session 4. May 15 23:41:40.774486 systemd[1]: Started sshd@4-172.31.22.89:22-147.75.109.163:39720.service - OpenSSH per-connection server daemon (147.75.109.163:39720). May 15 23:41:40.968481 sshd[2206]: Accepted publickey for core from 147.75.109.163 port 39720 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:40.970883 sshd-session[2206]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:40.980519 systemd-logind[1915]: New session 5 of user core. May 15 23:41:40.983553 systemd[1]: Started session-5.scope - Session 5 of User core. May 15 23:41:41.101565 sshd[2208]: Connection closed by 147.75.109.163 port 39720 May 15 23:41:41.102381 sshd-session[2206]: pam_unix(sshd:session): session closed for user core May 15 23:41:41.109514 systemd[1]: sshd@4-172.31.22.89:22-147.75.109.163:39720.service: Deactivated successfully. May 15 23:41:41.113028 systemd[1]: session-5.scope: Deactivated successfully. May 15 23:41:41.114639 systemd-logind[1915]: Session 5 logged out. Waiting for processes to exit. May 15 23:41:41.116544 systemd-logind[1915]: Removed session 5. May 15 23:41:41.141982 systemd[1]: Started sshd@5-172.31.22.89:22-147.75.109.163:39722.service - OpenSSH per-connection server daemon (147.75.109.163:39722). May 15 23:41:41.320392 sshd[2213]: Accepted publickey for core from 147.75.109.163 port 39722 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:41.322768 sshd-session[2213]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:41.329765 systemd-logind[1915]: New session 6 of user core. May 15 23:41:41.342533 systemd[1]: Started session-6.scope - Session 6 of User core. May 15 23:41:41.468713 sshd[2215]: Connection closed by 147.75.109.163 port 39722 May 15 23:41:41.470089 sshd-session[2213]: pam_unix(sshd:session): session closed for user core May 15 23:41:41.475539 systemd[1]: sshd@5-172.31.22.89:22-147.75.109.163:39722.service: Deactivated successfully. May 15 23:41:41.480379 systemd[1]: session-6.scope: Deactivated successfully. May 15 23:41:41.484625 systemd-logind[1915]: Session 6 logged out. Waiting for processes to exit. May 15 23:41:41.486987 systemd-logind[1915]: Removed session 6. May 15 23:41:41.505794 systemd[1]: Started sshd@6-172.31.22.89:22-147.75.109.163:39734.service - OpenSSH per-connection server daemon (147.75.109.163:39734). May 15 23:41:41.694848 sshd[2220]: Accepted publickey for core from 147.75.109.163 port 39734 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:41:41.697340 sshd-session[2220]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:41:41.704595 systemd-logind[1915]: New session 7 of user core. May 15 23:41:41.714534 systemd[1]: Started session-7.scope - Session 7 of User core. May 15 23:41:41.830191 sudo[2223]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 15 23:41:41.830931 sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 23:41:41.833470 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. May 15 23:41:41.847051 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 23:41:42.222624 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 23:41:42.226180 (kubelet)[2245]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 15 23:41:42.314487 kubelet[2245]: E0515 23:41:42.314210 2245 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 15 23:41:42.322183 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 15 23:41:42.323464 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 15 23:41:42.870447 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 15 23:41:42.880802 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 23:41:42.939157 systemd[1]: Reloading requested from client PID 2276 ('systemctl') (unit session-7.scope)... May 15 23:41:42.939189 systemd[1]: Reloading... May 15 23:41:43.182420 zram_generator::config[2322]: No configuration found. May 15 23:41:43.412737 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. May 15 23:41:43.583463 systemd[1]: Reloading finished in 643 ms. May 15 23:41:43.680336 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM May 15 23:41:43.680518 systemd[1]: kubelet.service: Failed with result 'signal'. May 15 23:41:43.681229 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 15 23:41:43.688003 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 15 23:41:43.998084 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 15 23:41:44.012772 (kubelet)[2379]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 15 23:41:44.081930 kubelet[2379]: 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. May 15 23:41:44.081930 kubelet[2379]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. May 15 23:41:44.081930 kubelet[2379]: 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. May 15 23:41:44.082517 kubelet[2379]: I0515 23:41:44.082018 2379 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 15 23:41:44.887024 kubelet[2379]: I0515 23:41:44.886956 2379 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" May 15 23:41:44.887024 kubelet[2379]: I0515 23:41:44.887006 2379 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 15 23:41:44.887620 kubelet[2379]: I0515 23:41:44.887568 2379 server.go:956] "Client rotation is on, will bootstrap in background" May 15 23:41:44.932311 kubelet[2379]: I0515 23:41:44.930883 2379 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 15 23:41:44.948145 kubelet[2379]: E0515 23:41:44.948064 2379 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 15 23:41:44.948145 kubelet[2379]: I0515 23:41:44.948129 2379 server.go:1423] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 15 23:41:44.954487 kubelet[2379]: I0515 23:41:44.954426 2379 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 15 23:41:44.957131 kubelet[2379]: I0515 23:41:44.957050 2379 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 15 23:41:44.957442 kubelet[2379]: I0515 23:41:44.957122 2379 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.22.89","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 15 23:41:44.957620 kubelet[2379]: I0515 23:41:44.957461 2379 topology_manager.go:138] "Creating topology manager with none policy" May 15 23:41:44.957620 kubelet[2379]: I0515 23:41:44.957485 2379 container_manager_linux.go:303] "Creating device plugin manager" May 15 23:41:44.957733 kubelet[2379]: I0515 23:41:44.957720 2379 state_mem.go:36] "Initialized new in-memory state store" May 15 23:41:44.963569 kubelet[2379]: I0515 23:41:44.963507 2379 kubelet.go:480] "Attempting to sync node with API server" May 15 23:41:44.963569 kubelet[2379]: I0515 23:41:44.963556 2379 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" May 15 23:41:44.963744 kubelet[2379]: I0515 23:41:44.963602 2379 kubelet.go:386] "Adding apiserver pod source" May 15 23:41:44.966797 kubelet[2379]: I0515 23:41:44.966018 2379 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 15 23:41:44.966797 kubelet[2379]: E0515 23:41:44.966035 2379 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:44.966797 kubelet[2379]: E0515 23:41:44.966746 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:44.968692 kubelet[2379]: I0515 23:41:44.968658 2379 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" May 15 23:41:44.970161 kubelet[2379]: I0515 23:41:44.970132 2379 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" May 15 23:41:44.970404 kubelet[2379]: W0515 23:41:44.970378 2379 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 15 23:41:44.974605 kubelet[2379]: I0515 23:41:44.974569 2379 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 15 23:41:44.974822 kubelet[2379]: I0515 23:41:44.974802 2379 server.go:1289] "Started kubelet" May 15 23:41:44.978524 kubelet[2379]: I0515 23:41:44.978415 2379 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 May 15 23:41:44.980317 kubelet[2379]: I0515 23:41:44.979757 2379 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 15 23:41:44.980317 kubelet[2379]: I0515 23:41:44.980119 2379 server.go:317] "Adding debug handlers to kubelet server" May 15 23:41:44.980509 kubelet[2379]: I0515 23:41:44.980484 2379 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 15 23:41:44.984326 kubelet[2379]: I0515 23:41:44.984148 2379 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 15 23:41:44.995585 kubelet[2379]: I0515 23:41:44.995532 2379 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 15 23:41:45.000472 kubelet[2379]: E0515 23:41:44.999571 2379 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.22.89\" not found" May 15 23:41:45.000472 kubelet[2379]: I0515 23:41:44.999630 2379 volume_manager.go:297] "Starting Kubelet Volume Manager" May 15 23:41:45.000472 kubelet[2379]: I0515 23:41:44.999972 2379 desired_state_of_world_populator.go:150] "Desired state populator starts to run" May 15 23:41:45.000472 kubelet[2379]: I0515 23:41:45.000056 2379 reconciler.go:26] "Reconciler: start to sync state" May 15 23:41:45.002737 kubelet[2379]: E0515 23:41:45.002684 2379 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 15 23:41:45.004297 kubelet[2379]: I0515 23:41:45.002955 2379 factory.go:223] Registration of the systemd container factory successfully May 15 23:41:45.004297 kubelet[2379]: I0515 23:41:45.003125 2379 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory May 15 23:41:45.006304 kubelet[2379]: I0515 23:41:45.006232 2379 factory.go:223] Registration of the containerd container factory successfully May 15 23:41:45.018432 kubelet[2379]: E0515 23:41:45.016853 2379 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.22.89\" not found" node="172.31.22.89" May 15 23:41:45.052775 kubelet[2379]: I0515 23:41:45.052605 2379 cpu_manager.go:221] "Starting CPU manager" policy="none" May 15 23:41:45.052997 kubelet[2379]: I0515 23:41:45.052843 2379 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 15 23:41:45.052997 kubelet[2379]: I0515 23:41:45.052889 2379 state_mem.go:36] "Initialized new in-memory state store" May 15 23:41:45.059461 kubelet[2379]: I0515 23:41:45.059238 2379 policy_none.go:49] "None policy: Start" May 15 23:41:45.059461 kubelet[2379]: I0515 23:41:45.059347 2379 memory_manager.go:186] "Starting memorymanager" policy="None" May 15 23:41:45.059461 kubelet[2379]: I0515 23:41:45.059391 2379 state_mem.go:35] "Initializing new in-memory state store" May 15 23:41:45.075874 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 15 23:41:45.091920 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 15 23:41:45.100317 kubelet[2379]: E0515 23:41:45.100263 2379 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"172.31.22.89\" not found" May 15 23:41:45.110021 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 15 23:41:45.115393 kubelet[2379]: E0515 23:41:45.113445 2379 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" May 15 23:41:45.115393 kubelet[2379]: I0515 23:41:45.113726 2379 eviction_manager.go:189] "Eviction manager: starting control loop" May 15 23:41:45.115393 kubelet[2379]: I0515 23:41:45.113746 2379 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 15 23:41:45.117771 kubelet[2379]: I0515 23:41:45.117735 2379 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 15 23:41:45.119170 kubelet[2379]: E0515 23:41:45.119118 2379 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 15 23:41:45.119562 kubelet[2379]: E0515 23:41:45.119524 2379 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.22.89\" not found" May 15 23:41:45.125587 kubelet[2379]: I0515 23:41:45.125498 2379 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" May 15 23:41:45.129155 kubelet[2379]: I0515 23:41:45.129094 2379 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" May 15 23:41:45.129155 kubelet[2379]: I0515 23:41:45.129140 2379 status_manager.go:230] "Starting to sync pod status with apiserver" May 15 23:41:45.129392 kubelet[2379]: I0515 23:41:45.129175 2379 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 15 23:41:45.129392 kubelet[2379]: I0515 23:41:45.129190 2379 kubelet.go:2436] "Starting kubelet main sync loop" May 15 23:41:45.129392 kubelet[2379]: E0515 23:41:45.129314 2379 kubelet.go:2460] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" May 15 23:41:45.216195 kubelet[2379]: I0515 23:41:45.216045 2379 kubelet_node_status.go:75] "Attempting to register node" node="172.31.22.89" May 15 23:41:45.225428 kubelet[2379]: I0515 23:41:45.225380 2379 kubelet_node_status.go:78] "Successfully registered node" node="172.31.22.89" May 15 23:41:45.225428 kubelet[2379]: E0515 23:41:45.225433 2379 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"172.31.22.89\": node \"172.31.22.89\" not found" May 15 23:41:45.352315 kubelet[2379]: I0515 23:41:45.352239 2379 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 15 23:41:45.352844 containerd[1930]: time="2025-05-15T23:41:45.352773221Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 15 23:41:45.353410 kubelet[2379]: I0515 23:41:45.353085 2379 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 15 23:41:45.551284 sudo[2223]: pam_unix(sudo:session): session closed for user root May 15 23:41:45.575126 sshd[2222]: Connection closed by 147.75.109.163 port 39734 May 15 23:41:45.574422 sshd-session[2220]: pam_unix(sshd:session): session closed for user core May 15 23:41:45.579644 systemd-logind[1915]: Session 7 logged out. Waiting for processes to exit. May 15 23:41:45.580737 systemd[1]: sshd@6-172.31.22.89:22-147.75.109.163:39734.service: Deactivated successfully. May 15 23:41:45.584118 systemd[1]: session-7.scope: Deactivated successfully. May 15 23:41:45.588093 systemd-logind[1915]: Removed session 7. May 15 23:41:45.891238 kubelet[2379]: I0515 23:41:45.891041 2379 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 15 23:41:45.891810 kubelet[2379]: I0515 23:41:45.891636 2379 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" May 15 23:41:45.891810 kubelet[2379]: I0515 23:41:45.891664 2379 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" May 15 23:41:45.891810 kubelet[2379]: I0515 23:41:45.891716 2379 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" May 15 23:41:45.967736 kubelet[2379]: E0515 23:41:45.967649 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:45.967736 kubelet[2379]: I0515 23:41:45.967735 2379 apiserver.go:52] "Watching apiserver" May 15 23:41:45.992497 systemd[1]: Created slice kubepods-besteffort-pod2901a52a_91ae_45a7_a842_7e7352484b76.slice - libcontainer container kubepods-besteffort-pod2901a52a_91ae_45a7_a842_7e7352484b76.slice. May 15 23:41:46.001056 kubelet[2379]: I0515 23:41:46.001012 2379 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" May 15 23:41:46.005481 kubelet[2379]: I0515 23:41:46.005423 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2901a52a-91ae-45a7-a842-7e7352484b76-kube-proxy\") pod \"kube-proxy-hrskk\" (UID: \"2901a52a-91ae-45a7-a842-7e7352484b76\") " pod="kube-system/kube-proxy-hrskk" May 15 23:41:46.005625 kubelet[2379]: I0515 23:41:46.005497 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2901a52a-91ae-45a7-a842-7e7352484b76-xtables-lock\") pod \"kube-proxy-hrskk\" (UID: \"2901a52a-91ae-45a7-a842-7e7352484b76\") " pod="kube-system/kube-proxy-hrskk" May 15 23:41:46.005625 kubelet[2379]: I0515 23:41:46.005538 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s7lms\" (UniqueName: \"kubernetes.io/projected/2901a52a-91ae-45a7-a842-7e7352484b76-kube-api-access-s7lms\") pod \"kube-proxy-hrskk\" (UID: \"2901a52a-91ae-45a7-a842-7e7352484b76\") " pod="kube-system/kube-proxy-hrskk" May 15 23:41:46.005625 kubelet[2379]: I0515 23:41:46.005578 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/0691d00d-7f38-40aa-b7bd-125279713757-run\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.005625 kubelet[2379]: I0515 23:41:46.005615 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/0691d00d-7f38-40aa-b7bd-125279713757-cni\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.005849 kubelet[2379]: I0515 23:41:46.005652 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0691d00d-7f38-40aa-b7bd-125279713757-xtables-lock\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.005849 kubelet[2379]: I0515 23:41:46.005699 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6mtcn\" (UniqueName: \"kubernetes.io/projected/0691d00d-7f38-40aa-b7bd-125279713757-kube-api-access-6mtcn\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.005849 kubelet[2379]: I0515 23:41:46.005736 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2901a52a-91ae-45a7-a842-7e7352484b76-lib-modules\") pod \"kube-proxy-hrskk\" (UID: \"2901a52a-91ae-45a7-a842-7e7352484b76\") " pod="kube-system/kube-proxy-hrskk" May 15 23:41:46.005849 kubelet[2379]: I0515 23:41:46.005775 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/0691d00d-7f38-40aa-b7bd-125279713757-cni-plugin\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.005849 kubelet[2379]: I0515 23:41:46.005814 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/0691d00d-7f38-40aa-b7bd-125279713757-flannel-cfg\") pod \"kube-flannel-ds-ctlvh\" (UID: \"0691d00d-7f38-40aa-b7bd-125279713757\") " pod="kube-flannel/kube-flannel-ds-ctlvh" May 15 23:41:46.015096 systemd[1]: Created slice kubepods-burstable-pod0691d00d_7f38_40aa_b7bd_125279713757.slice - libcontainer container kubepods-burstable-pod0691d00d_7f38_40aa_b7bd_125279713757.slice. May 15 23:41:46.311069 containerd[1930]: time="2025-05-15T23:41:46.310933422Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hrskk,Uid:2901a52a-91ae-45a7-a842-7e7352484b76,Namespace:kube-system,Attempt:0,}" May 15 23:41:46.330015 containerd[1930]: time="2025-05-15T23:41:46.329952882Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ctlvh,Uid:0691d00d-7f38-40aa-b7bd-125279713757,Namespace:kube-flannel,Attempt:0,}" May 15 23:41:46.855027 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4124727293.mount: Deactivated successfully. May 15 23:41:46.866757 containerd[1930]: time="2025-05-15T23:41:46.866678505Z" 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\"}" May 15 23:41:46.870330 containerd[1930]: time="2025-05-15T23:41:46.870251925Z" 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\"}" May 15 23:41:46.871818 containerd[1930]: time="2025-05-15T23:41:46.871754709Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" May 15 23:41:46.873805 containerd[1930]: time="2025-05-15T23:41:46.873315549Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 15 23:41:46.873805 containerd[1930]: time="2025-05-15T23:41:46.873733233Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 15 23:41:46.879310 containerd[1930]: time="2025-05-15T23:41:46.877937937Z" 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\"}" May 15 23:41:46.886163 containerd[1930]: time="2025-05-15T23:41:46.886099257Z" 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 574.693923ms" May 15 23:41:46.889777 containerd[1930]: time="2025-05-15T23:41:46.889698597Z" 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 559.636707ms" May 15 23:41:47.030692 kubelet[2379]: E0515 23:41:47.030655 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:47.107820 containerd[1930]: time="2025-05-15T23:41:47.107097354Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:41:47.108957 containerd[1930]: time="2025-05-15T23:41:47.108834870Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:41:47.109100 containerd[1930]: time="2025-05-15T23:41:47.109010634Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:47.111286 containerd[1930]: time="2025-05-15T23:41:47.109653378Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:47.113632 containerd[1930]: time="2025-05-15T23:41:47.112640250Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:41:47.113632 containerd[1930]: time="2025-05-15T23:41:47.112743918Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:41:47.113632 containerd[1930]: time="2025-05-15T23:41:47.112782714Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:47.113632 containerd[1930]: time="2025-05-15T23:41:47.113087322Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:47.232995 systemd[1]: Started cri-containerd-4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe.scope - libcontainer container 4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe. May 15 23:41:47.238128 systemd[1]: Started cri-containerd-6f25dad15ad048f5bdb11e51f3b7d3ae8e249d3a0eb8984fd265568046500650.scope - libcontainer container 6f25dad15ad048f5bdb11e51f3b7d3ae8e249d3a0eb8984fd265568046500650. May 15 23:41:47.313797 containerd[1930]: time="2025-05-15T23:41:47.313201231Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hrskk,Uid:2901a52a-91ae-45a7-a842-7e7352484b76,Namespace:kube-system,Attempt:0,} returns sandbox id \"6f25dad15ad048f5bdb11e51f3b7d3ae8e249d3a0eb8984fd265568046500650\"" May 15 23:41:47.319702 containerd[1930]: time="2025-05-15T23:41:47.319640923Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.1\"" May 15 23:41:47.326412 containerd[1930]: time="2025-05-15T23:41:47.326355259Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ctlvh,Uid:0691d00d-7f38-40aa-b7bd-125279713757,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\"" May 15 23:41:48.032415 kubelet[2379]: E0515 23:41:48.032212 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:48.548490 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount370461058.mount: Deactivated successfully. May 15 23:41:49.033098 kubelet[2379]: E0515 23:41:49.032939 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:49.148592 containerd[1930]: time="2025-05-15T23:41:49.148508252Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:49.150665 containerd[1930]: time="2025-05-15T23:41:49.150566792Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.1: active requests=0, bytes read=28196004" May 15 23:41:49.152110 containerd[1930]: time="2025-05-15T23:41:49.152028032Z" level=info msg="ImageCreate event name:\"sha256:3e58848989f556e36aa29d7852ab1712163960651e074d11cae9d31fb27192db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:49.160079 containerd[1930]: time="2025-05-15T23:41:49.159992696Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:7ddf379897139ae8ade8b33cb9373b70c632a4d5491da6e234f5d830e0a50807\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:49.161546 containerd[1930]: time="2025-05-15T23:41:49.161479916Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.1\" with image id \"sha256:3e58848989f556e36aa29d7852ab1712163960651e074d11cae9d31fb27192db\", repo tag \"registry.k8s.io/kube-proxy:v1.33.1\", repo digest \"registry.k8s.io/kube-proxy@sha256:7ddf379897139ae8ade8b33cb9373b70c632a4d5491da6e234f5d830e0a50807\", size \"28195023\" in 1.841461485s" May 15 23:41:49.161669 containerd[1930]: time="2025-05-15T23:41:49.161543408Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.1\" returns image reference \"sha256:3e58848989f556e36aa29d7852ab1712163960651e074d11cae9d31fb27192db\"" May 15 23:41:49.164199 containerd[1930]: time="2025-05-15T23:41:49.163746296Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" May 15 23:41:49.168544 containerd[1930]: time="2025-05-15T23:41:49.168473744Z" level=info msg="CreateContainer within sandbox \"6f25dad15ad048f5bdb11e51f3b7d3ae8e249d3a0eb8984fd265568046500650\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 15 23:41:49.191299 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount404362986.mount: Deactivated successfully. May 15 23:41:49.199339 containerd[1930]: time="2025-05-15T23:41:49.199058108Z" level=info msg="CreateContainer within sandbox \"6f25dad15ad048f5bdb11e51f3b7d3ae8e249d3a0eb8984fd265568046500650\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"54ee946f667283f4cde510388d63f9f7b96bb3b034157588ae549eeff8b80e7a\"" May 15 23:41:49.200388 containerd[1930]: time="2025-05-15T23:41:49.200322992Z" level=info msg="StartContainer for \"54ee946f667283f4cde510388d63f9f7b96bb3b034157588ae549eeff8b80e7a\"" May 15 23:41:49.256584 systemd[1]: Started cri-containerd-54ee946f667283f4cde510388d63f9f7b96bb3b034157588ae549eeff8b80e7a.scope - libcontainer container 54ee946f667283f4cde510388d63f9f7b96bb3b034157588ae549eeff8b80e7a. May 15 23:41:49.313127 containerd[1930]: time="2025-05-15T23:41:49.312960285Z" level=info msg="StartContainer for \"54ee946f667283f4cde510388d63f9f7b96bb3b034157588ae549eeff8b80e7a\" returns successfully" May 15 23:41:50.033335 kubelet[2379]: E0515 23:41:50.033252 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:50.178460 kubelet[2379]: I0515 23:41:50.178370 2379 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-hrskk" podStartSLOduration=3.332723696 podStartE2EDuration="5.178351257s" podCreationTimestamp="2025-05-15 23:41:45 +0000 UTC" firstStartedPulling="2025-05-15 23:41:47.317828371 +0000 UTC m=+3.298039181" lastFinishedPulling="2025-05-15 23:41:49.163455932 +0000 UTC m=+5.143666742" observedRunningTime="2025-05-15 23:41:50.178034313 +0000 UTC m=+6.158245135" watchObservedRunningTime="2025-05-15 23:41:50.178351257 +0000 UTC m=+6.158562091" May 15 23:41:50.508484 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3778997675.mount: Deactivated successfully. May 15 23:41:50.560068 containerd[1930]: time="2025-05-15T23:41:50.559986599Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:50.562279 containerd[1930]: time="2025-05-15T23:41:50.562164083Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" May 15 23:41:50.563185 containerd[1930]: time="2025-05-15T23:41:50.562706207Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:50.569881 containerd[1930]: time="2025-05-15T23:41:50.568932587Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:50.574045 containerd[1930]: time="2025-05-15T23:41:50.573981455Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 1.410167539s" May 15 23:41:50.574045 containerd[1930]: time="2025-05-15T23:41:50.574044371Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" May 15 23:41:50.579176 containerd[1930]: time="2025-05-15T23:41:50.579118703Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 15 23:41:50.599856 containerd[1930]: time="2025-05-15T23:41:50.599782127Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4\"" May 15 23:41:50.601432 containerd[1930]: time="2025-05-15T23:41:50.601368179Z" level=info msg="StartContainer for \"419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4\"" May 15 23:41:50.653566 systemd[1]: run-containerd-runc-k8s.io-419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4-runc.oJ4KeC.mount: Deactivated successfully. May 15 23:41:50.664595 systemd[1]: Started cri-containerd-419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4.scope - libcontainer container 419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4. May 15 23:41:50.713507 containerd[1930]: time="2025-05-15T23:41:50.713324808Z" level=info msg="StartContainer for \"419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4\" returns successfully" May 15 23:41:50.716501 systemd[1]: cri-containerd-419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4.scope: Deactivated successfully. May 15 23:41:50.753186 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4-rootfs.mount: Deactivated successfully. May 15 23:41:50.860045 containerd[1930]: time="2025-05-15T23:41:50.859943053Z" level=info msg="shim disconnected" id=419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4 namespace=k8s.io May 15 23:41:50.860365 containerd[1930]: time="2025-05-15T23:41:50.860041309Z" level=warning msg="cleaning up after shim disconnected" id=419abfa20bf1a764afb6deca507d5e8c14c782aee339e2b0ada1cc5e04bb86c4 namespace=k8s.io May 15 23:41:50.860365 containerd[1930]: time="2025-05-15T23:41:50.860084905Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 15 23:41:51.034038 kubelet[2379]: E0515 23:41:51.033965 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:51.169455 containerd[1930]: time="2025-05-15T23:41:51.169309186Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" May 15 23:41:52.035171 kubelet[2379]: E0515 23:41:52.035029 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:53.035611 kubelet[2379]: E0515 23:41:53.035557 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:53.473743 containerd[1930]: time="2025-05-15T23:41:53.473663282Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:53.475957 containerd[1930]: time="2025-05-15T23:41:53.475876634Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" May 15 23:41:53.476912 containerd[1930]: time="2025-05-15T23:41:53.476828654Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:53.484769 containerd[1930]: time="2025-05-15T23:41:53.484668554Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:41:53.488696 containerd[1930]: time="2025-05-15T23:41:53.487785986Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 2.318390316s" May 15 23:41:53.488696 containerd[1930]: time="2025-05-15T23:41:53.487842818Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" May 15 23:41:53.494168 containerd[1930]: time="2025-05-15T23:41:53.493966838Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 15 23:41:53.515686 containerd[1930]: time="2025-05-15T23:41:53.515538038Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714\"" May 15 23:41:53.516975 containerd[1930]: time="2025-05-15T23:41:53.516653198Z" level=info msg="StartContainer for \"d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714\"" May 15 23:41:53.567594 systemd[1]: Started cri-containerd-d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714.scope - libcontainer container d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714. May 15 23:41:53.609516 systemd[1]: cri-containerd-d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714.scope: Deactivated successfully. May 15 23:41:53.612001 containerd[1930]: time="2025-05-15T23:41:53.610989602Z" level=info msg="StartContainer for \"d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714\" returns successfully" May 15 23:41:53.643023 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714-rootfs.mount: Deactivated successfully. May 15 23:41:53.668873 kubelet[2379]: I0515 23:41:53.668831 2379 kubelet_node_status.go:501] "Fast updating node status as it just became ready" May 15 23:41:53.770403 containerd[1930]: time="2025-05-15T23:41:53.770091627Z" level=info msg="shim disconnected" id=d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714 namespace=k8s.io May 15 23:41:53.770403 containerd[1930]: time="2025-05-15T23:41:53.770165763Z" level=warning msg="cleaning up after shim disconnected" id=d20eb809d50c513f2f96e49f2ad3493680c17ace8b9e1e51481761e4ede2f714 namespace=k8s.io May 15 23:41:53.770403 containerd[1930]: time="2025-05-15T23:41:53.770187015Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 15 23:41:54.036419 kubelet[2379]: E0515 23:41:54.036211 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:54.182832 containerd[1930]: time="2025-05-15T23:41:54.182773405Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 15 23:41:54.205014 containerd[1930]: time="2025-05-15T23:41:54.204895273Z" level=info msg="CreateContainer within sandbox \"4572ca23a437d5a128666ed54290d8e6559b5e7a018dd1b07736fc4cb8e814fe\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e1019c94133fc566006be353258ee74eede759ee29cbd1ab0f307f68dfc1a1ec\"" May 15 23:41:54.206310 containerd[1930]: time="2025-05-15T23:41:54.206182945Z" level=info msg="StartContainer for \"e1019c94133fc566006be353258ee74eede759ee29cbd1ab0f307f68dfc1a1ec\"" May 15 23:41:54.245569 systemd[1]: Started cri-containerd-e1019c94133fc566006be353258ee74eede759ee29cbd1ab0f307f68dfc1a1ec.scope - libcontainer container e1019c94133fc566006be353258ee74eede759ee29cbd1ab0f307f68dfc1a1ec. May 15 23:41:54.290713 containerd[1930]: time="2025-05-15T23:41:54.290426606Z" level=info msg="StartContainer for \"e1019c94133fc566006be353258ee74eede759ee29cbd1ab0f307f68dfc1a1ec\" returns successfully" May 15 23:41:55.036754 kubelet[2379]: E0515 23:41:55.036680 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:55.370823 (udev-worker)[2892]: Network interface NamePolicy= disabled on kernel command line. May 15 23:41:55.392155 systemd-networkd[1828]: flannel.1: Link UP May 15 23:41:55.392172 systemd-networkd[1828]: flannel.1: Gained carrier May 15 23:41:56.037380 kubelet[2379]: E0515 23:41:56.037331 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:56.459589 systemd-networkd[1828]: flannel.1: Gained IPv6LL May 15 23:41:57.037904 kubelet[2379]: E0515 23:41:57.037837 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:57.805484 kubelet[2379]: I0515 23:41:57.805396 2379 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ctlvh" podStartSLOduration=6.643594356 podStartE2EDuration="12.805371727s" podCreationTimestamp="2025-05-15 23:41:45 +0000 UTC" firstStartedPulling="2025-05-15 23:41:47.328237531 +0000 UTC m=+3.308448341" lastFinishedPulling="2025-05-15 23:41:53.490014914 +0000 UTC m=+9.470225712" observedRunningTime="2025-05-15 23:41:55.204140342 +0000 UTC m=+11.184351164" watchObservedRunningTime="2025-05-15 23:41:57.805371727 +0000 UTC m=+13.785582549" May 15 23:41:57.818590 systemd[1]: Created slice kubepods-besteffort-pod989b860c_bea8_455c_b9fa_ce06bbf6458f.slice - libcontainer container kubepods-besteffort-pod989b860c_bea8_455c_b9fa_ce06bbf6458f.slice. May 15 23:41:57.896670 kubelet[2379]: I0515 23:41:57.896615 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-75dc7\" (UniqueName: \"kubernetes.io/projected/989b860c-bea8-455c-b9fa-ce06bbf6458f-kube-api-access-75dc7\") pod \"nginx-deployment-7fcdb87857-rgbgt\" (UID: \"989b860c-bea8-455c-b9fa-ce06bbf6458f\") " pod="default/nginx-deployment-7fcdb87857-rgbgt" May 15 23:41:58.038245 kubelet[2379]: E0515 23:41:58.038179 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:58.123870 containerd[1930]: time="2025-05-15T23:41:58.123813413Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-rgbgt,Uid:989b860c-bea8-455c-b9fa-ce06bbf6458f,Namespace:default,Attempt:0,}" May 15 23:41:58.160009 (udev-worker)[2891]: Network interface NamePolicy= disabled on kernel command line. May 15 23:41:58.160241 systemd-networkd[1828]: cni0: Link UP May 15 23:41:58.172861 systemd-networkd[1828]: veth6c73820c: Link UP May 15 23:41:58.175944 kernel: cni0: port 1(veth6c73820c) entered blocking state May 15 23:41:58.176066 kernel: cni0: port 1(veth6c73820c) entered disabled state May 15 23:41:58.176112 kernel: veth6c73820c: entered allmulticast mode May 15 23:41:58.178043 kernel: veth6c73820c: entered promiscuous mode May 15 23:41:58.178408 kernel: cni0: port 1(veth6c73820c) entered blocking state May 15 23:41:58.181984 kernel: cni0: port 1(veth6c73820c) entered forwarding state May 15 23:41:58.184467 kernel: cni0: port 1(veth6c73820c) entered disabled state May 15 23:41:58.185952 (udev-worker)[2962]: Network interface NamePolicy= disabled on kernel command line. May 15 23:41:58.200365 kernel: cni0: port 1(veth6c73820c) entered blocking state May 15 23:41:58.200463 kernel: cni0: port 1(veth6c73820c) entered forwarding state May 15 23:41:58.199894 systemd-networkd[1828]: veth6c73820c: Gained carrier May 15 23:41:58.202704 systemd-networkd[1828]: cni0: Gained carrier May 15 23:41:58.207138 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 15 23:41:58.214310 containerd[1930]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} May 15 23:41:58.214310 containerd[1930]: delegateAdd: netconf sent to delegate plugin: May 15 23:41:58.257019 containerd[1930]: {"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-05-15T23:41:58.256568057Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:41:58.257019 containerd[1930]: time="2025-05-15T23:41:58.256675709Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:41:58.257019 containerd[1930]: time="2025-05-15T23:41:58.256711937Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:58.257019 containerd[1930]: time="2025-05-15T23:41:58.256882061Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:41:58.296592 systemd[1]: Started cri-containerd-c92c03c1e0502245ad648a914337a1779145abda43eeabd7ce84988113c593f6.scope - libcontainer container c92c03c1e0502245ad648a914337a1779145abda43eeabd7ce84988113c593f6. May 15 23:41:58.351691 containerd[1930]: time="2025-05-15T23:41:58.351224934Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-rgbgt,Uid:989b860c-bea8-455c-b9fa-ce06bbf6458f,Namespace:default,Attempt:0,} returns sandbox id \"c92c03c1e0502245ad648a914337a1779145abda43eeabd7ce84988113c593f6\"" May 15 23:41:58.354717 containerd[1930]: time="2025-05-15T23:41:58.354641466Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 23:41:59.039411 kubelet[2379]: E0515 23:41:59.039304 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:41:59.275452 systemd-networkd[1828]: veth6c73820c: Gained IPv6LL May 15 23:42:00.039711 kubelet[2379]: E0515 23:42:00.039610 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:00.171875 systemd-networkd[1828]: cni0: Gained IPv6LL May 15 23:42:01.040616 kubelet[2379]: E0515 23:42:01.040572 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:01.730768 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1576498300.mount: Deactivated successfully. May 15 23:42:02.042866 kubelet[2379]: E0515 23:42:02.042510 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:02.516513 ntpd[1909]: Listen normally on 8 flannel.1 192.168.1.0:123 May 15 23:42:02.516614 ntpd[1909]: Listen normally on 9 cni0 192.168.1.1:123 May 15 23:42:02.517547 ntpd[1909]: 15 May 23:42:02 ntpd[1909]: Listen normally on 8 flannel.1 192.168.1.0:123 May 15 23:42:02.517547 ntpd[1909]: 15 May 23:42:02 ntpd[1909]: Listen normally on 9 cni0 192.168.1.1:123 May 15 23:42:02.517547 ntpd[1909]: 15 May 23:42:02 ntpd[1909]: Listen normally on 10 flannel.1 [fe80::6c32:4ff:fe5c:eb9b%3]:123 May 15 23:42:02.517547 ntpd[1909]: 15 May 23:42:02 ntpd[1909]: Listen normally on 11 cni0 [fe80::34f1:e9ff:fec5:cde5%4]:123 May 15 23:42:02.517547 ntpd[1909]: 15 May 23:42:02 ntpd[1909]: Listen normally on 12 veth6c73820c [fe80::cccc:4cff:fe7a:19c6%5]:123 May 15 23:42:02.516709 ntpd[1909]: Listen normally on 10 flannel.1 [fe80::6c32:4ff:fe5c:eb9b%3]:123 May 15 23:42:02.516790 ntpd[1909]: Listen normally on 11 cni0 [fe80::34f1:e9ff:fec5:cde5%4]:123 May 15 23:42:02.516858 ntpd[1909]: Listen normally on 12 veth6c73820c [fe80::cccc:4cff:fe7a:19c6%5]:123 May 15 23:42:03.043779 kubelet[2379]: E0515 23:42:03.043709 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:03.054714 containerd[1930]: time="2025-05-15T23:42:03.054633657Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:03.056189 containerd[1930]: time="2025-05-15T23:42:03.056110521Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 15 23:42:03.057218 containerd[1930]: time="2025-05-15T23:42:03.057159813Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:03.064310 containerd[1930]: time="2025-05-15T23:42:03.064133109Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:03.066527 containerd[1930]: time="2025-05-15T23:42:03.066339513Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 4.711615559s" May 15 23:42:03.066527 containerd[1930]: time="2025-05-15T23:42:03.066396369Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 15 23:42:03.072076 containerd[1930]: time="2025-05-15T23:42:03.071995389Z" level=info msg="CreateContainer within sandbox \"c92c03c1e0502245ad648a914337a1779145abda43eeabd7ce84988113c593f6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 15 23:42:03.090244 containerd[1930]: time="2025-05-15T23:42:03.090168657Z" level=info msg="CreateContainer within sandbox \"c92c03c1e0502245ad648a914337a1779145abda43eeabd7ce84988113c593f6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054\"" May 15 23:42:03.091001 containerd[1930]: time="2025-05-15T23:42:03.090941061Z" level=info msg="StartContainer for \"36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054\"" May 15 23:42:03.139578 systemd[1]: run-containerd-runc-k8s.io-36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054-runc.3btDDy.mount: Deactivated successfully. May 15 23:42:03.152552 systemd[1]: Started cri-containerd-36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054.scope - libcontainer container 36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054. May 15 23:42:03.194988 containerd[1930]: time="2025-05-15T23:42:03.194862766Z" level=info msg="StartContainer for \"36e72f511c52ef58b679102dc8ca3d05944654354abf60c82bb0428c7008b054\" returns successfully" May 15 23:42:03.227215 kubelet[2379]: I0515 23:42:03.226016 2379 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-rgbgt" podStartSLOduration=1.511621387 podStartE2EDuration="6.225997162s" podCreationTimestamp="2025-05-15 23:41:57 +0000 UTC" firstStartedPulling="2025-05-15 23:41:58.35373357 +0000 UTC m=+14.333944404" lastFinishedPulling="2025-05-15 23:42:03.068109369 +0000 UTC m=+19.048320179" observedRunningTime="2025-05-15 23:42:03.225877918 +0000 UTC m=+19.206088752" watchObservedRunningTime="2025-05-15 23:42:03.225997162 +0000 UTC m=+19.206207960" May 15 23:42:04.044795 kubelet[2379]: E0515 23:42:04.044727 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:04.963909 kubelet[2379]: E0515 23:42:04.963838 2379 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:05.045787 kubelet[2379]: E0515 23:42:05.045730 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:06.046397 kubelet[2379]: E0515 23:42:06.046337 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:06.273853 systemd[1]: Created slice kubepods-besteffort-podc8c0818f_df7c_43ea_9e5b_216fd7638443.slice - libcontainer container kubepods-besteffort-podc8c0818f_df7c_43ea_9e5b_216fd7638443.slice. May 15 23:42:06.443060 kubelet[2379]: I0515 23:42:06.442978 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/c8c0818f-df7c-43ea-9e5b-216fd7638443-data\") pod \"nfs-server-provisioner-0\" (UID: \"c8c0818f-df7c-43ea-9e5b-216fd7638443\") " pod="default/nfs-server-provisioner-0" May 15 23:42:06.443235 kubelet[2379]: I0515 23:42:06.443068 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vstwj\" (UniqueName: \"kubernetes.io/projected/c8c0818f-df7c-43ea-9e5b-216fd7638443-kube-api-access-vstwj\") pod \"nfs-server-provisioner-0\" (UID: \"c8c0818f-df7c-43ea-9e5b-216fd7638443\") " pod="default/nfs-server-provisioner-0" May 15 23:42:06.580285 containerd[1930]: time="2025-05-15T23:42:06.580131351Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c8c0818f-df7c-43ea-9e5b-216fd7638443,Namespace:default,Attempt:0,}" May 15 23:42:06.612888 (udev-worker)[3149]: Network interface NamePolicy= disabled on kernel command line. May 15 23:42:06.614215 systemd-networkd[1828]: veth4f244d89: Link UP May 15 23:42:06.618880 kernel: cni0: port 2(veth4f244d89) entered blocking state May 15 23:42:06.618938 kernel: cni0: port 2(veth4f244d89) entered disabled state May 15 23:42:06.620072 kernel: veth4f244d89: entered allmulticast mode May 15 23:42:06.621458 kernel: veth4f244d89: entered promiscuous mode May 15 23:42:06.635726 kernel: cni0: port 2(veth4f244d89) entered blocking state May 15 23:42:06.635840 kernel: cni0: port 2(veth4f244d89) entered forwarding state May 15 23:42:06.636985 systemd-networkd[1828]: veth4f244d89: Gained carrier May 15 23:42:06.641971 containerd[1930]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000082950), "name":"cbr0", "type":"bridge"} May 15 23:42:06.641971 containerd[1930]: delegateAdd: netconf sent to delegate plugin: May 15 23:42:06.675662 containerd[1930]: {"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-05-15T23:42:06.675403707Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:42:06.675662 containerd[1930]: time="2025-05-15T23:42:06.675570567Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:42:06.676025 containerd[1930]: time="2025-05-15T23:42:06.675932451Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:42:06.676465 containerd[1930]: time="2025-05-15T23:42:06.676383795Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:42:06.711600 systemd[1]: Started cri-containerd-6d1fe2dcf061046fb4c79e36bd8ca4c2c479a55434b3449f6e7f93d93431898f.scope - libcontainer container 6d1fe2dcf061046fb4c79e36bd8ca4c2c479a55434b3449f6e7f93d93431898f. May 15 23:42:06.772974 containerd[1930]: time="2025-05-15T23:42:06.772915936Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:c8c0818f-df7c-43ea-9e5b-216fd7638443,Namespace:default,Attempt:0,} returns sandbox id \"6d1fe2dcf061046fb4c79e36bd8ca4c2c479a55434b3449f6e7f93d93431898f\"" May 15 23:42:06.776124 containerd[1930]: time="2025-05-15T23:42:06.776059960Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 15 23:42:07.047284 kubelet[2379]: E0515 23:42:07.047110 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:08.048074 kubelet[2379]: E0515 23:42:08.047998 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:08.235439 systemd-networkd[1828]: veth4f244d89: Gained IPv6LL May 15 23:42:09.049226 kubelet[2379]: E0515 23:42:09.049152 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:10.050322 kubelet[2379]: E0515 23:42:10.050190 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:10.517700 ntpd[1909]: Listen normally on 13 veth4f244d89 [fe80::e0ea:60ff:fedf:ad27%6]:123 May 15 23:42:10.518352 ntpd[1909]: 15 May 23:42:10 ntpd[1909]: Listen normally on 13 veth4f244d89 [fe80::e0ea:60ff:fedf:ad27%6]:123 May 15 23:42:10.618159 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3981261470.mount: Deactivated successfully. May 15 23:42:11.051024 kubelet[2379]: E0515 23:42:11.050965 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:12.053879 kubelet[2379]: E0515 23:42:12.053743 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:12.466407 update_engine[1916]: I20250515 23:42:12.466324 1916 update_attempter.cc:509] Updating boot flags... May 15 23:42:12.601297 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (3271) May 15 23:42:12.961322 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (3273) May 15 23:42:13.054874 kubelet[2379]: E0515 23:42:13.054827 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:13.963578 containerd[1930]: time="2025-05-15T23:42:13.963508763Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:13.965435 containerd[1930]: time="2025-05-15T23:42:13.965348003Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" May 15 23:42:13.966075 containerd[1930]: time="2025-05-15T23:42:13.966002123Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:13.971073 containerd[1930]: time="2025-05-15T23:42:13.971020763Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:13.973285 containerd[1930]: time="2025-05-15T23:42:13.973150583Z" 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 7.197028019s" May 15 23:42:13.973285 containerd[1930]: time="2025-05-15T23:42:13.973205183Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 15 23:42:13.984830 containerd[1930]: time="2025-05-15T23:42:13.984763836Z" level=info msg="CreateContainer within sandbox \"6d1fe2dcf061046fb4c79e36bd8ca4c2c479a55434b3449f6e7f93d93431898f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 15 23:42:14.007591 containerd[1930]: time="2025-05-15T23:42:14.007531724Z" level=info msg="CreateContainer within sandbox \"6d1fe2dcf061046fb4c79e36bd8ca4c2c479a55434b3449f6e7f93d93431898f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35\"" May 15 23:42:14.008624 containerd[1930]: time="2025-05-15T23:42:14.008563256Z" level=info msg="StartContainer for \"7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35\"" May 15 23:42:14.055611 systemd[1]: run-containerd-runc-k8s.io-7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35-runc.GWf51K.mount: Deactivated successfully. May 15 23:42:14.056974 kubelet[2379]: E0515 23:42:14.056078 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:14.065587 systemd[1]: Started cri-containerd-7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35.scope - libcontainer container 7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35. May 15 23:42:14.114882 containerd[1930]: time="2025-05-15T23:42:14.114646352Z" level=info msg="StartContainer for \"7329989c20613d367a334fb1dde4683e621587a93431147ceda6d19bd2af9e35\" returns successfully" May 15 23:42:14.267993 kubelet[2379]: I0515 23:42:14.267737 2379 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.064536949 podStartE2EDuration="8.267695853s" podCreationTimestamp="2025-05-15 23:42:06 +0000 UTC" firstStartedPulling="2025-05-15 23:42:06.775370596 +0000 UTC m=+22.755581394" lastFinishedPulling="2025-05-15 23:42:13.978529488 +0000 UTC m=+29.958740298" observedRunningTime="2025-05-15 23:42:14.267419085 +0000 UTC m=+30.247629907" watchObservedRunningTime="2025-05-15 23:42:14.267695853 +0000 UTC m=+30.247906687" May 15 23:42:15.056851 kubelet[2379]: E0515 23:42:15.056791 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:16.057909 kubelet[2379]: E0515 23:42:16.057834 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:17.058703 kubelet[2379]: E0515 23:42:17.058637 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:18.059713 kubelet[2379]: E0515 23:42:18.059646 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:19.059996 kubelet[2379]: E0515 23:42:19.059939 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:19.498070 systemd[1]: Created slice kubepods-besteffort-pod4cfba18e_22ed_4969_a6cb_d74c707957ba.slice - libcontainer container kubepods-besteffort-pod4cfba18e_22ed_4969_a6cb_d74c707957ba.slice. May 15 23:42:19.627341 kubelet[2379]: I0515 23:42:19.627197 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ldqsh\" (UniqueName: \"kubernetes.io/projected/4cfba18e-22ed-4969-a6cb-d74c707957ba-kube-api-access-ldqsh\") pod \"test-pod-1\" (UID: \"4cfba18e-22ed-4969-a6cb-d74c707957ba\") " pod="default/test-pod-1" May 15 23:42:19.627341 kubelet[2379]: I0515 23:42:19.627291 2379 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-63577283-5f1a-4f58-9ab4-4aa87b31d173\" (UniqueName: \"kubernetes.io/nfs/4cfba18e-22ed-4969-a6cb-d74c707957ba-pvc-63577283-5f1a-4f58-9ab4-4aa87b31d173\") pod \"test-pod-1\" (UID: \"4cfba18e-22ed-4969-a6cb-d74c707957ba\") " pod="default/test-pod-1" May 15 23:42:19.764408 kernel: FS-Cache: Loaded May 15 23:42:19.807096 kernel: RPC: Registered named UNIX socket transport module. May 15 23:42:19.807241 kernel: RPC: Registered udp transport module. May 15 23:42:19.807311 kernel: RPC: Registered tcp transport module. May 15 23:42:19.808061 kernel: RPC: Registered tcp-with-tls transport module. May 15 23:42:19.810223 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 15 23:42:20.061221 kubelet[2379]: E0515 23:42:20.061073 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:20.132518 kernel: NFS: Registering the id_resolver key type May 15 23:42:20.132634 kernel: Key type id_resolver registered May 15 23:42:20.132677 kernel: Key type id_legacy registered May 15 23:42:20.172951 nfsidmap[3571]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' May 15 23:42:20.178927 nfsidmap[3572]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' May 15 23:42:20.405508 containerd[1930]: time="2025-05-15T23:42:20.405352671Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4cfba18e-22ed-4969-a6cb-d74c707957ba,Namespace:default,Attempt:0,}" May 15 23:42:20.452156 (udev-worker)[3558]: Network interface NamePolicy= disabled on kernel command line. May 15 23:42:20.452973 systemd-networkd[1828]: veth501363b0: Link UP May 15 23:42:20.456881 kernel: cni0: port 3(veth501363b0) entered blocking state May 15 23:42:20.456989 kernel: cni0: port 3(veth501363b0) entered disabled state May 15 23:42:20.457031 kernel: veth501363b0: entered allmulticast mode May 15 23:42:20.459034 kernel: veth501363b0: entered promiscuous mode May 15 23:42:20.468770 kernel: cni0: port 3(veth501363b0) entered blocking state May 15 23:42:20.468963 kernel: cni0: port 3(veth501363b0) entered forwarding state May 15 23:42:20.472080 systemd-networkd[1828]: veth501363b0: Gained carrier May 15 23:42:20.477880 containerd[1930]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e840), "name":"cbr0", "type":"bridge"} May 15 23:42:20.477880 containerd[1930]: delegateAdd: netconf sent to delegate plugin: May 15 23:42:20.512415 containerd[1930]: {"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-05-15T23:42:20.512171908Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:42:20.512415 containerd[1930]: time="2025-05-15T23:42:20.512310040Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:42:20.512415 containerd[1930]: time="2025-05-15T23:42:20.512348896Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:42:20.513786 containerd[1930]: time="2025-05-15T23:42:20.513661084Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:42:20.547596 systemd[1]: Started cri-containerd-8d7f398794457ba310cd1fcf5fb308d2f684b81cd4e67f42402d646f8c091afe.scope - libcontainer container 8d7f398794457ba310cd1fcf5fb308d2f684b81cd4e67f42402d646f8c091afe. May 15 23:42:20.625523 containerd[1930]: time="2025-05-15T23:42:20.625449773Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4cfba18e-22ed-4969-a6cb-d74c707957ba,Namespace:default,Attempt:0,} returns sandbox id \"8d7f398794457ba310cd1fcf5fb308d2f684b81cd4e67f42402d646f8c091afe\"" May 15 23:42:20.627909 containerd[1930]: time="2025-05-15T23:42:20.627861137Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 15 23:42:21.013361 containerd[1930]: time="2025-05-15T23:42:21.013220834Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 15 23:42:21.015520 containerd[1930]: time="2025-05-15T23:42:21.015411422Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 15 23:42:21.021687 containerd[1930]: time="2025-05-15T23:42:21.021489326Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 393.395689ms" May 15 23:42:21.021687 containerd[1930]: time="2025-05-15T23:42:21.021551714Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 15 23:42:21.029677 containerd[1930]: time="2025-05-15T23:42:21.029595939Z" level=info msg="CreateContainer within sandbox \"8d7f398794457ba310cd1fcf5fb308d2f684b81cd4e67f42402d646f8c091afe\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 15 23:42:21.059886 containerd[1930]: time="2025-05-15T23:42:21.059723403Z" level=info msg="CreateContainer within sandbox \"8d7f398794457ba310cd1fcf5fb308d2f684b81cd4e67f42402d646f8c091afe\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1333ff8968c42565e873644dae8bb05b5f35b1fbb5908838f1b0980b3fea88b7\"" May 15 23:42:21.060843 containerd[1930]: time="2025-05-15T23:42:21.060779451Z" level=info msg="StartContainer for \"1333ff8968c42565e873644dae8bb05b5f35b1fbb5908838f1b0980b3fea88b7\"" May 15 23:42:21.061318 kubelet[2379]: E0515 23:42:21.061244 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:21.114591 systemd[1]: Started cri-containerd-1333ff8968c42565e873644dae8bb05b5f35b1fbb5908838f1b0980b3fea88b7.scope - libcontainer container 1333ff8968c42565e873644dae8bb05b5f35b1fbb5908838f1b0980b3fea88b7. May 15 23:42:21.165478 containerd[1930]: time="2025-05-15T23:42:21.165421851Z" level=info msg="StartContainer for \"1333ff8968c42565e873644dae8bb05b5f35b1fbb5908838f1b0980b3fea88b7\" returns successfully" May 15 23:42:21.803578 systemd-networkd[1828]: veth501363b0: Gained IPv6LL May 15 23:42:22.061778 kubelet[2379]: E0515 23:42:22.061623 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:23.062482 kubelet[2379]: E0515 23:42:23.062419 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:24.063582 kubelet[2379]: E0515 23:42:24.063513 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:24.516511 ntpd[1909]: Listen normally on 14 veth501363b0 [fe80::d8d4:edff:feb2:3cd8%7]:123 May 15 23:42:24.517054 ntpd[1909]: 15 May 23:42:24 ntpd[1909]: Listen normally on 14 veth501363b0 [fe80::d8d4:edff:feb2:3cd8%7]:123 May 15 23:42:24.964277 kubelet[2379]: E0515 23:42:24.964204 2379 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:25.064286 kubelet[2379]: E0515 23:42:25.064203 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:26.064679 kubelet[2379]: E0515 23:42:26.064605 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:27.065449 kubelet[2379]: E0515 23:42:27.065385 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:28.066022 kubelet[2379]: E0515 23:42:28.065957 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:29.066681 kubelet[2379]: E0515 23:42:29.066622 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:30.067645 kubelet[2379]: E0515 23:42:30.067579 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:31.068516 kubelet[2379]: E0515 23:42:31.068445 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:32.068640 kubelet[2379]: E0515 23:42:32.068568 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:33.069615 kubelet[2379]: E0515 23:42:33.069550 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:34.070286 kubelet[2379]: E0515 23:42:34.070220 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:35.071205 kubelet[2379]: E0515 23:42:35.071140 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:36.071973 kubelet[2379]: E0515 23:42:36.071911 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:37.072106 kubelet[2379]: E0515 23:42:37.072035 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:38.072621 kubelet[2379]: E0515 23:42:38.072557 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:39.073321 kubelet[2379]: E0515 23:42:39.073233 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:40.074166 kubelet[2379]: E0515 23:42:40.074098 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:41.074824 kubelet[2379]: E0515 23:42:41.074770 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:42.075599 kubelet[2379]: E0515 23:42:42.075535 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:43.076089 kubelet[2379]: E0515 23:42:43.076015 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:44.076680 kubelet[2379]: E0515 23:42:44.076621 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:44.964297 kubelet[2379]: E0515 23:42:44.964203 2379 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:45.077656 kubelet[2379]: E0515 23:42:45.077588 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:46.048418 kubelet[2379]: E0515 23:42:46.048222 2379 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" May 15 23:42:46.078013 kubelet[2379]: E0515 23:42:46.077953 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:47.078803 kubelet[2379]: E0515 23:42:47.078749 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:48.079363 kubelet[2379]: E0515 23:42:48.079302 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:49.079748 kubelet[2379]: E0515 23:42:49.079684 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:50.080886 kubelet[2379]: E0515 23:42:50.080829 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:51.081442 kubelet[2379]: E0515 23:42:51.081366 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:52.082479 kubelet[2379]: E0515 23:42:52.082413 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:53.083114 kubelet[2379]: E0515 23:42:53.083045 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:54.083702 kubelet[2379]: E0515 23:42:54.083583 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:55.084029 kubelet[2379]: E0515 23:42:55.083964 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:56.049151 kubelet[2379]: E0515 23:42:56.048748 2379 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" May 15 23:42:56.084182 kubelet[2379]: E0515 23:42:56.084103 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:57.085319 kubelet[2379]: E0515 23:42:57.085226 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:58.086094 kubelet[2379]: E0515 23:42:58.086033 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:42:59.087007 kubelet[2379]: E0515 23:42:59.086943 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:00.087606 kubelet[2379]: E0515 23:43:00.087543 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:01.088021 kubelet[2379]: E0515 23:43:01.087944 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:02.088932 kubelet[2379]: E0515 23:43:02.088867 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:03.089636 kubelet[2379]: E0515 23:43:03.089564 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:04.090216 kubelet[2379]: E0515 23:43:04.090152 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:04.964413 kubelet[2379]: E0515 23:43:04.964350 2379 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:05.091242 kubelet[2379]: E0515 23:43:05.091172 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:06.049716 kubelet[2379]: E0515 23:43:06.049356 2379 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" May 15 23:43:06.091687 kubelet[2379]: E0515 23:43:06.091621 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:07.092400 kubelet[2379]: E0515 23:43:07.092331 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:08.092812 kubelet[2379]: E0515 23:43:08.092743 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:08.236350 kubelet[2379]: E0515 23:43:08.236285 2379 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": unexpected EOF" May 15 23:43:08.241899 kubelet[2379]: E0515 23:43:08.241636 2379 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": dial tcp 172.31.31.16:6443: connect: connection reset by peer" May 15 23:43:08.241899 kubelet[2379]: I0515 23:43:08.241699 2379 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" May 15 23:43:08.242844 kubelet[2379]: E0515 23:43:08.242388 2379 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": dial tcp 172.31.31.16:6443: connect: connection refused" interval="200ms" May 15 23:43:08.444148 kubelet[2379]: E0515 23:43:08.444006 2379 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": dial tcp 172.31.31.16:6443: connect: connection refused" interval="400ms" May 15 23:43:08.845747 kubelet[2379]: E0515 23:43:08.845683 2379 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": dial tcp 172.31.31.16:6443: connect: connection refused" interval="800ms" May 15 23:43:09.093555 kubelet[2379]: E0515 23:43:09.093488 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:10.094123 kubelet[2379]: E0515 23:43:10.094046 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:11.094601 kubelet[2379]: E0515 23:43:11.094532 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:12.094959 kubelet[2379]: E0515 23:43:12.094893 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:13.095485 kubelet[2379]: E0515 23:43:13.095418 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:14.095812 kubelet[2379]: E0515 23:43:14.095749 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:15.096391 kubelet[2379]: E0515 23:43:15.096334 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:16.096911 kubelet[2379]: E0515 23:43:16.096848 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:17.097773 kubelet[2379]: E0515 23:43:17.097719 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:18.098932 kubelet[2379]: E0515 23:43:18.098858 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:19.099070 kubelet[2379]: E0515 23:43:19.098984 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:19.646544 kubelet[2379]: E0515 23:43:19.646474 2379 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="1.6s" May 15 23:43:20.100017 kubelet[2379]: E0515 23:43:20.099966 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:21.100349 kubelet[2379]: E0515 23:43:21.100289 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:22.100761 kubelet[2379]: E0515 23:43:22.100709 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:23.100945 kubelet[2379]: E0515 23:43:23.100856 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:24.101742 kubelet[2379]: E0515 23:43:24.101674 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:24.964601 kubelet[2379]: E0515 23:43:24.964544 2379 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:25.102199 kubelet[2379]: E0515 23:43:25.102126 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:26.102619 kubelet[2379]: E0515 23:43:26.102551 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:27.103417 kubelet[2379]: E0515 23:43:27.103360 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:28.103785 kubelet[2379]: E0515 23:43:28.103727 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:29.104842 kubelet[2379]: E0515 23:43:29.104785 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:30.105861 kubelet[2379]: E0515 23:43:30.105806 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:31.107027 kubelet[2379]: E0515 23:43:31.106954 2379 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 15 23:43:31.247674 kubelet[2379]: E0515 23:43:31.247600 2379 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.16:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.22.89?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="3.2s" May 15 23:43:31.482706 systemd-logind[1915]: Power key pressed short. May 15 23:43:31.482726 systemd-logind[1915]: Powering off...