Nov 12 22:23:39.204817 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 12 22:23:39.204863 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Nov 12 21:07:55 -00 2024 Nov 12 22:23:39.204887 kernel: KASLR disabled due to lack of seed Nov 12 22:23:39.204903 kernel: efi: EFI v2.7 by EDK II Nov 12 22:23:39.204919 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x78503d98 Nov 12 22:23:39.204934 kernel: secureboot: Secure boot disabled Nov 12 22:23:39.204951 kernel: ACPI: Early table checksum verification disabled Nov 12 22:23:39.204986 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 12 22:23:39.205004 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 12 22:23:39.205020 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 12 22:23:39.205041 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 12 22:23:39.205056 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 12 22:23:39.205072 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 12 22:23:39.205087 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 12 22:23:39.205105 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 12 22:23:39.205125 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 12 22:23:39.205142 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 12 22:23:39.205158 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 12 22:23:39.205174 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 12 22:23:39.205190 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 12 22:23:39.205206 kernel: printk: bootconsole [uart0] enabled Nov 12 22:23:39.205222 kernel: NUMA: Failed to initialise from firmware Nov 12 22:23:39.205239 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:23:39.205255 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 12 22:23:39.205272 kernel: Zone ranges: Nov 12 22:23:39.205288 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 12 22:23:39.205309 kernel: DMA32 empty Nov 12 22:23:39.205326 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 12 22:23:39.209058 kernel: Movable zone start for each node Nov 12 22:23:39.209080 kernel: Early memory node ranges Nov 12 22:23:39.209098 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 12 22:23:39.209115 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 12 22:23:39.209131 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 12 22:23:39.209148 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 12 22:23:39.209164 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 12 22:23:39.209180 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 12 22:23:39.209196 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 12 22:23:39.209213 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 12 22:23:39.209239 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:23:39.209256 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 12 22:23:39.209280 kernel: psci: probing for conduit method from ACPI. Nov 12 22:23:39.209298 kernel: psci: PSCIv1.0 detected in firmware. Nov 12 22:23:39.209315 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 22:23:39.209336 kernel: psci: Trusted OS migration not required Nov 12 22:23:39.209355 kernel: psci: SMC Calling Convention v1.1 Nov 12 22:23:39.209372 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 22:23:39.209390 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 22:23:39.209407 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 12 22:23:39.209425 kernel: Detected PIPT I-cache on CPU0 Nov 12 22:23:39.209442 kernel: CPU features: detected: GIC system register CPU interface Nov 12 22:23:39.209459 kernel: CPU features: detected: Spectre-v2 Nov 12 22:23:39.209476 kernel: CPU features: detected: Spectre-v3a Nov 12 22:23:39.209493 kernel: CPU features: detected: Spectre-BHB Nov 12 22:23:39.209510 kernel: CPU features: detected: ARM erratum 1742098 Nov 12 22:23:39.209528 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 12 22:23:39.209549 kernel: alternatives: applying boot alternatives Nov 12 22:23:39.209569 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=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:23:39.209588 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 22:23:39.209606 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 22:23:39.209623 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 22:23:39.209640 kernel: Fallback order for Node 0: 0 Nov 12 22:23:39.209657 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 12 22:23:39.209675 kernel: Policy zone: Normal Nov 12 22:23:39.209692 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 22:23:39.209709 kernel: software IO TLB: area num 2. Nov 12 22:23:39.209730 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 12 22:23:39.209748 kernel: Memory: 3819960K/4030464K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39680K init, 897K bss, 210504K reserved, 0K cma-reserved) Nov 12 22:23:39.209766 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 12 22:23:39.209783 kernel: trace event string verifier disabled Nov 12 22:23:39.209800 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 22:23:39.209819 kernel: rcu: RCU event tracing is enabled. Nov 12 22:23:39.209837 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 12 22:23:39.209854 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 22:23:39.209872 kernel: Tracing variant of Tasks RCU enabled. Nov 12 22:23:39.209889 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 22:23:39.209906 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 12 22:23:39.209927 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 22:23:39.209945 kernel: GICv3: 96 SPIs implemented Nov 12 22:23:39.210071 kernel: GICv3: 0 Extended SPIs implemented Nov 12 22:23:39.210094 kernel: Root IRQ handler: gic_handle_irq Nov 12 22:23:39.210112 kernel: GICv3: GICv3 features: 16 PPIs Nov 12 22:23:39.210129 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 12 22:23:39.210146 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 12 22:23:39.210164 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 22:23:39.210182 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 12 22:23:39.210199 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 12 22:23:39.210216 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 12 22:23:39.210233 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 12 22:23:39.210257 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 22:23:39.210275 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 12 22:23:39.210292 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 12 22:23:39.210311 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 12 22:23:39.210329 kernel: Console: colour dummy device 80x25 Nov 12 22:23:39.210347 kernel: printk: console [tty1] enabled Nov 12 22:23:39.210366 kernel: ACPI: Core revision 20230628 Nov 12 22:23:39.210384 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 12 22:23:39.210402 kernel: pid_max: default: 32768 minimum: 301 Nov 12 22:23:39.210420 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 22:23:39.210442 kernel: landlock: Up and running. Nov 12 22:23:39.210459 kernel: SELinux: Initializing. Nov 12 22:23:39.210477 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:23:39.210494 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:23:39.210512 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:23:39.210530 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:23:39.210547 kernel: rcu: Hierarchical SRCU implementation. Nov 12 22:23:39.210564 kernel: rcu: Max phase no-delay instances is 400. Nov 12 22:23:39.210582 kernel: Platform MSI: ITS@0x10080000 domain created Nov 12 22:23:39.210603 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 12 22:23:39.210620 kernel: Remapping and enabling EFI services. Nov 12 22:23:39.210637 kernel: smp: Bringing up secondary CPUs ... Nov 12 22:23:39.210654 kernel: Detected PIPT I-cache on CPU1 Nov 12 22:23:39.210672 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 12 22:23:39.210689 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 12 22:23:39.210706 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 12 22:23:39.210724 kernel: smp: Brought up 1 node, 2 CPUs Nov 12 22:23:39.210741 kernel: SMP: Total of 2 processors activated. Nov 12 22:23:39.210762 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 22:23:39.210779 kernel: CPU features: detected: 32-bit EL1 Support Nov 12 22:23:39.210807 kernel: CPU features: detected: CRC32 instructions Nov 12 22:23:39.210829 kernel: CPU: All CPU(s) started at EL1 Nov 12 22:23:39.210847 kernel: alternatives: applying system-wide alternatives Nov 12 22:23:39.210865 kernel: devtmpfs: initialized Nov 12 22:23:39.210883 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 22:23:39.210901 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 12 22:23:39.210919 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 22:23:39.210942 kernel: SMBIOS 3.0.0 present. Nov 12 22:23:39.210976 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 12 22:23:39.211000 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 22:23:39.211019 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 22:23:39.211039 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 22:23:39.211057 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 22:23:39.211076 kernel: audit: initializing netlink subsys (disabled) Nov 12 22:23:39.211102 kernel: audit: type=2000 audit(0.222:1): state=initialized audit_enabled=0 res=1 Nov 12 22:23:39.211121 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 22:23:39.211140 kernel: cpuidle: using governor menu Nov 12 22:23:39.211159 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 22:23:39.211177 kernel: ASID allocator initialised with 65536 entries Nov 12 22:23:39.211196 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 22:23:39.211214 kernel: Serial: AMBA PL011 UART driver Nov 12 22:23:39.211233 kernel: Modules: 17440 pages in range for non-PLT usage Nov 12 22:23:39.211252 kernel: Modules: 508960 pages in range for PLT usage Nov 12 22:23:39.211271 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 22:23:39.211295 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 22:23:39.211314 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 22:23:39.211334 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 22:23:39.211353 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 22:23:39.211371 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 22:23:39.211390 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 22:23:39.211409 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 22:23:39.211427 kernel: ACPI: Added _OSI(Module Device) Nov 12 22:23:39.211445 kernel: ACPI: Added _OSI(Processor Device) Nov 12 22:23:39.211468 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 22:23:39.211486 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 22:23:39.211526 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 22:23:39.211546 kernel: ACPI: Interpreter enabled Nov 12 22:23:39.211564 kernel: ACPI: Using GIC for interrupt routing Nov 12 22:23:39.211583 kernel: ACPI: MCFG table detected, 1 entries Nov 12 22:23:39.211602 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 12 22:23:39.214092 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 22:23:39.218186 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 22:23:39.218418 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 22:23:39.218625 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 12 22:23:39.218828 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 12 22:23:39.218854 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 12 22:23:39.218873 kernel: acpiphp: Slot [1] registered Nov 12 22:23:39.218893 kernel: acpiphp: Slot [2] registered Nov 12 22:23:39.218912 kernel: acpiphp: Slot [3] registered Nov 12 22:23:39.218940 kernel: acpiphp: Slot [4] registered Nov 12 22:23:39.218994 kernel: acpiphp: Slot [5] registered Nov 12 22:23:39.219020 kernel: acpiphp: Slot [6] registered Nov 12 22:23:39.219039 kernel: acpiphp: Slot [7] registered Nov 12 22:23:39.219059 kernel: acpiphp: Slot [8] registered Nov 12 22:23:39.219079 kernel: acpiphp: Slot [9] registered Nov 12 22:23:39.219097 kernel: acpiphp: Slot [10] registered Nov 12 22:23:39.219116 kernel: acpiphp: Slot [11] registered Nov 12 22:23:39.219135 kernel: acpiphp: Slot [12] registered Nov 12 22:23:39.219160 kernel: acpiphp: Slot [13] registered Nov 12 22:23:39.219180 kernel: acpiphp: Slot [14] registered Nov 12 22:23:39.219199 kernel: acpiphp: Slot [15] registered Nov 12 22:23:39.219219 kernel: acpiphp: Slot [16] registered Nov 12 22:23:39.219238 kernel: acpiphp: Slot [17] registered Nov 12 22:23:39.219258 kernel: acpiphp: Slot [18] registered Nov 12 22:23:39.219277 kernel: acpiphp: Slot [19] registered Nov 12 22:23:39.219295 kernel: acpiphp: Slot [20] registered Nov 12 22:23:39.219313 kernel: acpiphp: Slot [21] registered Nov 12 22:23:39.219332 kernel: acpiphp: Slot [22] registered Nov 12 22:23:39.219356 kernel: acpiphp: Slot [23] registered Nov 12 22:23:39.219375 kernel: acpiphp: Slot [24] registered Nov 12 22:23:39.219393 kernel: acpiphp: Slot [25] registered Nov 12 22:23:39.219412 kernel: acpiphp: Slot [26] registered Nov 12 22:23:39.219430 kernel: acpiphp: Slot [27] registered Nov 12 22:23:39.219448 kernel: acpiphp: Slot [28] registered Nov 12 22:23:39.219466 kernel: acpiphp: Slot [29] registered Nov 12 22:23:39.219485 kernel: acpiphp: Slot [30] registered Nov 12 22:23:39.219523 kernel: acpiphp: Slot [31] registered Nov 12 22:23:39.219547 kernel: PCI host bridge to bus 0000:00 Nov 12 22:23:39.219794 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 12 22:23:39.220003 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 22:23:39.220199 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 12 22:23:39.220387 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 12 22:23:39.220625 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 12 22:23:39.222851 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 12 22:23:39.223124 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 12 22:23:39.223363 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 12 22:23:39.223600 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 12 22:23:39.223811 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:23:39.224065 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 12 22:23:39.224276 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 12 22:23:39.224496 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 12 22:23:39.224701 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 12 22:23:39.224906 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:23:39.225177 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 12 22:23:39.225393 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 12 22:23:39.225606 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 12 22:23:39.225817 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 12 22:23:39.226416 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 12 22:23:39.226726 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 12 22:23:39.227117 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 22:23:39.229711 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 12 22:23:39.229767 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 22:23:39.229788 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 22:23:39.229809 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 22:23:39.229830 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 22:23:39.229865 kernel: iommu: Default domain type: Translated Nov 12 22:23:39.229887 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 22:23:39.229906 kernel: efivars: Registered efivars operations Nov 12 22:23:39.229926 kernel: vgaarb: loaded Nov 12 22:23:39.229945 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 22:23:39.230033 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 22:23:39.230058 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 22:23:39.230079 kernel: pnp: PnP ACPI init Nov 12 22:23:39.230394 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 12 22:23:39.230465 kernel: pnp: PnP ACPI: found 1 devices Nov 12 22:23:39.230485 kernel: NET: Registered PF_INET protocol family Nov 12 22:23:39.230505 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 22:23:39.230525 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 22:23:39.230545 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 22:23:39.230564 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 22:23:39.230584 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 22:23:39.230603 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 22:23:39.230630 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:23:39.230650 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:23:39.230669 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 22:23:39.230688 kernel: PCI: CLS 0 bytes, default 64 Nov 12 22:23:39.230708 kernel: kvm [1]: HYP mode not available Nov 12 22:23:39.230727 kernel: Initialise system trusted keyrings Nov 12 22:23:39.230747 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 22:23:39.230766 kernel: Key type asymmetric registered Nov 12 22:23:39.230785 kernel: Asymmetric key parser 'x509' registered Nov 12 22:23:39.230812 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 22:23:39.230833 kernel: io scheduler mq-deadline registered Nov 12 22:23:39.230853 kernel: io scheduler kyber registered Nov 12 22:23:39.230873 kernel: io scheduler bfq registered Nov 12 22:23:39.231268 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 12 22:23:39.231328 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 22:23:39.231349 kernel: ACPI: button: Power Button [PWRB] Nov 12 22:23:39.231369 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 12 22:23:39.231389 kernel: ACPI: button: Sleep Button [SLPB] Nov 12 22:23:39.231426 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 22:23:39.231449 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 12 22:23:39.231835 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 12 22:23:39.231896 kernel: printk: console [ttyS0] disabled Nov 12 22:23:39.231918 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 12 22:23:39.231940 kernel: printk: console [ttyS0] enabled Nov 12 22:23:39.231999 kernel: printk: bootconsole [uart0] disabled Nov 12 22:23:39.232026 kernel: thunder_xcv, ver 1.0 Nov 12 22:23:39.232046 kernel: thunder_bgx, ver 1.0 Nov 12 22:23:39.232081 kernel: nicpf, ver 1.0 Nov 12 22:23:39.232101 kernel: nicvf, ver 1.0 Nov 12 22:23:39.235421 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 22:23:39.235730 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T22:23:38 UTC (1731450218) Nov 12 22:23:39.235768 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 22:23:39.235791 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 12 22:23:39.235811 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 22:23:39.235832 kernel: watchdog: Hard watchdog permanently disabled Nov 12 22:23:39.235870 kernel: NET: Registered PF_INET6 protocol family Nov 12 22:23:39.235889 kernel: Segment Routing with IPv6 Nov 12 22:23:39.235909 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 22:23:39.235929 kernel: NET: Registered PF_PACKET protocol family Nov 12 22:23:39.235948 kernel: Key type dns_resolver registered Nov 12 22:23:39.236099 kernel: registered taskstats version 1 Nov 12 22:23:39.236122 kernel: Loading compiled-in X.509 certificates Nov 12 22:23:39.236144 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 27dd0d090d7a0971a24582c9198f7e80123ea69f' Nov 12 22:23:39.236165 kernel: Key type .fscrypt registered Nov 12 22:23:39.236199 kernel: Key type fscrypt-provisioning registered Nov 12 22:23:39.236218 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 22:23:39.236238 kernel: ima: Allocated hash algorithm: sha1 Nov 12 22:23:39.236259 kernel: ima: No architecture policies found Nov 12 22:23:39.236279 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 22:23:39.236300 kernel: clk: Disabling unused clocks Nov 12 22:23:39.236320 kernel: Freeing unused kernel memory: 39680K Nov 12 22:23:39.236339 kernel: Run /init as init process Nov 12 22:23:39.236360 kernel: with arguments: Nov 12 22:23:39.236387 kernel: /init Nov 12 22:23:39.236407 kernel: with environment: Nov 12 22:23:39.236425 kernel: HOME=/ Nov 12 22:23:39.236444 kernel: TERM=linux Nov 12 22:23:39.236463 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 22:23:39.236490 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) Nov 12 22:23:39.236516 systemd[1]: Detected virtualization amazon. Nov 12 22:23:39.236538 systemd[1]: Detected architecture arm64. Nov 12 22:23:39.236567 systemd[1]: Running in initrd. Nov 12 22:23:39.236589 systemd[1]: No hostname configured, using default hostname. Nov 12 22:23:39.236610 systemd[1]: Hostname set to . Nov 12 22:23:39.236631 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:23:39.236652 systemd[1]: Queued start job for default target initrd.target. Nov 12 22:23:39.236674 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:39.236695 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:39.236718 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 22:23:39.236746 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:23:39.236767 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 22:23:39.236788 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 22:23:39.236812 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 22:23:39.236834 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 22:23:39.236856 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:39.236882 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:39.236905 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:23:39.236927 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:23:39.236948 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:23:39.237018 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:23:39.237043 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:23:39.237066 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:23:39.237088 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:23:39.237109 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:23:39.237140 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:39.237162 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:39.237184 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:39.237206 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:23:39.237225 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 22:23:39.237247 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:23:39.237268 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 22:23:39.237290 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 22:23:39.237311 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:23:39.237341 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:23:39.237363 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:39.237384 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 22:23:39.237406 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:39.237428 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 22:23:39.237532 systemd-journald[252]: Collecting audit messages is disabled. Nov 12 22:23:39.237592 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:23:39.237613 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 22:23:39.237641 kernel: Bridge firewalling registered Nov 12 22:23:39.237662 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:39.237684 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:39.237707 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:39.237730 systemd-journald[252]: Journal started Nov 12 22:23:39.237770 systemd-journald[252]: Runtime Journal (/run/log/journal/ec280b19b8d024259a1fbab7a4d937ca) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:23:39.180938 systemd-modules-load[253]: Inserted module 'overlay' Nov 12 22:23:39.213037 systemd-modules-load[253]: Inserted module 'br_netfilter' Nov 12 22:23:39.259550 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:23:39.259620 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:23:39.266120 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:23:39.280037 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:39.293326 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:23:39.299356 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:23:39.312730 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:39.328253 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:39.339615 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 22:23:39.364221 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:23:39.384671 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:39.407977 dracut-cmdline[287]: dracut-dracut-053 Nov 12 22:23:39.419168 dracut-cmdline[287]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:23:39.458322 systemd-resolved[288]: Positive Trust Anchors: Nov 12 22:23:39.460208 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:23:39.460275 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 Nov 12 22:23:39.566014 kernel: SCSI subsystem initialized Nov 12 22:23:39.573996 kernel: Loading iSCSI transport class v2.0-870. Nov 12 22:23:39.587661 kernel: iscsi: registered transport (tcp) Nov 12 22:23:39.609085 kernel: iscsi: registered transport (qla4xxx) Nov 12 22:23:39.609158 kernel: QLogic iSCSI HBA Driver Nov 12 22:23:39.690004 kernel: random: crng init done Nov 12 22:23:39.689298 systemd-resolved[288]: Defaulting to hostname 'linux'. Nov 12 22:23:39.693146 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:23:39.693914 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:39.718690 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 22:23:39.727311 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 22:23:39.773851 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 22:23:39.773930 kernel: device-mapper: uevent: version 1.0.3 Nov 12 22:23:39.773979 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 22:23:39.842013 kernel: raid6: neonx8 gen() 6613 MB/s Nov 12 22:23:39.859005 kernel: raid6: neonx4 gen() 6410 MB/s Nov 12 22:23:39.875997 kernel: raid6: neonx2 gen() 5354 MB/s Nov 12 22:23:39.892995 kernel: raid6: neonx1 gen() 3919 MB/s Nov 12 22:23:39.909992 kernel: raid6: int64x8 gen() 3764 MB/s Nov 12 22:23:39.926995 kernel: raid6: int64x4 gen() 3687 MB/s Nov 12 22:23:39.944009 kernel: raid6: int64x2 gen() 3556 MB/s Nov 12 22:23:39.961804 kernel: raid6: int64x1 gen() 2764 MB/s Nov 12 22:23:39.961860 kernel: raid6: using algorithm neonx8 gen() 6613 MB/s Nov 12 22:23:39.979748 kernel: raid6: .... xor() 4915 MB/s, rmw enabled Nov 12 22:23:39.979806 kernel: raid6: using neon recovery algorithm Nov 12 22:23:39.988480 kernel: xor: measuring software checksum speed Nov 12 22:23:39.988549 kernel: 8regs : 10616 MB/sec Nov 12 22:23:39.989607 kernel: 32regs : 11976 MB/sec Nov 12 22:23:39.990811 kernel: arm64_neon : 9580 MB/sec Nov 12 22:23:39.990848 kernel: xor: using function: 32regs (11976 MB/sec) Nov 12 22:23:40.078051 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 22:23:40.100088 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:23:40.111310 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:40.152876 systemd-udevd[471]: Using default interface naming scheme 'v255'. Nov 12 22:23:40.163158 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:40.177725 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 22:23:40.212591 dracut-pre-trigger[475]: rd.md=0: removing MD RAID activation Nov 12 22:23:40.275156 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:23:40.284395 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:23:40.420724 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:40.435249 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 22:23:40.485252 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 22:23:40.490611 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:23:40.495572 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:40.501303 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:23:40.514431 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 22:23:40.551064 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:23:40.638044 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 22:23:40.638121 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 12 22:23:40.658211 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 12 22:23:40.658760 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 12 22:23:40.659033 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:4e:7f:99:0f:fd Nov 12 22:23:40.649482 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:23:40.649598 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:40.654096 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:40.656296 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:23:40.656402 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:40.658657 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:40.676810 (udev-worker)[518]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:23:40.691107 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 12 22:23:40.691148 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 12 22:23:40.684672 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:40.703003 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 12 22:23:40.714989 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 22:23:40.715052 kernel: GPT:9289727 != 16777215 Nov 12 22:23:40.715078 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 22:23:40.715102 kernel: GPT:9289727 != 16777215 Nov 12 22:23:40.716679 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 22:23:40.716720 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:40.719529 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:40.736563 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:40.792668 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:40.840435 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 12 22:23:40.887999 kernel: BTRFS: device fsid 337794e4-53df-462b-aefc-e93e6a958f34 devid 1 transid 41 /dev/nvme0n1p3 scanned by (udev-worker) (523) Nov 12 22:23:40.888088 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (537) Nov 12 22:23:40.966167 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 12 22:23:40.997411 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:23:41.013300 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 12 22:23:41.019574 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 12 22:23:41.030365 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 22:23:41.048221 disk-uuid[662]: Primary Header is updated. Nov 12 22:23:41.048221 disk-uuid[662]: Secondary Entries is updated. Nov 12 22:23:41.048221 disk-uuid[662]: Secondary Header is updated. Nov 12 22:23:41.059003 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:42.077103 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:42.077687 disk-uuid[663]: The operation has completed successfully. Nov 12 22:23:42.265579 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 22:23:42.268085 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 22:23:42.309377 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 22:23:42.318845 sh[923]: Success Nov 12 22:23:42.349027 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 22:23:42.467370 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 22:23:42.481207 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 22:23:42.489608 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 22:23:42.527310 kernel: BTRFS info (device dm-0): first mount of filesystem 337794e4-53df-462b-aefc-e93e6a958f34 Nov 12 22:23:42.527398 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:42.527426 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 22:23:42.530376 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 22:23:42.530455 kernel: BTRFS info (device dm-0): using free space tree Nov 12 22:23:42.661021 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 12 22:23:42.715811 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 22:23:42.720228 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 22:23:42.733239 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 22:23:42.740697 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 22:23:42.758767 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:42.758857 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:42.760299 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:42.768415 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:42.787674 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 22:23:42.790738 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:42.827395 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 22:23:42.840429 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 22:23:42.931727 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:23:42.948319 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:23:43.004388 systemd-networkd[1116]: lo: Link UP Nov 12 22:23:43.004418 systemd-networkd[1116]: lo: Gained carrier Nov 12 22:23:43.009080 systemd-networkd[1116]: Enumeration completed Nov 12 22:23:43.009923 systemd-networkd[1116]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:43.009930 systemd-networkd[1116]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:23:43.013776 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:23:43.018573 systemd-networkd[1116]: eth0: Link UP Nov 12 22:23:43.018582 systemd-networkd[1116]: eth0: Gained carrier Nov 12 22:23:43.018602 systemd-networkd[1116]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:43.027712 systemd[1]: Reached target network.target - Network. Nov 12 22:23:43.044123 systemd-networkd[1116]: eth0: DHCPv4 address 172.31.27.94/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:23:43.443334 ignition[1044]: Ignition 2.20.0 Nov 12 22:23:43.443367 ignition[1044]: Stage: fetch-offline Nov 12 22:23:43.443927 ignition[1044]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:43.445074 ignition[1044]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:43.446857 ignition[1044]: Ignition finished successfully Nov 12 22:23:43.453174 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:23:43.473536 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 12 22:23:43.502302 ignition[1126]: Ignition 2.20.0 Nov 12 22:23:43.502854 ignition[1126]: Stage: fetch Nov 12 22:23:43.503683 ignition[1126]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:43.503714 ignition[1126]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:43.503925 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:43.515278 ignition[1126]: PUT result: OK Nov 12 22:23:43.518507 ignition[1126]: parsed url from cmdline: "" Nov 12 22:23:43.518527 ignition[1126]: no config URL provided Nov 12 22:23:43.518543 ignition[1126]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 22:23:43.518579 ignition[1126]: no config at "/usr/lib/ignition/user.ign" Nov 12 22:23:43.518630 ignition[1126]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:43.522864 ignition[1126]: PUT result: OK Nov 12 22:23:43.533058 unknown[1126]: fetched base config from "system" Nov 12 22:23:43.523135 ignition[1126]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 12 22:23:43.533076 unknown[1126]: fetched base config from "system" Nov 12 22:23:43.524024 ignition[1126]: GET result: OK Nov 12 22:23:43.533090 unknown[1126]: fetched user config from "aws" Nov 12 22:23:43.524149 ignition[1126]: parsing config with SHA512: 12380b695829de9ebbb4e95532a10b9b631c28250e763046d74632ba5a85a3a05bc8796cd38194513e94ec35dfb56452c8a95386f5b243808a1100cf8d11d6cd Nov 12 22:23:43.533640 ignition[1126]: fetch: fetch complete Nov 12 22:23:43.533652 ignition[1126]: fetch: fetch passed Nov 12 22:23:43.533760 ignition[1126]: Ignition finished successfully Nov 12 22:23:43.549622 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 12 22:23:43.558318 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 22:23:43.596191 ignition[1133]: Ignition 2.20.0 Nov 12 22:23:43.596221 ignition[1133]: Stage: kargs Nov 12 22:23:43.597733 ignition[1133]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:43.597761 ignition[1133]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:43.598625 ignition[1133]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:43.604293 ignition[1133]: PUT result: OK Nov 12 22:23:43.612255 ignition[1133]: kargs: kargs passed Nov 12 22:23:43.613273 ignition[1133]: Ignition finished successfully Nov 12 22:23:43.617222 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 22:23:43.629252 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 22:23:43.654608 ignition[1139]: Ignition 2.20.0 Nov 12 22:23:43.656150 ignition[1139]: Stage: disks Nov 12 22:23:43.656767 ignition[1139]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:43.656793 ignition[1139]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:43.656941 ignition[1139]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:43.658724 ignition[1139]: PUT result: OK Nov 12 22:23:43.666335 ignition[1139]: disks: disks passed Nov 12 22:23:43.666427 ignition[1139]: Ignition finished successfully Nov 12 22:23:43.684018 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 22:23:43.690577 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 22:23:43.694944 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:23:43.697273 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:23:43.699194 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:23:43.703169 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:23:43.731377 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 22:23:43.779327 systemd-fsck[1147]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 22:23:43.786688 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 22:23:43.799205 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 22:23:43.884992 kernel: EXT4-fs (nvme0n1p9): mounted filesystem be7e07bb-77fc-4aec-a4f6-d76dc4498784 r/w with ordered data mode. Quota mode: none. Nov 12 22:23:43.886458 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 22:23:43.890369 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 22:23:43.912188 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:23:43.918145 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 22:23:43.921593 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 22:23:43.930076 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 22:23:43.930170 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:23:43.955003 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1166) Nov 12 22:23:43.959411 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:43.959467 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:43.960676 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:43.966645 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 22:23:43.973182 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:43.977280 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 22:23:43.987490 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:23:44.411182 systemd-networkd[1116]: eth0: Gained IPv6LL Nov 12 22:23:44.580920 initrd-setup-root[1190]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 22:23:44.591289 initrd-setup-root[1197]: cut: /sysroot/etc/group: No such file or directory Nov 12 22:23:44.616064 initrd-setup-root[1204]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 22:23:44.624586 initrd-setup-root[1211]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 22:23:45.058734 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 22:23:45.073183 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 22:23:45.079301 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 22:23:45.106430 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 22:23:45.109125 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:45.139550 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 22:23:45.157857 ignition[1279]: INFO : Ignition 2.20.0 Nov 12 22:23:45.161114 ignition[1279]: INFO : Stage: mount Nov 12 22:23:45.161114 ignition[1279]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:45.161114 ignition[1279]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:45.161114 ignition[1279]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:45.169633 ignition[1279]: INFO : PUT result: OK Nov 12 22:23:45.173725 ignition[1279]: INFO : mount: mount passed Nov 12 22:23:45.173725 ignition[1279]: INFO : Ignition finished successfully Nov 12 22:23:45.177543 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 22:23:45.187195 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 22:23:45.221327 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:23:45.243011 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1290) Nov 12 22:23:45.247015 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:45.247090 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:45.248302 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:45.254991 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:45.256225 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:23:45.292607 ignition[1307]: INFO : Ignition 2.20.0 Nov 12 22:23:45.295388 ignition[1307]: INFO : Stage: files Nov 12 22:23:45.295388 ignition[1307]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:45.295388 ignition[1307]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:45.295388 ignition[1307]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:45.304499 ignition[1307]: INFO : PUT result: OK Nov 12 22:23:45.308443 ignition[1307]: DEBUG : files: compiled without relabeling support, skipping Nov 12 22:23:45.311796 ignition[1307]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 22:23:45.314743 ignition[1307]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 22:23:45.334241 ignition[1307]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 22:23:45.337369 ignition[1307]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 22:23:45.340642 unknown[1307]: wrote ssh authorized keys file for user: core Nov 12 22:23:45.343060 ignition[1307]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 22:23:45.356523 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Nov 12 22:23:45.360184 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Nov 12 22:23:45.360184 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Nov 12 22:23:45.360184 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Nov 12 22:23:45.360184 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:23:45.373991 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:23:45.373991 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 22:23:45.373991 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 22:23:45.373991 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 22:23:45.373991 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Nov 12 22:23:45.747981 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Nov 12 22:23:46.125855 ignition[1307]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 22:23:46.125855 ignition[1307]: INFO : files: op(8): [started] processing unit "containerd.service" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: op(8): [finished] processing unit "containerd.service" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:23:46.132414 ignition[1307]: INFO : files: files passed Nov 12 22:23:46.132414 ignition[1307]: INFO : Ignition finished successfully Nov 12 22:23:46.152931 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 22:23:46.165257 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 22:23:46.171225 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 22:23:46.197119 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 22:23:46.199077 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 22:23:46.207459 initrd-setup-root-after-ignition[1335]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:46.210933 initrd-setup-root-after-ignition[1335]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:46.214068 initrd-setup-root-after-ignition[1339]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:46.221057 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:23:46.221614 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 22:23:46.243988 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 22:23:46.301866 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 22:23:46.302175 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 22:23:46.305271 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 22:23:46.305413 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 22:23:46.305782 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 22:23:46.328374 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 22:23:46.357337 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:23:46.367369 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 22:23:46.396740 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:46.401435 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:46.404454 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 22:23:46.408429 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 22:23:46.408720 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:23:46.416386 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 22:23:46.419183 systemd[1]: Stopped target basic.target - Basic System. Nov 12 22:23:46.422503 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 22:23:46.428304 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:23:46.431824 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 22:23:46.435072 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 22:23:46.437447 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:23:46.445852 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 22:23:46.448023 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 22:23:46.450457 systemd[1]: Stopped target swap.target - Swaps. Nov 12 22:23:46.454358 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 22:23:46.454633 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:23:46.460102 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:46.461346 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:46.467558 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 22:23:46.472988 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:46.478876 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 22:23:46.479152 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 22:23:46.484599 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 22:23:46.484854 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:23:46.493014 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 22:23:46.493460 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 22:23:46.509137 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 22:23:46.513115 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 22:23:46.513596 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:46.537081 ignition[1359]: INFO : Ignition 2.20.0 Nov 12 22:23:46.537081 ignition[1359]: INFO : Stage: umount Nov 12 22:23:46.537081 ignition[1359]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:46.537081 ignition[1359]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:46.546220 ignition[1359]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:46.546220 ignition[1359]: INFO : PUT result: OK Nov 12 22:23:46.552989 ignition[1359]: INFO : umount: umount passed Nov 12 22:23:46.552989 ignition[1359]: INFO : Ignition finished successfully Nov 12 22:23:46.557890 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 22:23:46.561315 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 22:23:46.561577 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:46.564159 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 22:23:46.564389 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:23:46.579585 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 22:23:46.583096 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 22:23:46.586738 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 22:23:46.589884 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 22:23:46.591399 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 22:23:46.591861 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 22:23:46.592453 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 12 22:23:46.592550 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 12 22:23:46.592846 systemd[1]: Stopped target network.target - Network. Nov 12 22:23:46.596696 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 22:23:46.597721 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:23:46.604851 systemd[1]: Stopped target paths.target - Path Units. Nov 12 22:23:46.609337 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 22:23:46.613705 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:46.619899 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 22:23:46.621771 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 22:23:46.623780 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 22:23:46.623879 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:23:46.626187 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 22:23:46.626286 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:23:46.628484 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 22:23:46.628607 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 22:23:46.630827 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 22:23:46.630987 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 22:23:46.654116 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 22:23:46.657831 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 22:23:46.674086 systemd-networkd[1116]: eth0: DHCPv6 lease lost Nov 12 22:23:46.678906 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 22:23:46.681714 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 22:23:46.684184 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 22:23:46.690926 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 22:23:46.693112 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 22:23:46.700756 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 22:23:46.700993 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 22:23:46.704875 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 22:23:46.705878 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 22:23:46.720372 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 22:23:46.720492 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:46.724837 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 22:23:46.725068 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 22:23:46.741219 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 22:23:46.745106 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 22:23:46.745239 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:23:46.748417 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 22:23:46.748534 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:46.751203 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 22:23:46.751317 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:46.753980 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 22:23:46.754096 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:46.760233 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:46.798773 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 22:23:46.800937 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:46.804795 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 22:23:46.804941 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:46.810884 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 22:23:46.811005 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:46.819205 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 22:23:46.819321 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:23:46.822198 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 22:23:46.822314 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 22:23:46.833898 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:23:46.834056 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:46.857373 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 22:23:46.859886 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 22:23:46.860061 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:46.863083 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:23:46.863198 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:46.880656 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 22:23:46.880864 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 22:23:46.886530 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 22:23:46.886764 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 22:23:46.893067 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 22:23:46.914339 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 22:23:46.948462 systemd[1]: Switching root. Nov 12 22:23:46.986846 systemd-journald[252]: Journal stopped Nov 12 22:23:50.354675 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). Nov 12 22:23:50.354814 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 22:23:50.354859 kernel: SELinux: policy capability open_perms=1 Nov 12 22:23:50.354906 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 22:23:50.354938 kernel: SELinux: policy capability always_check_network=0 Nov 12 22:23:50.355008 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 22:23:50.355051 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 22:23:50.355084 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 22:23:50.355113 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 22:23:50.355148 kernel: audit: type=1403 audit(1731450228.421:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 22:23:50.355190 systemd[1]: Successfully loaded SELinux policy in 62.773ms. Nov 12 22:23:50.355243 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.186ms. Nov 12 22:23:50.355282 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) Nov 12 22:23:50.355318 systemd[1]: Detected virtualization amazon. Nov 12 22:23:50.355351 systemd[1]: Detected architecture arm64. Nov 12 22:23:50.355384 systemd[1]: Detected first boot. Nov 12 22:23:50.355413 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:23:50.355448 zram_generator::config[1419]: No configuration found. Nov 12 22:23:50.355487 systemd[1]: Populated /etc with preset unit settings. Nov 12 22:23:50.355534 systemd[1]: Queued start job for default target multi-user.target. Nov 12 22:23:50.355571 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 12 22:23:50.355603 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 22:23:50.355635 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 22:23:50.355667 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 22:23:50.355698 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 22:23:50.355733 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 22:23:50.355766 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 22:23:50.355805 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 22:23:50.355837 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 22:23:50.355868 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:50.355898 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:50.355930 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 22:23:50.360154 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 22:23:50.360227 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 22:23:50.360268 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:23:50.360478 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 12 22:23:50.360518 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:50.360548 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 22:23:50.360589 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:50.366304 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:23:50.366359 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:23:50.366389 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:23:50.366429 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 22:23:50.366461 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 22:23:50.366493 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:23:50.366523 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:23:50.366554 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:50.366586 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:50.366614 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:50.366643 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 22:23:50.366672 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 22:23:50.366705 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 22:23:50.366734 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 22:23:50.366763 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 22:23:50.366792 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 22:23:50.366825 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 22:23:50.366856 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 22:23:50.366888 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:50.366921 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:23:50.366951 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 22:23:50.375417 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:23:50.375461 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:23:50.375492 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:23:50.375542 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 22:23:50.375574 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:23:50.375605 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 22:23:50.375638 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Nov 12 22:23:50.375671 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Nov 12 22:23:50.375710 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:23:50.375743 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:23:50.375773 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 22:23:50.375804 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 22:23:50.375836 kernel: fuse: init (API version 7.39) Nov 12 22:23:50.375865 kernel: loop: module loaded Nov 12 22:23:50.375897 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:23:50.386265 systemd-journald[1523]: Collecting audit messages is disabled. Nov 12 22:23:50.386364 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 22:23:50.386400 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 22:23:50.386442 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 22:23:50.386472 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 22:23:50.386503 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 22:23:50.386533 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 22:23:50.386562 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:50.386591 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 22:23:50.386623 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 22:23:50.386658 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:23:50.386687 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:23:50.386716 systemd-journald[1523]: Journal started Nov 12 22:23:50.386768 systemd-journald[1523]: Runtime Journal (/run/log/journal/ec280b19b8d024259a1fbab7a4d937ca) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:23:50.393869 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:23:50.397534 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:23:50.397926 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:23:50.401387 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 22:23:50.401751 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 22:23:50.406507 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:23:50.406886 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:23:50.412137 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:50.418927 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 22:23:50.429141 kernel: ACPI: bus type drm_connector registered Nov 12 22:23:50.426649 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 22:23:50.439443 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:23:50.442041 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:23:50.446751 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 22:23:50.479866 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 22:23:50.491209 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 22:23:50.502376 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 22:23:50.505197 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 22:23:50.537336 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 22:23:50.548230 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 22:23:50.552057 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:23:50.566380 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 22:23:50.568702 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:23:50.584385 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:23:50.597318 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:23:50.609209 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 22:23:50.611951 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 22:23:50.619628 systemd-journald[1523]: Time spent on flushing to /var/log/journal/ec280b19b8d024259a1fbab7a4d937ca is 33.959ms for 878 entries. Nov 12 22:23:50.619628 systemd-journald[1523]: System Journal (/var/log/journal/ec280b19b8d024259a1fbab7a4d937ca) is 8.0M, max 195.6M, 187.6M free. Nov 12 22:23:50.678416 systemd-journald[1523]: Received client request to flush runtime journal. Nov 12 22:23:50.635694 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 22:23:50.641276 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 22:23:50.685848 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 22:23:50.712066 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:50.727789 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:50.746405 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 22:23:50.780396 udevadm[1587]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 12 22:23:50.782119 systemd-tmpfiles[1571]: ACLs are not supported, ignoring. Nov 12 22:23:50.782143 systemd-tmpfiles[1571]: ACLs are not supported, ignoring. Nov 12 22:23:50.794882 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:23:50.808366 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 22:23:50.863705 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 22:23:50.879355 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:23:50.913521 systemd-tmpfiles[1594]: ACLs are not supported, ignoring. Nov 12 22:23:50.914085 systemd-tmpfiles[1594]: ACLs are not supported, ignoring. Nov 12 22:23:50.925128 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:51.776838 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 22:23:51.791345 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:51.856021 systemd-udevd[1600]: Using default interface naming scheme 'v255'. Nov 12 22:23:51.962120 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:51.980354 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:23:52.030662 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 22:23:52.107589 (udev-worker)[1601]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:23:52.110022 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1616) Nov 12 22:23:52.115489 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1616) Nov 12 22:23:52.121296 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Nov 12 22:23:52.241189 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 22:23:52.405026 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1615) Nov 12 22:23:52.430622 systemd-networkd[1605]: lo: Link UP Nov 12 22:23:52.431094 systemd-networkd[1605]: lo: Gained carrier Nov 12 22:23:52.435822 systemd-networkd[1605]: Enumeration completed Nov 12 22:23:52.436248 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:23:52.441875 systemd-networkd[1605]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:52.441901 systemd-networkd[1605]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:23:52.445718 systemd-networkd[1605]: eth0: Link UP Nov 12 22:23:52.447291 systemd-networkd[1605]: eth0: Gained carrier Nov 12 22:23:52.447348 systemd-networkd[1605]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:52.449452 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 22:23:52.459156 systemd-networkd[1605]: eth0: DHCPv4 address 172.31.27.94/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:23:52.639401 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:52.699304 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 22:23:52.720201 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:23:52.767294 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 22:23:52.808100 lvm[1725]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:23:52.847181 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:52.853841 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 22:23:52.858915 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:52.872321 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 22:23:52.894098 lvm[1732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:23:52.938738 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 22:23:52.941884 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:23:52.945133 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). Nov 12 22:23:52.945382 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:23:52.947624 systemd[1]: Reached target machines.target - Containers. Nov 12 22:23:52.952062 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 22:23:52.965357 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 22:23:52.972785 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 22:23:52.975429 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:52.981472 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 22:23:52.996305 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 22:23:53.005330 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 22:23:53.010583 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 22:23:53.041572 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 22:23:53.061039 kernel: loop0: detected capacity change from 0 to 194512 Nov 12 22:23:53.074137 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 22:23:53.078028 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 22:23:53.103202 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 22:23:53.145555 kernel: loop1: detected capacity change from 0 to 116808 Nov 12 22:23:53.278026 kernel: loop2: detected capacity change from 0 to 113536 Nov 12 22:23:53.419085 kernel: loop3: detected capacity change from 0 to 53784 Nov 12 22:23:53.549021 kernel: loop4: detected capacity change from 0 to 194512 Nov 12 22:23:53.570050 kernel: loop5: detected capacity change from 0 to 116808 Nov 12 22:23:53.585392 kernel: loop6: detected capacity change from 0 to 113536 Nov 12 22:23:53.599042 kernel: loop7: detected capacity change from 0 to 53784 Nov 12 22:23:53.609773 (sd-merge)[1753]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Nov 12 22:23:53.611014 (sd-merge)[1753]: Merged extensions into '/usr'. Nov 12 22:23:53.620475 systemd[1]: Reloading requested from client PID 1740 ('systemd-sysext') (unit systemd-sysext.service)... Nov 12 22:23:53.621274 systemd[1]: Reloading... Nov 12 22:23:53.758140 zram_generator::config[1781]: No configuration found. Nov 12 22:23:53.883173 systemd-networkd[1605]: eth0: Gained IPv6LL Nov 12 22:23:54.063332 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. Nov 12 22:23:54.215230 systemd[1]: Reloading finished in 592 ms. Nov 12 22:23:54.248650 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 22:23:54.252210 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 22:23:54.267416 systemd[1]: Starting ensure-sysext.service... Nov 12 22:23:54.278374 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:23:54.298566 systemd[1]: Reloading requested from client PID 1840 ('systemctl') (unit ensure-sysext.service)... Nov 12 22:23:54.298598 systemd[1]: Reloading... Nov 12 22:23:54.351147 systemd-tmpfiles[1841]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 22:23:54.351956 systemd-tmpfiles[1841]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 22:23:54.354412 systemd-tmpfiles[1841]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 22:23:54.355204 systemd-tmpfiles[1841]: ACLs are not supported, ignoring. Nov 12 22:23:54.355389 systemd-tmpfiles[1841]: ACLs are not supported, ignoring. Nov 12 22:23:54.361938 systemd-tmpfiles[1841]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:23:54.361999 systemd-tmpfiles[1841]: Skipping /boot Nov 12 22:23:54.402517 systemd-tmpfiles[1841]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:23:54.402554 systemd-tmpfiles[1841]: Skipping /boot Nov 12 22:23:54.477996 zram_generator::config[1867]: No configuration found. Nov 12 22:23:54.764917 ldconfig[1736]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 22:23:54.790835 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. Nov 12 22:23:54.942915 systemd[1]: Reloading finished in 643 ms. Nov 12 22:23:54.971875 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 22:23:54.987301 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:55.007483 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:23:55.014710 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 22:23:55.025230 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 22:23:55.042381 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:23:55.053543 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 22:23:55.075221 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:55.086524 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:23:55.096040 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:23:55.113609 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:23:55.118203 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:55.142194 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:55.142657 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:55.155192 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 22:23:55.159228 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:23:55.159689 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:23:55.187621 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:23:55.189541 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:23:55.200655 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:23:55.202601 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:23:55.222866 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:55.241755 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:23:55.248197 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:55.248490 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:23:55.248751 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:23:55.249170 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 22:23:55.264472 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 22:23:55.276578 systemd[1]: Finished ensure-sysext.service. Nov 12 22:23:55.304311 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 22:23:55.310557 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:23:55.311949 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:23:55.329665 augenrules[1979]: No rules Nov 12 22:23:55.333081 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:23:55.333682 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:23:55.337216 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 22:23:55.343237 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). Nov 12 22:23:55.367866 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 22:23:55.407431 systemd-resolved[1936]: Positive Trust Anchors: Nov 12 22:23:55.407489 systemd-resolved[1936]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:23:55.407581 systemd-resolved[1936]: 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 Nov 12 22:23:55.415756 systemd-resolved[1936]: Defaulting to hostname 'linux'. Nov 12 22:23:55.419764 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:23:55.422355 systemd[1]: Reached target network.target - Network. Nov 12 22:23:55.424220 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 22:23:55.426558 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:55.428942 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:23:55.431285 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 22:23:55.433840 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 22:23:55.436644 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 22:23:55.439340 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 22:23:55.442582 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 22:23:55.445255 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 22:23:55.445325 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:23:55.447254 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:23:55.450637 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 22:23:55.456375 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:23:55.460814 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 22:23:55.466242 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:23:55.469175 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:23:55.471295 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:23:55.478453 systemd[1]: System is tainted: cgroupsv1 Nov 12 22:23:55.478578 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:23:55.478631 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:23:55.487182 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:23:55.501266 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 12 22:23:55.508906 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 22:23:55.527512 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 22:23:55.535328 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 22:23:55.537741 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). Nov 12 22:23:55.551671 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:23:55.569194 jq[1996]: false Nov 12 22:23:55.566206 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 22:23:55.595413 systemd[1]: Started ntpd.service - Network Time Service. Nov 12 22:23:55.605306 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 22:23:55.624157 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 12 22:23:55.631662 extend-filesystems[1997]: Found loop4 Nov 12 22:23:55.634235 extend-filesystems[1997]: Found loop5 Nov 12 22:23:55.634235 extend-filesystems[1997]: Found loop6 Nov 12 22:23:55.634235 extend-filesystems[1997]: Found loop7 Nov 12 22:23:55.634235 extend-filesystems[1997]: Found nvme0n1 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p1 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p2 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p3 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found usr Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p4 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p6 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p7 Nov 12 22:23:55.653691 extend-filesystems[1997]: Found nvme0n1p9 Nov 12 22:23:55.653691 extend-filesystems[1997]: Checking size of /dev/nvme0n1p9 Nov 12 22:23:55.657830 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 22:23:55.688269 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 22:23:55.705489 dbus-daemon[1995]: [system] SELinux support is enabled Nov 12 22:23:55.714425 dbus-daemon[1995]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1605 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 12 22:23:55.707799 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 22:23:55.716943 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 22:23:55.763009 extend-filesystems[1997]: Resized partition /dev/nvme0n1p9 Nov 12 22:23:55.764661 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 22:23:55.770489 extend-filesystems[2022]: resize2fs 1.47.1 (20-May-2024) Nov 12 22:23:55.777994 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Nov 12 22:23:55.780389 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 22:23:55.784216 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 22:23:55.802666 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 22:23:55.803267 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 22:23:55.829527 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 22:23:55.830112 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 22:23:55.845289 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). Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: ---------------------------------------------------- Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: corporation. Support and training for ntp-4 are Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: available at https://www.nwtime.org/support Nov 12 22:23:55.898736 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: ---------------------------------------------------- Nov 12 22:23:55.849308 dbus-daemon[1995]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 12 22:23:55.845357 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 22:23:55.919778 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: proto: precision = 0.096 usec (-23) Nov 12 22:23:55.919778 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: basedate set to 2024-10-31 Nov 12 22:23:55.919778 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: gps base set to 2024-11-03 (week 2339) Nov 12 22:23:55.875718 ntpd[2002]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:23:55.849250 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). Nov 12 22:23:55.948055 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:23:55.948055 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:23:55.875765 ntpd[2002]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:23:55.948256 jq[2025]: true Nov 12 22:23:55.849288 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 22:23:55.875785 ntpd[2002]: ---------------------------------------------------- Nov 12 22:23:55.876646 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 12 22:23:55.875804 ntpd[2002]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:23:55.949610 (ntainerd)[2050]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:23:55.875823 ntpd[2002]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:23:55.875841 ntpd[2002]: corporation. Support and training for ntp-4 are Nov 12 22:23:55.875863 ntpd[2002]: available at https://www.nwtime.org/support Nov 12 22:23:55.976639 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:23:55.976639 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen normally on 3 eth0 172.31.27.94:123 Nov 12 22:23:55.976639 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen normally on 4 lo [::1]:123 Nov 12 22:23:55.976639 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listen normally on 5 eth0 [fe80::44e:7fff:fe99:ffd%2]:123 Nov 12 22:23:55.976639 ntpd[2002]: 12 Nov 22:23:55 ntpd[2002]: Listening on routing socket on fd #22 for interface updates Nov 12 22:23:55.875881 ntpd[2002]: ---------------------------------------------------- Nov 12 22:23:55.908806 ntpd[2002]: proto: precision = 0.096 usec (-23) Nov 12 22:23:55.922243 ntpd[2002]: basedate set to 2024-10-31 Nov 12 22:23:55.980164 update_engine[2017]: I20241112 22:23:55.979395 2017 main.cc:92] Flatcar Update Engine starting Nov 12 22:23:55.925114 ntpd[2002]: gps base set to 2024-11-03 (week 2339) Nov 12 22:23:55.945567 ntpd[2002]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:23:55.946431 ntpd[2002]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:23:55.969820 ntpd[2002]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:23:55.969919 ntpd[2002]: Listen normally on 3 eth0 172.31.27.94:123 Nov 12 22:23:55.970033 ntpd[2002]: Listen normally on 4 lo [::1]:123 Nov 12 22:23:55.970123 ntpd[2002]: Listen normally on 5 eth0 [fe80::44e:7fff:fe99:ffd%2]:123 Nov 12 22:23:55.970197 ntpd[2002]: Listening on routing socket on fd #22 for interface updates Nov 12 22:23:56.016013 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Nov 12 22:23:56.003728 systemd[1]: Started update-engine.service - Update Engine. Nov 12 22:23:56.106200 update_engine[2017]: I20241112 22:23:56.003834 2017 update_check_scheduler.cc:74] Next update check in 4m27s Nov 12 22:23:56.106479 ntpd[2002]: 12 Nov 22:23:56 ntpd[2002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:56.106479 ntpd[2002]: 12 Nov 22:23:56 ntpd[2002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:56.106703 jq[2055]: true Nov 12 22:23:56.014303 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 22:23:56.020148 ntpd[2002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:56.144208 extend-filesystems[2022]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 12 22:23:56.144208 extend-filesystems[2022]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 12 22:23:56.144208 extend-filesystems[2022]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Nov 12 22:23:56.023451 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 22:23:56.020210 ntpd[2002]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:56.183672 extend-filesystems[1997]: Resized filesystem in /dev/nvme0n1p9 Nov 12 22:23:56.049146 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 22:23:56.049742 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 22:23:56.092764 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 22:23:56.113777 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 22:23:56.114391 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 22:23:56.137135 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 12 22:23:56.152278 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 12 22:23:56.290675 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (2058) Nov 12 22:23:56.298918 coreos-metadata[1993]: Nov 12 22:23:56.298 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:23:56.304466 coreos-metadata[1993]: Nov 12 22:23:56.304 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 12 22:23:56.310084 coreos-metadata[1993]: Nov 12 22:23:56.307 INFO Fetch successful Nov 12 22:23:56.310084 coreos-metadata[1993]: Nov 12 22:23:56.308 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 12 22:23:56.310084 coreos-metadata[1993]: Nov 12 22:23:56.309 INFO Fetch successful Nov 12 22:23:56.310084 coreos-metadata[1993]: Nov 12 22:23:56.309 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 12 22:23:56.316508 coreos-metadata[1993]: Nov 12 22:23:56.310 INFO Fetch successful Nov 12 22:23:56.316508 coreos-metadata[1993]: Nov 12 22:23:56.310 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 12 22:23:56.316508 coreos-metadata[1993]: Nov 12 22:23:56.315 INFO Fetch successful Nov 12 22:23:56.316508 coreos-metadata[1993]: Nov 12 22:23:56.315 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 12 22:23:56.320191 coreos-metadata[1993]: Nov 12 22:23:56.319 INFO Fetch failed with 404: resource not found Nov 12 22:23:56.320191 coreos-metadata[1993]: Nov 12 22:23:56.319 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 12 22:23:56.324058 coreos-metadata[1993]: Nov 12 22:23:56.323 INFO Fetch successful Nov 12 22:23:56.324058 coreos-metadata[1993]: Nov 12 22:23:56.323 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 12 22:23:56.327056 coreos-metadata[1993]: Nov 12 22:23:56.326 INFO Fetch successful Nov 12 22:23:56.327056 coreos-metadata[1993]: Nov 12 22:23:56.327 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 12 22:23:56.338830 coreos-metadata[1993]: Nov 12 22:23:56.334 INFO Fetch successful Nov 12 22:23:56.338830 coreos-metadata[1993]: Nov 12 22:23:56.334 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 12 22:23:56.341560 coreos-metadata[1993]: Nov 12 22:23:56.341 INFO Fetch successful Nov 12 22:23:56.341560 coreos-metadata[1993]: Nov 12 22:23:56.341 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 12 22:23:56.345769 coreos-metadata[1993]: Nov 12 22:23:56.344 INFO Fetch successful Nov 12 22:23:56.399019 bash[2122]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:23:56.406000 amazon-ssm-agent[2086]: Initializing new seelog logger Nov 12 22:23:56.408298 amazon-ssm-agent[2086]: New Seelog Logger Creation Complete Nov 12 22:23:56.408469 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.408469 amazon-ssm-agent[2086]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 processing appconfig overrides Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 processing appconfig overrides Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 processing appconfig overrides Nov 12 22:23:56.417234 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO Proxy environment variables: Nov 12 22:23:56.409295 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 22:23:56.443203 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.443203 amazon-ssm-agent[2086]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:56.443203 amazon-ssm-agent[2086]: 2024/11/12 22:23:56 processing appconfig overrides Nov 12 22:23:56.457561 systemd[1]: Starting sshkeys.service... Nov 12 22:23:56.510524 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 12 22:23:56.539069 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO https_proxy: Nov 12 22:23:56.551722 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 12 22:23:56.555731 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 12 22:23:56.562525 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 22:23:56.633992 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO http_proxy: Nov 12 22:23:56.700279 systemd-logind[2014]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 22:23:56.701105 systemd-logind[2014]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 12 22:23:56.708838 systemd-logind[2014]: New seat seat0. Nov 12 22:23:56.726782 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 22:23:56.735732 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO no_proxy: Nov 12 22:23:56.836485 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO Checking if agent identity type OnPrem can be assumed Nov 12 22:23:56.863005 containerd[2050]: time="2024-11-12T22:23:56.861905619Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:23:56.887332 locksmithd[2068]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 22:23:56.938552 coreos-metadata[2166]: Nov 12 22:23:56.938 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:23:56.943571 coreos-metadata[2166]: Nov 12 22:23:56.940 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 12 22:23:56.943698 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO Checking if agent identity type EC2 can be assumed Nov 12 22:23:56.944776 coreos-metadata[2166]: Nov 12 22:23:56.944 INFO Fetch successful Nov 12 22:23:56.946527 coreos-metadata[2166]: Nov 12 22:23:56.944 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 12 22:23:56.947647 coreos-metadata[2166]: Nov 12 22:23:56.947 INFO Fetch successful Nov 12 22:23:56.954834 unknown[2166]: wrote ssh authorized keys file for user: core Nov 12 22:23:57.043000 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO Agent will take identity from EC2 Nov 12 22:23:57.082666 update-ssh-keys[2217]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:23:57.090600 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 12 22:23:57.102898 systemd[1]: Finished sshkeys.service. Nov 12 22:23:57.123165 dbus-daemon[1995]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 12 22:23:57.133783 dbus-daemon[1995]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=2043 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 12 22:23:57.123411 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 12 22:23:57.144290 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:57.144607 containerd[2050]: time="2024-11-12T22:23:57.144536244Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.154601 systemd[1]: Starting polkit.service - Authorization Manager... Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.160895676Z" 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.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.160987104Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161051460Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161351868Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161385720Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161508672Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161542752Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161903076Z" 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 Nov 12 22:23:57.162507 containerd[2050]: time="2024-11-12T22:23:57.161935440Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.164113668Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.164175060Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.164414472Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.164873124Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.165250692Z" 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 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.165290340Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.165505512Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:23:57.168995 containerd[2050]: time="2024-11-12T22:23:57.165604704Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.190862280Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.191101392Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.191235840Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.191304804Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.191367960Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:23:57.191993 containerd[2050]: time="2024-11-12T22:23:57.191858256Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:23:57.193648 containerd[2050]: time="2024-11-12T22:23:57.193574592Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:23:57.194859 containerd[2050]: time="2024-11-12T22:23:57.193878972Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:23:57.198380 containerd[2050]: time="2024-11-12T22:23:57.193944996Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:23:57.198380 containerd[2050]: time="2024-11-12T22:23:57.198312012Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:23:57.198540 containerd[2050]: time="2024-11-12T22:23:57.198398640Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198540 containerd[2050]: time="2024-11-12T22:23:57.198438120Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198540 containerd[2050]: time="2024-11-12T22:23:57.198492576Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198540 containerd[2050]: time="2024-11-12T22:23:57.198528792Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198741 containerd[2050]: time="2024-11-12T22:23:57.198588276Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198741 containerd[2050]: time="2024-11-12T22:23:57.198623352Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198741 containerd[2050]: time="2024-11-12T22:23:57.198678264Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198741 containerd[2050]: time="2024-11-12T22:23:57.198708072Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:23:57.198911 containerd[2050]: time="2024-11-12T22:23:57.198778608Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.198911 containerd[2050]: time="2024-11-12T22:23:57.198837240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.198911 containerd[2050]: time="2024-11-12T22:23:57.198874020Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200049372Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200190816Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200234844Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200295504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200376228Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.200414028Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.203149 containerd[2050]: time="2024-11-12T22:23:57.202191408Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.204876 containerd[2050]: time="2024-11-12T22:23:57.204312084Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.204876 containerd[2050]: time="2024-11-12T22:23:57.204428820Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.204876 containerd[2050]: time="2024-11-12T22:23:57.204494736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.204876 containerd[2050]: time="2024-11-12T22:23:57.204568752Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:23:57.204876 containerd[2050]: time="2024-11-12T22:23:57.204652164Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.205399 containerd[2050]: time="2024-11-12T22:23:57.204695844Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.205399 containerd[2050]: time="2024-11-12T22:23:57.205377420Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.205617984Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207272004Z" 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 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207312816Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207373320Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207402900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207468036Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207496440Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:23:57.210099 containerd[2050]: time="2024-11-12T22:23:57.207566244Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:23:57.210590 containerd[2050]: time="2024-11-12T22:23:57.210021360Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 12 22:23:57.215846 containerd[2050]: time="2024-11-12T22:23:57.210890712Z" level=info msg="Connect containerd service" Nov 12 22:23:57.215846 containerd[2050]: time="2024-11-12T22:23:57.213228660Z" level=info msg="using legacy CRI server" Nov 12 22:23:57.215846 containerd[2050]: time="2024-11-12T22:23:57.213262848Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:23:57.215846 containerd[2050]: time="2024-11-12T22:23:57.213743124Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:23:57.216753 containerd[2050]: time="2024-11-12T22:23:57.216261480Z" 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" Nov 12 22:23:57.219431 polkitd[2225]: Started polkitd version 121 Nov 12 22:23:57.225908 containerd[2050]: time="2024-11-12T22:23:57.225835921Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:23:57.226099 containerd[2050]: time="2024-11-12T22:23:57.226031809Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229101541Z" level=info msg="Start subscribing containerd event" Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229243417Z" level=info msg="Start recovering state" Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229429693Z" level=info msg="Start event monitor" Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229465285Z" level=info msg="Start snapshots syncer" Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229498189Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:23:57.231864 containerd[2050]: time="2024-11-12T22:23:57.229529161Z" level=info msg="Start streaming server" Nov 12 22:23:57.229945 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:23:57.239417 containerd[2050]: time="2024-11-12T22:23:57.235362013Z" level=info msg="containerd successfully booted in 0.376761s" Nov 12 22:23:57.251201 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:57.257683 polkitd[2225]: Loading rules from directory /etc/polkit-1/rules.d Nov 12 22:23:57.257825 polkitd[2225]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 12 22:23:57.261059 polkitd[2225]: Finished loading, compiling and executing 2 rules Nov 12 22:23:57.265070 dbus-daemon[1995]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 12 22:23:57.265360 systemd[1]: Started polkit.service - Authorization Manager. Nov 12 22:23:57.269101 polkitd[2225]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 12 22:23:57.318443 systemd-hostnamed[2043]: Hostname set to (transient) Nov 12 22:23:57.318477 systemd-resolved[1936]: System hostname changed to 'ip-172-31-27-94'. Nov 12 22:23:57.350406 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:57.452982 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 12 22:23:57.552332 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 12 22:23:57.651841 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] Starting Core Agent Nov 12 22:23:57.678997 sshd_keygen[2057]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 22:23:57.752013 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 12 22:23:57.752954 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 22:23:57.770550 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 22:23:57.802824 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 22:23:57.803441 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 22:23:57.809383 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [Registrar] Starting registrar module Nov 12 22:23:57.810113 amazon-ssm-agent[2086]: 2024-11-12 22:23:56 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 12 22:23:57.810220 amazon-ssm-agent[2086]: 2024-11-12 22:23:57 INFO [EC2Identity] EC2 registration was successful. Nov 12 22:23:57.810220 amazon-ssm-agent[2086]: 2024-11-12 22:23:57 INFO [CredentialRefresher] credentialRefresher has started Nov 12 22:23:57.810220 amazon-ssm-agent[2086]: 2024-11-12 22:23:57 INFO [CredentialRefresher] Starting credentials refresher loop Nov 12 22:23:57.810342 amazon-ssm-agent[2086]: 2024-11-12 22:23:57 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 12 22:23:57.816922 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 22:23:57.852610 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 22:23:57.858590 amazon-ssm-agent[2086]: 2024-11-12 22:23:57 INFO [CredentialRefresher] Next credential rotation will be in 31.533296411633334 minutes Nov 12 22:23:57.874452 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 22:23:57.884697 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 12 22:23:57.889438 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 22:23:58.106409 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:23:58.111136 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 22:23:58.119326 systemd[1]: Startup finished in 10.765s (kernel) + 9.759s (userspace) = 20.524s. Nov 12 22:23:58.124866 (kubelet)[2280]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 12 22:23:58.840686 amazon-ssm-agent[2086]: 2024-11-12 22:23:58 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 12 22:23:58.941137 amazon-ssm-agent[2086]: 2024-11-12 22:23:58 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2292) started Nov 12 22:23:58.968028 kubelet[2280]: E1112 22:23:58.967237 2280 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 12 22:23:58.976392 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 12 22:23:58.979450 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 12 22:23:59.041875 amazon-ssm-agent[2086]: 2024-11-12 22:23:58 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 12 22:24:02.781591 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 22:24:02.792483 systemd[1]: Started sshd@0-172.31.27.94:22-147.75.109.163:35832.service - OpenSSH per-connection server daemon (147.75.109.163:35832). Nov 12 22:24:02.456594 systemd-resolved[1936]: Clock change detected. Flushing caches. Nov 12 22:24:02.465252 systemd-journald[1523]: Time jumped backwards, rotating. Nov 12 22:24:02.570220 sshd[2305]: Accepted publickey for core from 147.75.109.163 port 35832 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:02.573905 sshd-session[2305]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:02.592768 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 22:24:02.599797 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 22:24:02.605427 systemd-logind[2014]: New session 1 of user core. Nov 12 22:24:02.635577 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 22:24:02.649800 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 22:24:02.657608 (systemd)[2312]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 22:24:02.861372 systemd[2312]: Queued start job for default target default.target. Nov 12 22:24:02.862081 systemd[2312]: Created slice app.slice - User Application Slice. Nov 12 22:24:02.862135 systemd[2312]: Reached target paths.target - Paths. Nov 12 22:24:02.862166 systemd[2312]: Reached target timers.target - Timers. Nov 12 22:24:02.868438 systemd[2312]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 22:24:02.893865 systemd[2312]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 22:24:02.893992 systemd[2312]: Reached target sockets.target - Sockets. Nov 12 22:24:02.894024 systemd[2312]: Reached target basic.target - Basic System. Nov 12 22:24:02.894119 systemd[2312]: Reached target default.target - Main User Target. Nov 12 22:24:02.894179 systemd[2312]: Startup finished in 225ms. Nov 12 22:24:02.894525 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 22:24:02.902820 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 22:24:03.054156 systemd[1]: Started sshd@1-172.31.27.94:22-147.75.109.163:35834.service - OpenSSH per-connection server daemon (147.75.109.163:35834). Nov 12 22:24:03.240656 sshd[2324]: Accepted publickey for core from 147.75.109.163 port 35834 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:03.243113 sshd-session[2324]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:03.250888 systemd-logind[2014]: New session 2 of user core. Nov 12 22:24:03.265760 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 22:24:03.393811 sshd[2327]: Connection closed by 147.75.109.163 port 35834 Nov 12 22:24:03.396603 sshd-session[2324]: pam_unix(sshd:session): session closed for user core Nov 12 22:24:03.404378 systemd[1]: sshd@1-172.31.27.94:22-147.75.109.163:35834.service: Deactivated successfully. Nov 12 22:24:03.404779 systemd-logind[2014]: Session 2 logged out. Waiting for processes to exit. Nov 12 22:24:03.411376 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 22:24:03.413651 systemd-logind[2014]: Removed session 2. Nov 12 22:24:03.434813 systemd[1]: Started sshd@2-172.31.27.94:22-147.75.109.163:35840.service - OpenSSH per-connection server daemon (147.75.109.163:35840). Nov 12 22:24:03.617582 sshd[2332]: Accepted publickey for core from 147.75.109.163 port 35840 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:03.620469 sshd-session[2332]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:03.628839 systemd-logind[2014]: New session 3 of user core. Nov 12 22:24:03.638842 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 22:24:03.761354 sshd[2335]: Connection closed by 147.75.109.163 port 35840 Nov 12 22:24:03.762106 sshd-session[2332]: pam_unix(sshd:session): session closed for user core Nov 12 22:24:03.768063 systemd[1]: sshd@2-172.31.27.94:22-147.75.109.163:35840.service: Deactivated successfully. Nov 12 22:24:03.774597 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 22:24:03.776204 systemd-logind[2014]: Session 3 logged out. Waiting for processes to exit. Nov 12 22:24:03.777860 systemd-logind[2014]: Removed session 3. Nov 12 22:24:03.789803 systemd[1]: Started sshd@3-172.31.27.94:22-147.75.109.163:35850.service - OpenSSH per-connection server daemon (147.75.109.163:35850). Nov 12 22:24:03.973842 sshd[2340]: Accepted publickey for core from 147.75.109.163 port 35850 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:03.976236 sshd-session[2340]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:03.983385 systemd-logind[2014]: New session 4 of user core. Nov 12 22:24:03.991878 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 22:24:04.120333 sshd[2343]: Connection closed by 147.75.109.163 port 35850 Nov 12 22:24:04.120607 sshd-session[2340]: pam_unix(sshd:session): session closed for user core Nov 12 22:24:04.125060 systemd[1]: sshd@3-172.31.27.94:22-147.75.109.163:35850.service: Deactivated successfully. Nov 12 22:24:04.130197 systemd-logind[2014]: Session 4 logged out. Waiting for processes to exit. Nov 12 22:24:04.134199 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 22:24:04.136228 systemd-logind[2014]: Removed session 4. Nov 12 22:24:04.151743 systemd[1]: Started sshd@4-172.31.27.94:22-147.75.109.163:35862.service - OpenSSH per-connection server daemon (147.75.109.163:35862). Nov 12 22:24:04.328027 sshd[2348]: Accepted publickey for core from 147.75.109.163 port 35862 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:04.330457 sshd-session[2348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:04.338024 systemd-logind[2014]: New session 5 of user core. Nov 12 22:24:04.345902 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 22:24:04.493228 sudo[2352]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 12 22:24:04.493925 sudo[2352]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:24:05.757935 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:05.773807 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:24:05.829053 systemd[1]: Reloading requested from client PID 2391 ('systemctl') (unit session-5.scope)... Nov 12 22:24:05.829080 systemd[1]: Reloading... Nov 12 22:24:06.014322 zram_generator::config[2437]: No configuration found. Nov 12 22:24:06.263453 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. Nov 12 22:24:06.427357 systemd[1]: Reloading finished in 597 ms. Nov 12 22:24:06.534253 systemd[1]: kubelet.service: Deactivated successfully. Nov 12 22:24:06.534898 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:06.546336 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:24:07.096600 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:07.110929 (kubelet)[2507]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 12 22:24:07.189829 kubelet[2507]: 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. Nov 12 22:24:07.190648 kubelet[2507]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Nov 12 22:24:07.190648 kubelet[2507]: 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. Nov 12 22:24:07.200312 kubelet[2507]: I1112 22:24:07.199308 2507 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 12 22:24:08.092827 kubelet[2507]: I1112 22:24:08.092786 2507 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Nov 12 22:24:08.093023 kubelet[2507]: I1112 22:24:08.093002 2507 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 12 22:24:08.093634 kubelet[2507]: I1112 22:24:08.093556 2507 server.go:919] "Client rotation is on, will bootstrap in background" Nov 12 22:24:08.128825 kubelet[2507]: I1112 22:24:08.128757 2507 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 12 22:24:08.143918 kubelet[2507]: I1112 22:24:08.143850 2507 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 12 22:24:08.146754 kubelet[2507]: I1112 22:24:08.146682 2507 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 12 22:24:08.147329 kubelet[2507]: I1112 22:24:08.147246 2507 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Nov 12 22:24:08.147329 kubelet[2507]: I1112 22:24:08.147328 2507 topology_manager.go:138] "Creating topology manager with none policy" Nov 12 22:24:08.147616 kubelet[2507]: I1112 22:24:08.147351 2507 container_manager_linux.go:301] "Creating device plugin manager" Nov 12 22:24:08.147616 kubelet[2507]: I1112 22:24:08.147555 2507 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:24:08.152882 kubelet[2507]: I1112 22:24:08.152819 2507 kubelet.go:396] "Attempting to sync node with API server" Nov 12 22:24:08.153482 kubelet[2507]: I1112 22:24:08.153442 2507 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 12 22:24:08.154892 kubelet[2507]: I1112 22:24:08.154669 2507 kubelet.go:312] "Adding apiserver pod source" Nov 12 22:24:08.154892 kubelet[2507]: I1112 22:24:08.154739 2507 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 12 22:24:08.154892 kubelet[2507]: E1112 22:24:08.154752 2507 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:08.154892 kubelet[2507]: E1112 22:24:08.154844 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:08.157549 kubelet[2507]: I1112 22:24:08.157515 2507 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 12 22:24:08.159316 kubelet[2507]: I1112 22:24:08.158362 2507 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 12 22:24:08.159316 kubelet[2507]: W1112 22:24:08.158464 2507 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 12 22:24:08.159846 kubelet[2507]: I1112 22:24:08.159819 2507 server.go:1256] "Started kubelet" Nov 12 22:24:08.160159 kubelet[2507]: I1112 22:24:08.160107 2507 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Nov 12 22:24:08.160616 kubelet[2507]: I1112 22:24:08.160579 2507 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 12 22:24:08.161310 kubelet[2507]: I1112 22:24:08.161247 2507 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 12 22:24:08.161630 kubelet[2507]: I1112 22:24:08.161584 2507 server.go:461] "Adding debug handlers to kubelet server" Nov 12 22:24:08.169979 kubelet[2507]: I1112 22:24:08.169922 2507 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 12 22:24:08.182544 kubelet[2507]: E1112 22:24:08.182492 2507 event.go:346] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.27.94.180758d3bbd36224 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.27.94,UID:172.31.27.94,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.27.94,},FirstTimestamp:2024-11-12 22:24:08.159781412 +0000 UTC m=+1.042098894,LastTimestamp:2024-11-12 22:24:08.159781412 +0000 UTC m=+1.042098894,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.27.94,}" Nov 12 22:24:08.189048 kubelet[2507]: I1112 22:24:08.188995 2507 volume_manager.go:291] "Starting Kubelet Volume Manager" Nov 12 22:24:08.189975 kubelet[2507]: I1112 22:24:08.189927 2507 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Nov 12 22:24:08.190826 kubelet[2507]: I1112 22:24:08.190590 2507 reconciler_new.go:29] "Reconciler: start to sync state" Nov 12 22:24:08.192934 kubelet[2507]: I1112 22:24:08.192799 2507 factory.go:221] Registration of the systemd container factory successfully Nov 12 22:24:08.193333 kubelet[2507]: I1112 22:24:08.193293 2507 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 12 22:24:08.195653 kubelet[2507]: E1112 22:24:08.195505 2507 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 12 22:24:08.196621 kubelet[2507]: I1112 22:24:08.196570 2507 factory.go:221] Registration of the containerd container factory successfully Nov 12 22:24:08.238627 kubelet[2507]: E1112 22:24:08.238325 2507 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.27.94\" not found" node="172.31.27.94" Nov 12 22:24:08.250959 kubelet[2507]: I1112 22:24:08.250920 2507 cpu_manager.go:214] "Starting CPU manager" policy="none" Nov 12 22:24:08.251338 kubelet[2507]: I1112 22:24:08.251113 2507 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Nov 12 22:24:08.251338 kubelet[2507]: I1112 22:24:08.251153 2507 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:24:08.281007 kubelet[2507]: I1112 22:24:08.280951 2507 policy_none.go:49] "None policy: Start" Nov 12 22:24:08.282754 kubelet[2507]: I1112 22:24:08.282717 2507 memory_manager.go:170] "Starting memorymanager" policy="None" Nov 12 22:24:08.283395 kubelet[2507]: I1112 22:24:08.283127 2507 state_mem.go:35] "Initializing new in-memory state store" Nov 12 22:24:08.292036 kubelet[2507]: I1112 22:24:08.290722 2507 kubelet_node_status.go:73] "Attempting to register node" node="172.31.27.94" Nov 12 22:24:08.300512 kubelet[2507]: I1112 22:24:08.300343 2507 kubelet_node_status.go:76] "Successfully registered node" node="172.31.27.94" Nov 12 22:24:08.311387 kubelet[2507]: I1112 22:24:08.311211 2507 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 12 22:24:08.323428 kubelet[2507]: I1112 22:24:08.323378 2507 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 12 22:24:08.354554 kubelet[2507]: I1112 22:24:08.353973 2507 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 12 22:24:08.357477 containerd[2050]: time="2024-11-12T22:24:08.357250485Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 12 22:24:08.360702 kubelet[2507]: I1112 22:24:08.359496 2507 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 12 22:24:08.368682 kubelet[2507]: I1112 22:24:08.368630 2507 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 12 22:24:08.372079 kubelet[2507]: I1112 22:24:08.371611 2507 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 12 22:24:08.372079 kubelet[2507]: I1112 22:24:08.371673 2507 status_manager.go:217] "Starting to sync pod status with apiserver" Nov 12 22:24:08.372079 kubelet[2507]: I1112 22:24:08.371725 2507 kubelet.go:2329] "Starting kubelet main sync loop" Nov 12 22:24:08.372079 kubelet[2507]: E1112 22:24:08.371811 2507 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 12 22:24:09.097659 kubelet[2507]: I1112 22:24:09.097087 2507 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 12 22:24:09.097659 kubelet[2507]: W1112 22:24:09.097389 2507 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.Service ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Nov 12 22:24:09.097659 kubelet[2507]: W1112 22:24:09.097582 2507 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Nov 12 22:24:09.097659 kubelet[2507]: W1112 22:24:09.097657 2507 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Nov 12 22:24:09.156058 kubelet[2507]: I1112 22:24:09.155959 2507 apiserver.go:52] "Watching apiserver" Nov 12 22:24:09.156349 kubelet[2507]: E1112 22:24:09.156292 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:09.162430 kubelet[2507]: I1112 22:24:09.162221 2507 topology_manager.go:215] "Topology Admit Handler" podUID="64449420-0349-4965-a777-50cdf3b04a5d" podNamespace="kube-flannel" podName="kube-flannel-ds-hrxkw" Nov 12 22:24:09.162430 kubelet[2507]: I1112 22:24:09.162401 2507 topology_manager.go:215] "Topology Admit Handler" podUID="6dee30aa-00a4-4564-b763-958daff1e4fe" podNamespace="kube-system" podName="kube-proxy-j8mmn" Nov 12 22:24:09.191057 kubelet[2507]: I1112 22:24:09.190991 2507 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Nov 12 22:24:09.196403 kubelet[2507]: I1112 22:24:09.196332 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/64449420-0349-4965-a777-50cdf3b04a5d-run\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196563 kubelet[2507]: I1112 22:24:09.196435 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/64449420-0349-4965-a777-50cdf3b04a5d-cni\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196563 kubelet[2507]: I1112 22:24:09.196484 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/6dee30aa-00a4-4564-b763-958daff1e4fe-kube-proxy\") pod \"kube-proxy-j8mmn\" (UID: \"6dee30aa-00a4-4564-b763-958daff1e4fe\") " pod="kube-system/kube-proxy-j8mmn" Nov 12 22:24:09.196563 kubelet[2507]: I1112 22:24:09.196528 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/6dee30aa-00a4-4564-b763-958daff1e4fe-lib-modules\") pod \"kube-proxy-j8mmn\" (UID: \"6dee30aa-00a4-4564-b763-958daff1e4fe\") " pod="kube-system/kube-proxy-j8mmn" Nov 12 22:24:09.196715 kubelet[2507]: I1112 22:24:09.196579 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h8t2z\" (UniqueName: \"kubernetes.io/projected/6dee30aa-00a4-4564-b763-958daff1e4fe-kube-api-access-h8t2z\") pod \"kube-proxy-j8mmn\" (UID: \"6dee30aa-00a4-4564-b763-958daff1e4fe\") " pod="kube-system/kube-proxy-j8mmn" Nov 12 22:24:09.196715 kubelet[2507]: I1112 22:24:09.196625 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/64449420-0349-4965-a777-50cdf3b04a5d-cni-plugin\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196715 kubelet[2507]: I1112 22:24:09.196668 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/64449420-0349-4965-a777-50cdf3b04a5d-flannel-cfg\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196715 kubelet[2507]: I1112 22:24:09.196715 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/64449420-0349-4965-a777-50cdf3b04a5d-xtables-lock\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196923 kubelet[2507]: I1112 22:24:09.196778 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rgklj\" (UniqueName: \"kubernetes.io/projected/64449420-0349-4965-a777-50cdf3b04a5d-kube-api-access-rgklj\") pod \"kube-flannel-ds-hrxkw\" (UID: \"64449420-0349-4965-a777-50cdf3b04a5d\") " pod="kube-flannel/kube-flannel-ds-hrxkw" Nov 12 22:24:09.196923 kubelet[2507]: I1112 22:24:09.196834 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6dee30aa-00a4-4564-b763-958daff1e4fe-xtables-lock\") pod \"kube-proxy-j8mmn\" (UID: \"6dee30aa-00a4-4564-b763-958daff1e4fe\") " pod="kube-system/kube-proxy-j8mmn" Nov 12 22:24:09.477306 containerd[2050]: time="2024-11-12T22:24:09.477205522Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hrxkw,Uid:64449420-0349-4965-a777-50cdf3b04a5d,Namespace:kube-flannel,Attempt:0,}" Nov 12 22:24:09.482843 containerd[2050]: time="2024-11-12T22:24:09.482199322Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-j8mmn,Uid:6dee30aa-00a4-4564-b763-958daff1e4fe,Namespace:kube-system,Attempt:0,}" Nov 12 22:24:09.633746 sudo[2352]: pam_unix(sudo:session): session closed for user root Nov 12 22:24:09.656153 sshd[2351]: Connection closed by 147.75.109.163 port 35862 Nov 12 22:24:09.657143 sshd-session[2348]: pam_unix(sshd:session): session closed for user core Nov 12 22:24:09.664838 systemd[1]: sshd@4-172.31.27.94:22-147.75.109.163:35862.service: Deactivated successfully. Nov 12 22:24:09.675034 systemd-logind[2014]: Session 5 logged out. Waiting for processes to exit. Nov 12 22:24:09.675207 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 22:24:09.682209 systemd-logind[2014]: Removed session 5. Nov 12 22:24:10.091615 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3988403526.mount: Deactivated successfully. Nov 12 22:24:10.109364 containerd[2050]: time="2024-11-12T22:24:10.108675814Z" 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\"}" Nov 12 22:24:10.113343 containerd[2050]: time="2024-11-12T22:24:10.112917430Z" 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\"}" Nov 12 22:24:10.114830 containerd[2050]: time="2024-11-12T22:24:10.114745318Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 12 22:24:10.117365 containerd[2050]: time="2024-11-12T22:24:10.117252958Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:24:10.118217 containerd[2050]: time="2024-11-12T22:24:10.118118074Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 12 22:24:10.122586 containerd[2050]: time="2024-11-12T22:24:10.122503642Z" 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\"}" Nov 12 22:24:10.127942 containerd[2050]: time="2024-11-12T22:24:10.127458178Z" 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 645.079852ms" Nov 12 22:24:10.130737 containerd[2050]: time="2024-11-12T22:24:10.130621714Z" 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 653.255164ms" Nov 12 22:24:10.157887 kubelet[2507]: E1112 22:24:10.157808 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:10.529320 containerd[2050]: time="2024-11-12T22:24:10.528744888Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:10.529320 containerd[2050]: time="2024-11-12T22:24:10.528885072Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:10.529320 containerd[2050]: time="2024-11-12T22:24:10.528924168Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:10.529320 containerd[2050]: time="2024-11-12T22:24:10.529089264Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:10.536627 containerd[2050]: time="2024-11-12T22:24:10.533412120Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:10.538465 containerd[2050]: time="2024-11-12T22:24:10.536665464Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:10.538465 containerd[2050]: time="2024-11-12T22:24:10.536747484Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:10.538465 containerd[2050]: time="2024-11-12T22:24:10.536982564Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:10.802903 containerd[2050]: time="2024-11-12T22:24:10.802730329Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-j8mmn,Uid:6dee30aa-00a4-4564-b763-958daff1e4fe,Namespace:kube-system,Attempt:0,} returns sandbox id \"45a417c6d0bbd49fb9cce1af8a6604d461cbc34a4484f3e981ea3f7fb2276e0d\"" Nov 12 22:24:10.811681 containerd[2050]: time="2024-11-12T22:24:10.811592389Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.10\"" Nov 12 22:24:10.820755 containerd[2050]: time="2024-11-12T22:24:10.820697485Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hrxkw,Uid:64449420-0349-4965-a777-50cdf3b04a5d,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\"" Nov 12 22:24:11.159103 kubelet[2507]: E1112 22:24:11.158155 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:12.160709 kubelet[2507]: E1112 22:24:12.160652 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:12.229792 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1572049042.mount: Deactivated successfully. Nov 12 22:24:12.800112 containerd[2050]: time="2024-11-12T22:24:12.800019459Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:12.801795 containerd[2050]: time="2024-11-12T22:24:12.801685203Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.10: active requests=0, bytes read=25272229" Nov 12 22:24:12.804311 containerd[2050]: time="2024-11-12T22:24:12.804182751Z" level=info msg="ImageCreate event name:\"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:12.810110 containerd[2050]: time="2024-11-12T22:24:12.809980419Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:12.811710 containerd[2050]: time="2024-11-12T22:24:12.811621155Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.10\" with image id \"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\", repo tag \"registry.k8s.io/kube-proxy:v1.29.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\", size \"25271248\" in 1.999948594s" Nov 12 22:24:12.811710 containerd[2050]: time="2024-11-12T22:24:12.811696719Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.10\" returns image reference \"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\"" Nov 12 22:24:12.813895 containerd[2050]: time="2024-11-12T22:24:12.812929767Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 12 22:24:12.818160 containerd[2050]: time="2024-11-12T22:24:12.818067891Z" level=info msg="CreateContainer within sandbox \"45a417c6d0bbd49fb9cce1af8a6604d461cbc34a4484f3e981ea3f7fb2276e0d\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 12 22:24:12.845924 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2941437231.mount: Deactivated successfully. Nov 12 22:24:12.852228 containerd[2050]: time="2024-11-12T22:24:12.852136071Z" level=info msg="CreateContainer within sandbox \"45a417c6d0bbd49fb9cce1af8a6604d461cbc34a4484f3e981ea3f7fb2276e0d\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"0aca93af7fd34326de33f6de4bdd7bbae9ae5a4ae06b4f15334602dcc43365e6\"" Nov 12 22:24:12.855477 containerd[2050]: time="2024-11-12T22:24:12.853676367Z" level=info msg="StartContainer for \"0aca93af7fd34326de33f6de4bdd7bbae9ae5a4ae06b4f15334602dcc43365e6\"" Nov 12 22:24:12.985806 containerd[2050]: time="2024-11-12T22:24:12.985442920Z" level=info msg="StartContainer for \"0aca93af7fd34326de33f6de4bdd7bbae9ae5a4ae06b4f15334602dcc43365e6\" returns successfully" Nov 12 22:24:13.161019 kubelet[2507]: E1112 22:24:13.160955 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:13.508462 kubelet[2507]: I1112 22:24:13.507981 2507 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-j8mmn" podStartSLOduration=3.5049003 podStartE2EDuration="5.507878306s" podCreationTimestamp="2024-11-12 22:24:08 +0000 UTC" firstStartedPulling="2024-11-12 22:24:10.809329957 +0000 UTC m=+3.691647439" lastFinishedPulling="2024-11-12 22:24:12.812307891 +0000 UTC m=+5.694625445" observedRunningTime="2024-11-12 22:24:13.507439766 +0000 UTC m=+6.389757332" watchObservedRunningTime="2024-11-12 22:24:13.507878306 +0000 UTC m=+6.390195860" Nov 12 22:24:14.161947 kubelet[2507]: E1112 22:24:14.161901 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:14.618902 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1606502632.mount: Deactivated successfully. Nov 12 22:24:14.717823 containerd[2050]: time="2024-11-12T22:24:14.717741544Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.719722 containerd[2050]: time="2024-11-12T22:24:14.719630392Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673531" Nov 12 22:24:14.722000 containerd[2050]: time="2024-11-12T22:24:14.721893424Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.727350 containerd[2050]: time="2024-11-12T22:24:14.727180480Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.730989 containerd[2050]: time="2024-11-12T22:24:14.729480976Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.916470041s" Nov 12 22:24:14.730989 containerd[2050]: time="2024-11-12T22:24:14.729561268Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 12 22:24:14.733194 containerd[2050]: time="2024-11-12T22:24:14.733126984Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 12 22:24:14.760078 containerd[2050]: time="2024-11-12T22:24:14.759980153Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7\"" Nov 12 22:24:14.761306 containerd[2050]: time="2024-11-12T22:24:14.761230637Z" level=info msg="StartContainer for \"6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7\"" Nov 12 22:24:14.866664 containerd[2050]: time="2024-11-12T22:24:14.866496857Z" level=info msg="StartContainer for \"6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7\" returns successfully" Nov 12 22:24:15.162423 kubelet[2507]: E1112 22:24:15.162369 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:15.472320 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7-rootfs.mount: Deactivated successfully. Nov 12 22:24:15.620903 containerd[2050]: time="2024-11-12T22:24:15.620799677Z" level=info msg="shim disconnected" id=6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7 namespace=k8s.io Nov 12 22:24:15.621112 containerd[2050]: time="2024-11-12T22:24:15.620913689Z" level=warning msg="cleaning up after shim disconnected" id=6bb4f132d47d31da7bd48e41c55d24bc6bed3ad4833cf373b542f12a66f2d9e7 namespace=k8s.io Nov 12 22:24:15.621112 containerd[2050]: time="2024-11-12T22:24:15.620935901Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:24:16.162818 kubelet[2507]: E1112 22:24:16.162768 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:16.502443 containerd[2050]: time="2024-11-12T22:24:16.502310549Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 12 22:24:17.164463 kubelet[2507]: E1112 22:24:17.164401 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:18.165382 kubelet[2507]: E1112 22:24:18.165323 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:18.583552 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2704906411.mount: Deactivated successfully. Nov 12 22:24:19.166200 kubelet[2507]: E1112 22:24:19.165982 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:19.811083 containerd[2050]: time="2024-11-12T22:24:19.810985558Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:19.814214 containerd[2050]: time="2024-11-12T22:24:19.814086658Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Nov 12 22:24:19.817492 containerd[2050]: time="2024-11-12T22:24:19.817384858Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:19.828066 containerd[2050]: time="2024-11-12T22:24:19.827572654Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:19.830012 containerd[2050]: time="2024-11-12T22:24:19.829737706Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 3.327306965s" Nov 12 22:24:19.830012 containerd[2050]: time="2024-11-12T22:24:19.829810342Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 12 22:24:19.835032 containerd[2050]: time="2024-11-12T22:24:19.834937582Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 12 22:24:19.858234 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount246071083.mount: Deactivated successfully. Nov 12 22:24:19.865716 containerd[2050]: time="2024-11-12T22:24:19.865608022Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2\"" Nov 12 22:24:19.866771 containerd[2050]: time="2024-11-12T22:24:19.866699458Z" level=info msg="StartContainer for \"c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2\"" Nov 12 22:24:19.978855 containerd[2050]: time="2024-11-12T22:24:19.978623963Z" level=info msg="StartContainer for \"c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2\" returns successfully" Nov 12 22:24:20.016243 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2-rootfs.mount: Deactivated successfully. Nov 12 22:24:20.029187 kubelet[2507]: I1112 22:24:20.029133 2507 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Nov 12 22:24:20.166908 kubelet[2507]: E1112 22:24:20.166834 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:20.503098 containerd[2050]: time="2024-11-12T22:24:20.502630173Z" level=info msg="shim disconnected" id=c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2 namespace=k8s.io Nov 12 22:24:20.503098 containerd[2050]: time="2024-11-12T22:24:20.502713297Z" level=warning msg="cleaning up after shim disconnected" id=c2756cded9b85f15bf34defb2b2aef422e1a051d29218b4ead44c66e506744d2 namespace=k8s.io Nov 12 22:24:20.503098 containerd[2050]: time="2024-11-12T22:24:20.502737729Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:24:21.168084 kubelet[2507]: E1112 22:24:21.168005 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:21.538657 containerd[2050]: time="2024-11-12T22:24:21.538377358Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 12 22:24:21.575414 containerd[2050]: time="2024-11-12T22:24:21.575330746Z" level=info msg="CreateContainer within sandbox \"9bd26041c9b39555a34a3822c3c0142b25fe1d88f2ebf33da7751fa07fd46fea\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"d8baad36df4f424e8492503486ec34b4dafbb053f708a8812c62138f37abd030\"" Nov 12 22:24:21.576172 containerd[2050]: time="2024-11-12T22:24:21.576098374Z" level=info msg="StartContainer for \"d8baad36df4f424e8492503486ec34b4dafbb053f708a8812c62138f37abd030\"" Nov 12 22:24:21.633162 systemd[1]: run-containerd-runc-k8s.io-d8baad36df4f424e8492503486ec34b4dafbb053f708a8812c62138f37abd030-runc.YCPk69.mount: Deactivated successfully. Nov 12 22:24:21.691225 containerd[2050]: time="2024-11-12T22:24:21.690819155Z" level=info msg="StartContainer for \"d8baad36df4f424e8492503486ec34b4dafbb053f708a8812c62138f37abd030\" returns successfully" Nov 12 22:24:21.815189 kubelet[2507]: I1112 22:24:21.814990 2507 topology_manager.go:215] "Topology Admit Handler" podUID="ed4ebf0a-8532-4e50-9ced-b902390e3d64" podNamespace="default" podName="nginx-deployment-6d5f899847-4fmdg" Nov 12 22:24:21.926220 kubelet[2507]: I1112 22:24:21.926156 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-szzkb\" (UniqueName: \"kubernetes.io/projected/ed4ebf0a-8532-4e50-9ced-b902390e3d64-kube-api-access-szzkb\") pod \"nginx-deployment-6d5f899847-4fmdg\" (UID: \"ed4ebf0a-8532-4e50-9ced-b902390e3d64\") " pod="default/nginx-deployment-6d5f899847-4fmdg" Nov 12 22:24:22.123013 containerd[2050]: time="2024-11-12T22:24:22.122717097Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-4fmdg,Uid:ed4ebf0a-8532-4e50-9ced-b902390e3d64,Namespace:default,Attempt:0,}" Nov 12 22:24:22.167458 containerd[2050]: time="2024-11-12T22:24:22.167351865Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-4fmdg,Uid:ed4ebf0a-8532-4e50-9ced-b902390e3d64,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"e943ed8d1c4a6e42c91da8b5198746dd38ff64d325cf80aefe11514cb76e0dcd\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 12 22:24:22.167906 kubelet[2507]: E1112 22:24:22.167833 2507 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"e943ed8d1c4a6e42c91da8b5198746dd38ff64d325cf80aefe11514cb76e0dcd\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 12 22:24:22.168043 kubelet[2507]: E1112 22:24:22.167953 2507 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"e943ed8d1c4a6e42c91da8b5198746dd38ff64d325cf80aefe11514cb76e0dcd\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-4fmdg" Nov 12 22:24:22.168043 kubelet[2507]: E1112 22:24:22.167994 2507 kuberuntime_manager.go:1172] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"e943ed8d1c4a6e42c91da8b5198746dd38ff64d325cf80aefe11514cb76e0dcd\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-4fmdg" Nov 12 22:24:22.168152 kubelet[2507]: E1112 22:24:22.168087 2507 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-6d5f899847-4fmdg_default(ed4ebf0a-8532-4e50-9ced-b902390e3d64)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-6d5f899847-4fmdg_default(ed4ebf0a-8532-4e50-9ced-b902390e3d64)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"e943ed8d1c4a6e42c91da8b5198746dd38ff64d325cf80aefe11514cb76e0dcd\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-6d5f899847-4fmdg" podUID="ed4ebf0a-8532-4e50-9ced-b902390e3d64" Nov 12 22:24:22.168884 kubelet[2507]: E1112 22:24:22.168389 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:22.774970 (udev-worker)[3023]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:22.793544 systemd-networkd[1605]: flannel.1: Link UP Nov 12 22:24:22.793563 systemd-networkd[1605]: flannel.1: Gained carrier Nov 12 22:24:23.169673 kubelet[2507]: E1112 22:24:23.169595 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:23.900746 systemd-networkd[1605]: flannel.1: Gained IPv6LL Nov 12 22:24:24.170459 kubelet[2507]: E1112 22:24:24.170302 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:25.170857 kubelet[2507]: E1112 22:24:25.170769 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:26.171433 kubelet[2507]: E1112 22:24:26.171354 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:26.455895 ntpd[2002]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 12 22:24:26.456849 ntpd[2002]: 12 Nov 22:24:26 ntpd[2002]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 12 22:24:26.456849 ntpd[2002]: 12 Nov 22:24:26 ntpd[2002]: Listen normally on 7 flannel.1 [fe80::3464:70ff:fe9b:d016%3]:123 Nov 12 22:24:26.456041 ntpd[2002]: Listen normally on 7 flannel.1 [fe80::3464:70ff:fe9b:d016%3]:123 Nov 12 22:24:26.877925 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 12 22:24:27.172474 kubelet[2507]: E1112 22:24:27.172389 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:28.155667 kubelet[2507]: E1112 22:24:28.155593 2507 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:28.173213 kubelet[2507]: E1112 22:24:28.173139 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:29.173464 kubelet[2507]: E1112 22:24:29.173386 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:30.173615 kubelet[2507]: E1112 22:24:30.173544 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:31.174745 kubelet[2507]: E1112 22:24:31.174672 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:32.175794 kubelet[2507]: E1112 22:24:32.175721 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:33.176477 kubelet[2507]: E1112 22:24:33.176406 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:33.373824 containerd[2050]: time="2024-11-12T22:24:33.373768713Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-4fmdg,Uid:ed4ebf0a-8532-4e50-9ced-b902390e3d64,Namespace:default,Attempt:0,}" Nov 12 22:24:33.407749 systemd-networkd[1605]: cni0: Link UP Nov 12 22:24:33.407769 systemd-networkd[1605]: cni0: Gained carrier Nov 12 22:24:33.415258 (udev-worker)[3163]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:33.417469 systemd-networkd[1605]: cni0: Lost carrier Nov 12 22:24:33.424914 systemd-networkd[1605]: veth7e8bc714: Link UP Nov 12 22:24:33.426920 kernel: cni0: port 1(veth7e8bc714) entered blocking state Nov 12 22:24:33.427035 kernel: cni0: port 1(veth7e8bc714) entered disabled state Nov 12 22:24:33.428978 kernel: veth7e8bc714: entered allmulticast mode Nov 12 22:24:33.436430 kernel: veth7e8bc714: entered promiscuous mode Nov 12 22:24:33.436510 kernel: cni0: port 1(veth7e8bc714) entered blocking state Nov 12 22:24:33.436576 kernel: cni0: port 1(veth7e8bc714) entered forwarding state Nov 12 22:24:33.436619 kernel: cni0: port 1(veth7e8bc714) entered disabled state Nov 12 22:24:33.436108 (udev-worker)[3166]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:33.447944 kernel: cni0: port 1(veth7e8bc714) entered blocking state Nov 12 22:24:33.448418 kernel: cni0: port 1(veth7e8bc714) entered forwarding state Nov 12 22:24:33.448580 systemd-networkd[1605]: veth7e8bc714: Gained carrier Nov 12 22:24:33.450354 systemd-networkd[1605]: cni0: Gained carrier Nov 12 22:24:33.453973 containerd[2050]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 12 22:24:33.453973 containerd[2050]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:33.489019 containerd[2050]: {"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="2024-11-12T22:24:33.488401486Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:33.489019 containerd[2050]: time="2024-11-12T22:24:33.488532586Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:33.489019 containerd[2050]: time="2024-11-12T22:24:33.488569510Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:33.489019 containerd[2050]: time="2024-11-12T22:24:33.488857294Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:33.530451 systemd[1]: run-containerd-runc-k8s.io-b7ee37d5563d7159025148a49ba1f464d864c9e14bbec0e6234d276478733098-runc.wQHTow.mount: Deactivated successfully. Nov 12 22:24:33.585556 containerd[2050]: time="2024-11-12T22:24:33.585489418Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-4fmdg,Uid:ed4ebf0a-8532-4e50-9ced-b902390e3d64,Namespace:default,Attempt:0,} returns sandbox id \"b7ee37d5563d7159025148a49ba1f464d864c9e14bbec0e6234d276478733098\"" Nov 12 22:24:33.588439 containerd[2050]: time="2024-11-12T22:24:33.588239566Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:24:34.177131 kubelet[2507]: E1112 22:24:34.177029 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:34.588654 systemd-networkd[1605]: cni0: Gained IPv6LL Nov 12 22:24:35.177653 kubelet[2507]: E1112 22:24:35.177573 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:35.228630 systemd-networkd[1605]: veth7e8bc714: Gained IPv6LL Nov 12 22:24:36.178944 kubelet[2507]: E1112 22:24:36.178892 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:36.943180 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2344233321.mount: Deactivated successfully. Nov 12 22:24:37.180380 kubelet[2507]: E1112 22:24:37.180143 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:37.455779 ntpd[2002]: Listen normally on 8 cni0 192.168.1.1:123 Nov 12 22:24:37.457103 ntpd[2002]: 12 Nov 22:24:37 ntpd[2002]: Listen normally on 8 cni0 192.168.1.1:123 Nov 12 22:24:37.457103 ntpd[2002]: 12 Nov 22:24:37 ntpd[2002]: Listen normally on 9 cni0 [fe80::90d4:88ff:feca:cb71%4]:123 Nov 12 22:24:37.457103 ntpd[2002]: 12 Nov 22:24:37 ntpd[2002]: Listen normally on 10 veth7e8bc714 [fe80::d4d7:eaff:fe91:ba53%5]:123 Nov 12 22:24:37.455908 ntpd[2002]: Listen normally on 9 cni0 [fe80::90d4:88ff:feca:cb71%4]:123 Nov 12 22:24:37.455985 ntpd[2002]: Listen normally on 10 veth7e8bc714 [fe80::d4d7:eaff:fe91:ba53%5]:123 Nov 12 22:24:38.181149 kubelet[2507]: E1112 22:24:38.181072 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:38.353336 containerd[2050]: time="2024-11-12T22:24:38.352841306Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:38.354942 containerd[2050]: time="2024-11-12T22:24:38.354850178Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67683180" Nov 12 22:24:38.357102 containerd[2050]: time="2024-11-12T22:24:38.357027146Z" level=info msg="ImageCreate event name:\"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:38.364553 containerd[2050]: time="2024-11-12T22:24:38.364452878Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:38.366541 containerd[2050]: time="2024-11-12T22:24:38.366336494Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 4.77788316s" Nov 12 22:24:38.366541 containerd[2050]: time="2024-11-12T22:24:38.366386858Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:24:38.369523 containerd[2050]: time="2024-11-12T22:24:38.369467510Z" level=info msg="CreateContainer within sandbox \"b7ee37d5563d7159025148a49ba1f464d864c9e14bbec0e6234d276478733098\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 12 22:24:38.394735 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount167048650.mount: Deactivated successfully. Nov 12 22:24:38.395935 containerd[2050]: time="2024-11-12T22:24:38.395864486Z" level=info msg="CreateContainer within sandbox \"b7ee37d5563d7159025148a49ba1f464d864c9e14bbec0e6234d276478733098\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"6e8d4626ba0738f2265a4bacd57ce0583d3d2e84245f95fcae85208f2d45e6a1\"" Nov 12 22:24:38.398108 containerd[2050]: time="2024-11-12T22:24:38.397513382Z" level=info msg="StartContainer for \"6e8d4626ba0738f2265a4bacd57ce0583d3d2e84245f95fcae85208f2d45e6a1\"" Nov 12 22:24:38.464859 systemd[1]: run-containerd-runc-k8s.io-6e8d4626ba0738f2265a4bacd57ce0583d3d2e84245f95fcae85208f2d45e6a1-runc.GXHxP0.mount: Deactivated successfully. Nov 12 22:24:38.523444 containerd[2050]: time="2024-11-12T22:24:38.523351875Z" level=info msg="StartContainer for \"6e8d4626ba0738f2265a4bacd57ce0583d3d2e84245f95fcae85208f2d45e6a1\" returns successfully" Nov 12 22:24:38.601612 kubelet[2507]: I1112 22:24:38.601533 2507 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-hrxkw" podStartSLOduration=21.59439975 podStartE2EDuration="30.601450971s" podCreationTimestamp="2024-11-12 22:24:08 +0000 UTC" firstStartedPulling="2024-11-12 22:24:10.823071529 +0000 UTC m=+3.705389011" lastFinishedPulling="2024-11-12 22:24:19.830122738 +0000 UTC m=+12.712440232" observedRunningTime="2024-11-12 22:24:22.569577839 +0000 UTC m=+15.451895345" watchObservedRunningTime="2024-11-12 22:24:38.601450971 +0000 UTC m=+31.483768441" Nov 12 22:24:38.601877 kubelet[2507]: I1112 22:24:38.601838 2507 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-4fmdg" podStartSLOduration=12.822352583 podStartE2EDuration="17.601795347s" podCreationTimestamp="2024-11-12 22:24:21 +0000 UTC" firstStartedPulling="2024-11-12 22:24:33.587433754 +0000 UTC m=+26.469751224" lastFinishedPulling="2024-11-12 22:24:38.366876518 +0000 UTC m=+31.249193988" observedRunningTime="2024-11-12 22:24:38.601726875 +0000 UTC m=+31.484044357" watchObservedRunningTime="2024-11-12 22:24:38.601795347 +0000 UTC m=+31.484112829" Nov 12 22:24:39.182164 kubelet[2507]: E1112 22:24:39.182104 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:40.182566 kubelet[2507]: E1112 22:24:40.182499 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:41.163572 update_engine[2017]: I20241112 22:24:41.163457 2017 update_attempter.cc:509] Updating boot flags... Nov 12 22:24:41.182798 kubelet[2507]: E1112 22:24:41.182694 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:41.253352 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3341) Nov 12 22:24:41.518422 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3342) Nov 12 22:24:41.785421 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3342) Nov 12 22:24:42.183884 kubelet[2507]: E1112 22:24:42.183808 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:42.244131 kubelet[2507]: I1112 22:24:42.244078 2507 topology_manager.go:215] "Topology Admit Handler" podUID="d5f79e5c-1441-41c7-99e9-4359d5689536" podNamespace="default" podName="nfs-server-provisioner-0" Nov 12 22:24:42.356389 kubelet[2507]: I1112 22:24:42.356324 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hn9wx\" (UniqueName: \"kubernetes.io/projected/d5f79e5c-1441-41c7-99e9-4359d5689536-kube-api-access-hn9wx\") pod \"nfs-server-provisioner-0\" (UID: \"d5f79e5c-1441-41c7-99e9-4359d5689536\") " pod="default/nfs-server-provisioner-0" Nov 12 22:24:42.356525 kubelet[2507]: I1112 22:24:42.356426 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d5f79e5c-1441-41c7-99e9-4359d5689536-data\") pod \"nfs-server-provisioner-0\" (UID: \"d5f79e5c-1441-41c7-99e9-4359d5689536\") " pod="default/nfs-server-provisioner-0" Nov 12 22:24:42.551308 containerd[2050]: time="2024-11-12T22:24:42.550743175Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d5f79e5c-1441-41c7-99e9-4359d5689536,Namespace:default,Attempt:0,}" Nov 12 22:24:42.589404 (udev-worker)[3345]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:42.592395 systemd-networkd[1605]: veth4aa74c63: Link UP Nov 12 22:24:42.596443 kernel: cni0: port 2(veth4aa74c63) entered blocking state Nov 12 22:24:42.596490 kernel: cni0: port 2(veth4aa74c63) entered disabled state Nov 12 22:24:42.598001 kernel: veth4aa74c63: entered allmulticast mode Nov 12 22:24:42.599992 kernel: veth4aa74c63: entered promiscuous mode Nov 12 22:24:42.614323 kernel: cni0: port 2(veth4aa74c63) entered blocking state Nov 12 22:24:42.614420 kernel: cni0: port 2(veth4aa74c63) entered forwarding state Nov 12 22:24:42.614670 systemd-networkd[1605]: veth4aa74c63: Gained carrier Nov 12 22:24:42.618175 containerd[2050]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Nov 12 22:24:42.618175 containerd[2050]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:42.651430 containerd[2050]: {"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="2024-11-12T22:24:42.650042107Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:42.651430 containerd[2050]: time="2024-11-12T22:24:42.650131303Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:42.651430 containerd[2050]: time="2024-11-12T22:24:42.650155687Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:42.651430 containerd[2050]: time="2024-11-12T22:24:42.650341675Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:42.749874 containerd[2050]: time="2024-11-12T22:24:42.749819564Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d5f79e5c-1441-41c7-99e9-4359d5689536,Namespace:default,Attempt:0,} returns sandbox id \"574b3fecfb308218d4d60571baa26542a0fbf920265c8685d977936f73676f24\"" Nov 12 22:24:42.753315 containerd[2050]: time="2024-11-12T22:24:42.753228200Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 12 22:24:43.185010 kubelet[2507]: E1112 22:24:43.184920 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:44.186072 kubelet[2507]: E1112 22:24:44.185960 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:44.573471 systemd-networkd[1605]: veth4aa74c63: Gained IPv6LL Nov 12 22:24:45.186163 kubelet[2507]: E1112 22:24:45.186069 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:45.723762 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1222842263.mount: Deactivated successfully. Nov 12 22:24:46.186894 kubelet[2507]: E1112 22:24:46.186817 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:47.187608 kubelet[2507]: E1112 22:24:47.187406 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:47.455869 ntpd[2002]: Listen normally on 11 veth4aa74c63 [fe80::dc0b:68ff:fed4:92dd%6]:123 Nov 12 22:24:47.457315 ntpd[2002]: 12 Nov 22:24:47 ntpd[2002]: Listen normally on 11 veth4aa74c63 [fe80::dc0b:68ff:fed4:92dd%6]:123 Nov 12 22:24:48.154806 kubelet[2507]: E1112 22:24:48.154732 2507 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:48.188662 kubelet[2507]: E1112 22:24:48.188568 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:48.844878 containerd[2050]: time="2024-11-12T22:24:48.844791518Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:48.846993 containerd[2050]: time="2024-11-12T22:24:48.846891818Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 12 22:24:48.848790 containerd[2050]: time="2024-11-12T22:24:48.848705390Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:48.857136 containerd[2050]: time="2024-11-12T22:24:48.855485270Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:48.857804 containerd[2050]: time="2024-11-12T22:24:48.857743262Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 6.104425566s" Nov 12 22:24:48.858003 containerd[2050]: time="2024-11-12T22:24:48.857970098Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 12 22:24:48.861135 containerd[2050]: time="2024-11-12T22:24:48.861082022Z" level=info msg="CreateContainer within sandbox \"574b3fecfb308218d4d60571baa26542a0fbf920265c8685d977936f73676f24\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 12 22:24:48.890905 containerd[2050]: time="2024-11-12T22:24:48.890829374Z" level=info msg="CreateContainer within sandbox \"574b3fecfb308218d4d60571baa26542a0fbf920265c8685d977936f73676f24\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"3aeb9e61f398632896d0976f382557af28db985b3643a7d457271dc4f5c3b039\"" Nov 12 22:24:48.891908 containerd[2050]: time="2024-11-12T22:24:48.891631310Z" level=info msg="StartContainer for \"3aeb9e61f398632896d0976f382557af28db985b3643a7d457271dc4f5c3b039\"" Nov 12 22:24:48.941162 systemd[1]: run-containerd-runc-k8s.io-3aeb9e61f398632896d0976f382557af28db985b3643a7d457271dc4f5c3b039-runc.5GeahI.mount: Deactivated successfully. Nov 12 22:24:48.999355 containerd[2050]: time="2024-11-12T22:24:48.997582911Z" level=info msg="StartContainer for \"3aeb9e61f398632896d0976f382557af28db985b3643a7d457271dc4f5c3b039\" returns successfully" Nov 12 22:24:49.190319 kubelet[2507]: E1112 22:24:49.189506 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:50.190030 kubelet[2507]: E1112 22:24:50.189959 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:51.191076 kubelet[2507]: E1112 22:24:51.191014 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:52.191809 kubelet[2507]: E1112 22:24:52.191732 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:53.192919 kubelet[2507]: E1112 22:24:53.192856 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:54.193992 kubelet[2507]: E1112 22:24:54.193932 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:55.194948 kubelet[2507]: E1112 22:24:55.194884 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:56.195676 kubelet[2507]: E1112 22:24:56.195608 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:57.196200 kubelet[2507]: E1112 22:24:57.196132 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:58.197399 kubelet[2507]: E1112 22:24:58.197342 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:58.571387 kubelet[2507]: I1112 22:24:58.570161 2507 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.463955404 podStartE2EDuration="16.57009523s" podCreationTimestamp="2024-11-12 22:24:42 +0000 UTC" firstStartedPulling="2024-11-12 22:24:42.752240876 +0000 UTC m=+35.634558358" lastFinishedPulling="2024-11-12 22:24:48.858380702 +0000 UTC m=+41.740698184" observedRunningTime="2024-11-12 22:24:49.65377469 +0000 UTC m=+42.536092184" watchObservedRunningTime="2024-11-12 22:24:58.57009523 +0000 UTC m=+51.452412724" Nov 12 22:24:58.571387 kubelet[2507]: I1112 22:24:58.570410 2507 topology_manager.go:215] "Topology Admit Handler" podUID="23f05eb9-3222-402d-919e-eb98527e256a" podNamespace="default" podName="test-pod-1" Nov 12 22:24:58.755717 kubelet[2507]: I1112 22:24:58.755669 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-7d915469-b846-4191-b849-091ef089bf5b\" (UniqueName: \"kubernetes.io/nfs/23f05eb9-3222-402d-919e-eb98527e256a-pvc-7d915469-b846-4191-b849-091ef089bf5b\") pod \"test-pod-1\" (UID: \"23f05eb9-3222-402d-919e-eb98527e256a\") " pod="default/test-pod-1" Nov 12 22:24:58.756077 kubelet[2507]: I1112 22:24:58.756043 2507 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7pln7\" (UniqueName: \"kubernetes.io/projected/23f05eb9-3222-402d-919e-eb98527e256a-kube-api-access-7pln7\") pod \"test-pod-1\" (UID: \"23f05eb9-3222-402d-919e-eb98527e256a\") " pod="default/test-pod-1" Nov 12 22:24:58.907329 kernel: FS-Cache: Loaded Nov 12 22:24:58.950206 kernel: RPC: Registered named UNIX socket transport module. Nov 12 22:24:58.950348 kernel: RPC: Registered udp transport module. Nov 12 22:24:58.950480 kernel: RPC: Registered tcp transport module. Nov 12 22:24:58.950522 kernel: RPC: Registered tcp-with-tls transport module. Nov 12 22:24:58.951176 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 12 22:24:59.198376 kubelet[2507]: E1112 22:24:59.198215 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:59.271487 kernel: NFS: Registering the id_resolver key type Nov 12 22:24:59.271622 kernel: Key type id_resolver registered Nov 12 22:24:59.271664 kernel: Key type id_legacy registered Nov 12 22:24:59.306602 nfsidmap[3866]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 12 22:24:59.312623 nfsidmap[3867]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 12 22:24:59.483322 containerd[2050]: time="2024-11-12T22:24:59.482732315Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:23f05eb9-3222-402d-919e-eb98527e256a,Namespace:default,Attempt:0,}" Nov 12 22:24:59.520492 (udev-worker)[3860]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:59.521153 systemd-networkd[1605]: vethfe157108: Link UP Nov 12 22:24:59.524349 kernel: cni0: port 3(vethfe157108) entered blocking state Nov 12 22:24:59.524456 kernel: cni0: port 3(vethfe157108) entered disabled state Nov 12 22:24:59.526122 kernel: vethfe157108: entered allmulticast mode Nov 12 22:24:59.528388 kernel: vethfe157108: entered promiscuous mode Nov 12 22:24:59.543366 kernel: cni0: port 3(vethfe157108) entered blocking state Nov 12 22:24:59.543463 kernel: cni0: port 3(vethfe157108) entered forwarding state Nov 12 22:24:59.545417 systemd-networkd[1605]: vethfe157108: Gained carrier Nov 12 22:24:59.547337 containerd[2050]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 12 22:24:59.547337 containerd[2050]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:59.582181 containerd[2050]: {"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="2024-11-12T22:24:59.581462927Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:59.582181 containerd[2050]: time="2024-11-12T22:24:59.581563907Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:59.582181 containerd[2050]: time="2024-11-12T22:24:59.581750387Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:59.582181 containerd[2050]: time="2024-11-12T22:24:59.581941595Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:59.666648 containerd[2050]: time="2024-11-12T22:24:59.666562008Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:23f05eb9-3222-402d-919e-eb98527e256a,Namespace:default,Attempt:0,} returns sandbox id \"7193c7140c4516f74333b2037f5e995adefe03b57ab3dc02a2e774a591c06ce7\"" Nov 12 22:24:59.669976 containerd[2050]: time="2024-11-12T22:24:59.669842640Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:25:00.007101 containerd[2050]: time="2024-11-12T22:25:00.006241773Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:25:00.008110 containerd[2050]: time="2024-11-12T22:25:00.008029473Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 12 22:25:00.013697 containerd[2050]: time="2024-11-12T22:25:00.013606293Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 343.690225ms" Nov 12 22:25:00.013697 containerd[2050]: time="2024-11-12T22:25:00.013663857Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:25:00.016445 containerd[2050]: time="2024-11-12T22:25:00.016215657Z" level=info msg="CreateContainer within sandbox \"7193c7140c4516f74333b2037f5e995adefe03b57ab3dc02a2e774a591c06ce7\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 12 22:25:00.044134 containerd[2050]: time="2024-11-12T22:25:00.044061574Z" level=info msg="CreateContainer within sandbox \"7193c7140c4516f74333b2037f5e995adefe03b57ab3dc02a2e774a591c06ce7\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"6647c108fc1e1a6b1a81a9cd58ab2a90e832900e16ede824dab6f4257b1872cb\"" Nov 12 22:25:00.044915 containerd[2050]: time="2024-11-12T22:25:00.044841166Z" level=info msg="StartContainer for \"6647c108fc1e1a6b1a81a9cd58ab2a90e832900e16ede824dab6f4257b1872cb\"" Nov 12 22:25:00.149581 containerd[2050]: time="2024-11-12T22:25:00.149415958Z" level=info msg="StartContainer for \"6647c108fc1e1a6b1a81a9cd58ab2a90e832900e16ede824dab6f4257b1872cb\" returns successfully" Nov 12 22:25:00.198701 kubelet[2507]: E1112 22:25:00.198613 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:01.084593 systemd-networkd[1605]: vethfe157108: Gained IPv6LL Nov 12 22:25:01.199837 kubelet[2507]: E1112 22:25:01.199766 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:02.201029 kubelet[2507]: E1112 22:25:02.200945 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:03.201355 kubelet[2507]: E1112 22:25:03.201307 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:03.455943 ntpd[2002]: Listen normally on 12 vethfe157108 [fe80::6893:1fff:fe78:631d%7]:123 Nov 12 22:25:03.456582 ntpd[2002]: 12 Nov 22:25:03 ntpd[2002]: Listen normally on 12 vethfe157108 [fe80::6893:1fff:fe78:631d%7]:123 Nov 12 22:25:04.202049 kubelet[2507]: E1112 22:25:04.201987 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:05.202622 kubelet[2507]: E1112 22:25:05.202561 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:06.203749 kubelet[2507]: E1112 22:25:06.203688 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:07.204842 kubelet[2507]: E1112 22:25:07.204784 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:08.155201 kubelet[2507]: E1112 22:25:08.155141 2507 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:08.205760 kubelet[2507]: E1112 22:25:08.205708 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:09.206251 kubelet[2507]: E1112 22:25:09.206180 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:10.206852 kubelet[2507]: E1112 22:25:10.206792 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:11.208069 kubelet[2507]: E1112 22:25:11.207963 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:12.208335 kubelet[2507]: E1112 22:25:12.208221 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:13.208456 kubelet[2507]: E1112 22:25:13.208380 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:14.209208 kubelet[2507]: E1112 22:25:14.209141 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:15.209376 kubelet[2507]: E1112 22:25:15.209313 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:16.210266 kubelet[2507]: E1112 22:25:16.210200 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:17.211265 kubelet[2507]: E1112 22:25:17.211188 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:18.211428 kubelet[2507]: E1112 22:25:18.211356 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:19.212442 kubelet[2507]: E1112 22:25:19.212373 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:19.308237 kubelet[2507]: E1112 22:25:19.308174 2507 controller.go:195] "Failed to update lease" err="Put \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:20.115373 kubelet[2507]: E1112 22:25:20.115129 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="failed to patch status \"{\\\"status\\\":{\\\"$setElementOrder/conditions\\\":[{\\\"type\\\":\\\"NetworkUnavailable\\\"},{\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"type\\\":\\\"DiskPressure\\\"},{\\\"type\\\":\\\"PIDPressure\\\"},{\\\"type\\\":\\\"Ready\\\"}],\\\"conditions\\\":[{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T22:25:10Z\\\",\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T22:25:10Z\\\",\\\"type\\\":\\\"DiskPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T22:25:10Z\\\",\\\"type\\\":\\\"PIDPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T22:25:10Z\\\",\\\"type\\\":\\\"Ready\\\"}],\\\"images\\\":[{\\\"names\\\":[\\\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\\\",\\\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\\\"],\\\"sizeBytes\\\":87371201},{\\\"names\\\":[\\\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\\\",\\\"ghcr.io/flatcar/nginx:latest\\\"],\\\"sizeBytes\\\":67683058},{\\\"names\\\":[\\\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\\\",\\\"docker.io/flannel/flannel:v0.22.0\\\"],\\\"sizeBytes\\\":26863435},{\\\"names\\\":[\\\"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\\\",\\\"registry.k8s.io/kube-proxy:v1.29.10\\\"],\\\"sizeBytes\\\":25271248},{\\\"names\\\":[\\\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\\\",\\\"docker.io/flannel/flannel-cni-plugin:v1.1.2\\\"],\\\"sizeBytes\\\":3662650},{\\\"names\\\":[\\\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\\\",\\\"registry.k8s.io/pause:3.8\\\"],\\\"sizeBytes\\\":268403}]}}\" for node \"172.31.27.94\": Patch \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94/status?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:20.212895 kubelet[2507]: E1112 22:25:20.212835 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:21.213624 kubelet[2507]: E1112 22:25:21.213557 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:22.214708 kubelet[2507]: E1112 22:25:22.214649 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:23.215387 kubelet[2507]: E1112 22:25:23.215315 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:24.215731 kubelet[2507]: E1112 22:25:24.215657 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:25.216533 kubelet[2507]: E1112 22:25:25.216472 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:26.217631 kubelet[2507]: E1112 22:25:26.217571 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:27.217928 kubelet[2507]: E1112 22:25:27.217851 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:28.154952 kubelet[2507]: E1112 22:25:28.154884 2507 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:28.218071 kubelet[2507]: E1112 22:25:28.218013 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:29.219166 kubelet[2507]: E1112 22:25:29.219098 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:29.308986 kubelet[2507]: E1112 22:25:29.308926 2507 controller.go:195] "Failed to update lease" err="Put \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": context deadline exceeded" Nov 12 22:25:30.115504 kubelet[2507]: E1112 22:25:30.115437 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.27.94\": Get \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:30.219530 kubelet[2507]: E1112 22:25:30.219464 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:31.220165 kubelet[2507]: E1112 22:25:31.220094 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:32.221063 kubelet[2507]: E1112 22:25:32.220980 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:33.221990 kubelet[2507]: E1112 22:25:33.221922 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:34.222174 kubelet[2507]: E1112 22:25:34.222098 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:35.222899 kubelet[2507]: E1112 22:25:35.222832 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:36.223110 kubelet[2507]: E1112 22:25:36.223043 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:37.224164 kubelet[2507]: E1112 22:25:37.224098 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:38.225251 kubelet[2507]: E1112 22:25:38.225197 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:39.225424 kubelet[2507]: E1112 22:25:39.225361 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:39.309925 kubelet[2507]: E1112 22:25:39.309623 2507 controller.go:195] "Failed to update lease" err="Put \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:40.116626 kubelet[2507]: E1112 22:25:40.116447 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.27.94\": Get \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:40.147307 kubelet[2507]: E1112 22:25:40.146199 2507 controller.go:195] "Failed to update lease" err="Put \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": unexpected EOF" Nov 12 22:25:40.154894 kubelet[2507]: E1112 22:25:40.154828 2507 controller.go:195] "Failed to update lease" err="Put \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": read tcp 172.31.27.94:53816->172.31.25.26:6443: read: connection reset by peer" Nov 12 22:25:40.155111 kubelet[2507]: I1112 22:25:40.155089 2507 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Nov 12 22:25:40.155962 kubelet[2507]: E1112 22:25:40.155928 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" interval="200ms" Nov 12 22:25:40.226446 kubelet[2507]: E1112 22:25:40.226384 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:40.357667 kubelet[2507]: E1112 22:25:40.357620 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" interval="400ms" Nov 12 22:25:40.759100 kubelet[2507]: E1112 22:25:40.759038 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" interval="800ms" Nov 12 22:25:41.146903 kubelet[2507]: E1112 22:25:41.146854 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.27.94\": Get \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused - error from a previous attempt: unexpected EOF" Nov 12 22:25:41.147483 kubelet[2507]: E1112 22:25:41.147383 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.27.94\": Get \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" Nov 12 22:25:41.147483 kubelet[2507]: E1112 22:25:41.147417 2507 kubelet_node_status.go:531] "Unable to update node status" err="update node status exceeds retry count" Nov 12 22:25:41.227335 kubelet[2507]: E1112 22:25:41.227253 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:41.560163 kubelet[2507]: E1112 22:25:41.560039 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" interval="1.6s" Nov 12 22:25:42.228440 kubelet[2507]: E1112 22:25:42.228372 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:43.161180 kubelet[2507]: E1112 22:25:43.161125 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": dial tcp 172.31.25.26:6443: connect: connection refused" interval="3.2s" Nov 12 22:25:43.229545 kubelet[2507]: E1112 22:25:43.229484 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:44.230157 kubelet[2507]: E1112 22:25:44.230089 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:45.230805 kubelet[2507]: E1112 22:25:45.230748 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:46.231923 kubelet[2507]: E1112 22:25:46.231865 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:47.232867 kubelet[2507]: E1112 22:25:47.232802 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:48.155380 kubelet[2507]: E1112 22:25:48.155310 2507 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:48.233708 kubelet[2507]: E1112 22:25:48.233645 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:49.234699 kubelet[2507]: E1112 22:25:49.234635 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:50.235458 kubelet[2507]: E1112 22:25:50.235401 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:51.236492 kubelet[2507]: E1112 22:25:51.236427 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:52.237629 kubelet[2507]: E1112 22:25:52.237553 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:53.237789 kubelet[2507]: E1112 22:25:53.237721 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:54.238710 kubelet[2507]: E1112 22:25:54.238640 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:55.239715 kubelet[2507]: E1112 22:25:55.239651 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:56.240458 kubelet[2507]: E1112 22:25:56.240383 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:56.361985 kubelet[2507]: E1112 22:25:56.361931 2507 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.25.26:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.94?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="6.4s" Nov 12 22:25:57.240590 kubelet[2507]: E1112 22:25:57.240519 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:58.241713 kubelet[2507]: E1112 22:25:58.241640 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:59.242858 kubelet[2507]: E1112 22:25:59.242793 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:26:00.243344 kubelet[2507]: E1112 22:26:00.243257 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:26:01.243982 kubelet[2507]: E1112 22:26:01.243922 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:26:01.368671 kubelet[2507]: E1112 22:26:01.368612 2507 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.27.94\": Get \"https://172.31.25.26:6443/api/v1/nodes/172.31.27.94?resourceVersion=0&timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" Nov 12 22:26:02.244341 kubelet[2507]: E1112 22:26:02.244258 2507 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"