Sep 4 17:11:43.226493 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Sep 4 17:11:43.226540 kernel: Linux version 6.6.48-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT Wed Sep 4 15:52:28 -00 2024 Sep 4 17:11:43.226566 kernel: KASLR disabled due to lack of seed Sep 4 17:11:43.226583 kernel: efi: EFI v2.7 by EDK II Sep 4 17:11:43.226599 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7852ee18 Sep 4 17:11:43.226614 kernel: ACPI: Early table checksum verification disabled Sep 4 17:11:43.226632 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Sep 4 17:11:43.226648 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Sep 4 17:11:43.226664 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Sep 4 17:11:43.226680 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Sep 4 17:11:43.226701 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Sep 4 17:11:43.226717 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Sep 4 17:11:43.226733 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Sep 4 17:11:43.226749 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Sep 4 17:11:43.226768 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Sep 4 17:11:43.226788 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Sep 4 17:11:43.226805 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Sep 4 17:11:43.226822 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Sep 4 17:11:43.226839 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Sep 4 17:11:43.226855 kernel: printk: bootconsole [uart0] enabled Sep 4 17:11:43.226872 kernel: NUMA: Failed to initialise from firmware Sep 4 17:11:43.226889 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Sep 4 17:11:43.226906 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Sep 4 17:11:43.226922 kernel: Zone ranges: Sep 4 17:11:43.226939 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Sep 4 17:11:43.226955 kernel: DMA32 empty Sep 4 17:11:43.227024 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Sep 4 17:11:43.227043 kernel: Movable zone start for each node Sep 4 17:11:43.227060 kernel: Early memory node ranges Sep 4 17:11:43.227078 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Sep 4 17:11:43.227094 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Sep 4 17:11:43.227112 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Sep 4 17:11:43.227129 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Sep 4 17:11:43.227145 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Sep 4 17:11:43.227162 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Sep 4 17:11:43.227179 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Sep 4 17:11:43.227195 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Sep 4 17:11:43.227212 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Sep 4 17:11:43.227234 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Sep 4 17:11:43.227252 kernel: psci: probing for conduit method from ACPI. Sep 4 17:11:43.227275 kernel: psci: PSCIv1.0 detected in firmware. Sep 4 17:11:43.227293 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 17:11:43.227311 kernel: psci: Trusted OS migration not required Sep 4 17:11:43.227333 kernel: psci: SMC Calling Convention v1.1 Sep 4 17:11:43.227351 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 4 17:11:43.227368 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 4 17:11:43.227386 kernel: pcpu-alloc: [0] 0 [0] 1 Sep 4 17:11:43.227403 kernel: Detected PIPT I-cache on CPU0 Sep 4 17:11:43.227421 kernel: CPU features: detected: GIC system register CPU interface Sep 4 17:11:43.227438 kernel: CPU features: detected: Spectre-v2 Sep 4 17:11:43.227455 kernel: CPU features: detected: Spectre-v3a Sep 4 17:11:43.227473 kernel: CPU features: detected: Spectre-BHB Sep 4 17:11:43.227490 kernel: CPU features: detected: ARM erratum 1742098 Sep 4 17:11:43.227508 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Sep 4 17:11:43.227529 kernel: alternatives: applying boot alternatives Sep 4 17:11:43.227550 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=7913866621ae0af53522ae1b4ff4e1e453dd69d966d437a439147039341ecbbc Sep 4 17:11:43.227569 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 17:11:43.227586 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 17:11:43.227604 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 17:11:43.227621 kernel: Fallback order for Node 0: 0 Sep 4 17:11:43.227639 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Sep 4 17:11:43.227657 kernel: Policy zone: Normal Sep 4 17:11:43.227674 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 17:11:43.227692 kernel: software IO TLB: area num 2. Sep 4 17:11:43.227710 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Sep 4 17:11:43.227734 kernel: Memory: 3820536K/4030464K available (10240K kernel code, 2182K rwdata, 8076K rodata, 39040K init, 897K bss, 209928K reserved, 0K cma-reserved) Sep 4 17:11:43.227752 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 4 17:11:43.227769 kernel: trace event string verifier disabled Sep 4 17:11:43.227786 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 17:11:43.227805 kernel: rcu: RCU event tracing is enabled. Sep 4 17:11:43.227823 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 4 17:11:43.227841 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 17:11:43.227858 kernel: Tracing variant of Tasks RCU enabled. Sep 4 17:11:43.227876 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 17:11:43.227894 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 4 17:11:43.227911 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 17:11:43.227932 kernel: GICv3: 96 SPIs implemented Sep 4 17:11:43.227950 kernel: GICv3: 0 Extended SPIs implemented Sep 4 17:11:43.228000 kernel: Root IRQ handler: gic_handle_irq Sep 4 17:11:43.228020 kernel: GICv3: GICv3 features: 16 PPIs Sep 4 17:11:43.228038 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Sep 4 17:11:43.228056 kernel: ITS [mem 0x10080000-0x1009ffff] Sep 4 17:11:43.228074 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000c0000 (indirect, esz 8, psz 64K, shr 1) Sep 4 17:11:43.228092 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000d0000 (flat, esz 8, psz 64K, shr 1) Sep 4 17:11:43.228109 kernel: GICv3: using LPI property table @0x00000004000e0000 Sep 4 17:11:43.228127 kernel: ITS: Using hypervisor restricted LPI range [128] Sep 4 17:11:43.228144 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000f0000 Sep 4 17:11:43.228162 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 17:11:43.228186 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Sep 4 17:11:43.228204 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Sep 4 17:11:43.228221 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Sep 4 17:11:43.228239 kernel: Console: colour dummy device 80x25 Sep 4 17:11:43.228257 kernel: printk: console [tty1] enabled Sep 4 17:11:43.228275 kernel: ACPI: Core revision 20230628 Sep 4 17:11:43.228311 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Sep 4 17:11:43.228337 kernel: pid_max: default: 32768 minimum: 301 Sep 4 17:11:43.228356 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Sep 4 17:11:43.228374 kernel: SELinux: Initializing. Sep 4 17:11:43.228399 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:11:43.228418 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 17:11:43.231785 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:11:43.231807 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 17:11:43.231825 kernel: rcu: Hierarchical SRCU implementation. Sep 4 17:11:43.231845 kernel: rcu: Max phase no-delay instances is 400. Sep 4 17:11:43.231863 kernel: Platform MSI: ITS@0x10080000 domain created Sep 4 17:11:43.231881 kernel: PCI/MSI: ITS@0x10080000 domain created Sep 4 17:11:43.231900 kernel: Remapping and enabling EFI services. Sep 4 17:11:43.231929 kernel: smp: Bringing up secondary CPUs ... Sep 4 17:11:43.231947 kernel: Detected PIPT I-cache on CPU1 Sep 4 17:11:43.231984 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Sep 4 17:11:43.232005 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000400100000 Sep 4 17:11:43.232047 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Sep 4 17:11:43.232066 kernel: smp: Brought up 1 node, 2 CPUs Sep 4 17:11:43.232085 kernel: SMP: Total of 2 processors activated. Sep 4 17:11:43.232103 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 17:11:43.232121 kernel: CPU features: detected: 32-bit EL1 Support Sep 4 17:11:43.232146 kernel: CPU features: detected: CRC32 instructions Sep 4 17:11:43.232164 kernel: CPU: All CPU(s) started at EL1 Sep 4 17:11:43.232193 kernel: alternatives: applying system-wide alternatives Sep 4 17:11:43.232216 kernel: devtmpfs: initialized Sep 4 17:11:43.232234 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 17:11:43.232253 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 4 17:11:43.232272 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 17:11:43.232291 kernel: SMBIOS 3.0.0 present. Sep 4 17:11:43.232310 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Sep 4 17:11:43.232333 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 17:11:43.232352 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 17:11:43.232370 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 17:11:43.232390 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 17:11:43.232409 kernel: audit: initializing netlink subsys (disabled) Sep 4 17:11:43.232428 kernel: audit: type=2000 audit(0.296:1): state=initialized audit_enabled=0 res=1 Sep 4 17:11:43.232448 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 17:11:43.232473 kernel: cpuidle: using governor menu Sep 4 17:11:43.232492 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 17:11:43.232526 kernel: ASID allocator initialised with 65536 entries Sep 4 17:11:43.232549 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 17:11:43.232569 kernel: Serial: AMBA PL011 UART driver Sep 4 17:11:43.232588 kernel: Modules: 17600 pages in range for non-PLT usage Sep 4 17:11:43.232607 kernel: Modules: 509120 pages in range for PLT usage Sep 4 17:11:43.232625 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 17:11:43.232644 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 17:11:43.232669 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 17:11:43.232688 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 17:11:43.232707 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 17:11:43.232726 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 17:11:43.232746 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 17:11:43.232765 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 17:11:43.232783 kernel: ACPI: Added _OSI(Module Device) Sep 4 17:11:43.232802 kernel: ACPI: Added _OSI(Processor Device) Sep 4 17:11:43.232821 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 17:11:43.232845 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 17:11:43.232865 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 17:11:43.232884 kernel: ACPI: Interpreter enabled Sep 4 17:11:43.232903 kernel: ACPI: Using GIC for interrupt routing Sep 4 17:11:43.232922 kernel: ACPI: MCFG table detected, 1 entries Sep 4 17:11:43.233443 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Sep 4 17:11:43.233760 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 17:11:43.233989 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 4 17:11:43.234205 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 4 17:11:43.234402 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Sep 4 17:11:43.234636 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Sep 4 17:11:43.234666 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Sep 4 17:11:43.234686 kernel: acpiphp: Slot [1] registered Sep 4 17:11:43.234705 kernel: acpiphp: Slot [2] registered Sep 4 17:11:43.234724 kernel: acpiphp: Slot [3] registered Sep 4 17:11:43.234742 kernel: acpiphp: Slot [4] registered Sep 4 17:11:43.234760 kernel: acpiphp: Slot [5] registered Sep 4 17:11:43.234785 kernel: acpiphp: Slot [6] registered Sep 4 17:11:43.234804 kernel: acpiphp: Slot [7] registered Sep 4 17:11:43.234822 kernel: acpiphp: Slot [8] registered Sep 4 17:11:43.234841 kernel: acpiphp: Slot [9] registered Sep 4 17:11:43.234859 kernel: acpiphp: Slot [10] registered Sep 4 17:11:43.234877 kernel: acpiphp: Slot [11] registered Sep 4 17:11:43.234896 kernel: acpiphp: Slot [12] registered Sep 4 17:11:43.234914 kernel: acpiphp: Slot [13] registered Sep 4 17:11:43.234933 kernel: acpiphp: Slot [14] registered Sep 4 17:11:43.234955 kernel: acpiphp: Slot [15] registered Sep 4 17:11:43.235749 kernel: acpiphp: Slot [16] registered Sep 4 17:11:43.235770 kernel: acpiphp: Slot [17] registered Sep 4 17:11:43.235789 kernel: acpiphp: Slot [18] registered Sep 4 17:11:43.235807 kernel: acpiphp: Slot [19] registered Sep 4 17:11:43.235826 kernel: acpiphp: Slot [20] registered Sep 4 17:11:43.235845 kernel: acpiphp: Slot [21] registered Sep 4 17:11:43.235863 kernel: acpiphp: Slot [22] registered Sep 4 17:11:43.235882 kernel: acpiphp: Slot [23] registered Sep 4 17:11:43.235901 kernel: acpiphp: Slot [24] registered Sep 4 17:11:43.235927 kernel: acpiphp: Slot [25] registered Sep 4 17:11:43.235946 kernel: acpiphp: Slot [26] registered Sep 4 17:11:43.236019 kernel: acpiphp: Slot [27] registered Sep 4 17:11:43.236041 kernel: acpiphp: Slot [28] registered Sep 4 17:11:43.236060 kernel: acpiphp: Slot [29] registered Sep 4 17:11:43.236079 kernel: acpiphp: Slot [30] registered Sep 4 17:11:43.236098 kernel: acpiphp: Slot [31] registered Sep 4 17:11:43.236117 kernel: PCI host bridge to bus 0000:00 Sep 4 17:11:43.238046 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Sep 4 17:11:43.238293 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 4 17:11:43.238472 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Sep 4 17:11:43.238656 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Sep 4 17:11:43.238920 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Sep 4 17:11:43.240249 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Sep 4 17:11:43.240468 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Sep 4 17:11:43.240705 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Sep 4 17:11:43.240917 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Sep 4 17:11:43.242213 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Sep 4 17:11:43.242474 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Sep 4 17:11:43.242691 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Sep 4 17:11:43.242898 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Sep 4 17:11:43.245307 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Sep 4 17:11:43.245562 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Sep 4 17:11:43.245791 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Sep 4 17:11:43.246179 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Sep 4 17:11:43.246397 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Sep 4 17:11:43.246599 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Sep 4 17:11:43.246809 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Sep 4 17:11:43.247018 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Sep 4 17:11:43.247211 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 4 17:11:43.247389 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Sep 4 17:11:43.247414 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 4 17:11:43.247434 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 4 17:11:43.247454 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 4 17:11:43.247472 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 4 17:11:43.247491 kernel: iommu: Default domain type: Translated Sep 4 17:11:43.247510 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 17:11:43.247534 kernel: efivars: Registered efivars operations Sep 4 17:11:43.247553 kernel: vgaarb: loaded Sep 4 17:11:43.247571 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 17:11:43.247590 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 17:11:43.247608 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 17:11:43.247627 kernel: pnp: PnP ACPI init Sep 4 17:11:43.247830 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Sep 4 17:11:43.247858 kernel: pnp: PnP ACPI: found 1 devices Sep 4 17:11:43.247883 kernel: NET: Registered PF_INET protocol family Sep 4 17:11:43.247903 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 17:11:43.247922 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 17:11:43.247940 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 17:11:43.249468 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 17:11:43.249511 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 17:11:43.249531 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 17:11:43.249550 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:11:43.249569 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 17:11:43.249597 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 17:11:43.249616 kernel: PCI: CLS 0 bytes, default 64 Sep 4 17:11:43.249634 kernel: kvm [1]: HYP mode not available Sep 4 17:11:43.249653 kernel: Initialise system trusted keyrings Sep 4 17:11:43.249672 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 17:11:43.249690 kernel: Key type asymmetric registered Sep 4 17:11:43.249709 kernel: Asymmetric key parser 'x509' registered Sep 4 17:11:43.249727 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 17:11:43.249746 kernel: io scheduler mq-deadline registered Sep 4 17:11:43.249769 kernel: io scheduler kyber registered Sep 4 17:11:43.249787 kernel: io scheduler bfq registered Sep 4 17:11:43.250098 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Sep 4 17:11:43.250129 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 4 17:11:43.250148 kernel: ACPI: button: Power Button [PWRB] Sep 4 17:11:43.250167 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Sep 4 17:11:43.250186 kernel: ACPI: button: Sleep Button [SLPB] Sep 4 17:11:43.250205 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 17:11:43.250231 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Sep 4 17:11:43.250438 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Sep 4 17:11:43.250465 kernel: printk: console [ttyS0] disabled Sep 4 17:11:43.250485 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Sep 4 17:11:43.250504 kernel: printk: console [ttyS0] enabled Sep 4 17:11:43.250522 kernel: printk: bootconsole [uart0] disabled Sep 4 17:11:43.250541 kernel: thunder_xcv, ver 1.0 Sep 4 17:11:43.250559 kernel: thunder_bgx, ver 1.0 Sep 4 17:11:43.250578 kernel: nicpf, ver 1.0 Sep 4 17:11:43.250596 kernel: nicvf, ver 1.0 Sep 4 17:11:43.250816 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 17:11:43.251050 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-09-04T17:11:42 UTC (1725469902) Sep 4 17:11:43.251078 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 17:11:43.251097 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Sep 4 17:11:43.251117 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 4 17:11:43.251136 kernel: watchdog: Hard watchdog permanently disabled Sep 4 17:11:43.251155 kernel: NET: Registered PF_INET6 protocol family Sep 4 17:11:43.251173 kernel: Segment Routing with IPv6 Sep 4 17:11:43.251198 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 17:11:43.251217 kernel: NET: Registered PF_PACKET protocol family Sep 4 17:11:43.251235 kernel: Key type dns_resolver registered Sep 4 17:11:43.251254 kernel: registered taskstats version 1 Sep 4 17:11:43.251272 kernel: Loading compiled-in X.509 certificates Sep 4 17:11:43.251291 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 1f5b9f288f9cae6ec9698678cdc0f614482066f7' Sep 4 17:11:43.251310 kernel: Key type .fscrypt registered Sep 4 17:11:43.251328 kernel: Key type fscrypt-provisioning registered Sep 4 17:11:43.251346 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 17:11:43.251385 kernel: ima: Allocated hash algorithm: sha1 Sep 4 17:11:43.251410 kernel: ima: No architecture policies found Sep 4 17:11:43.251430 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 17:11:43.251450 kernel: clk: Disabling unused clocks Sep 4 17:11:43.251469 kernel: Freeing unused kernel memory: 39040K Sep 4 17:11:43.251488 kernel: Run /init as init process Sep 4 17:11:43.251507 kernel: with arguments: Sep 4 17:11:43.251527 kernel: /init Sep 4 17:11:43.251545 kernel: with environment: Sep 4 17:11:43.251571 kernel: HOME=/ Sep 4 17:11:43.251592 kernel: TERM=linux Sep 4 17:11:43.251611 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 17:11:43.251635 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) Sep 4 17:11:43.251659 systemd[1]: Detected virtualization amazon. Sep 4 17:11:43.251680 systemd[1]: Detected architecture arm64. Sep 4 17:11:43.251700 systemd[1]: Running in initrd. Sep 4 17:11:43.251720 systemd[1]: No hostname configured, using default hostname. Sep 4 17:11:43.251744 systemd[1]: Hostname set to . Sep 4 17:11:43.251766 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:11:43.251786 systemd[1]: Queued start job for default target initrd.target. Sep 4 17:11:43.251807 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:11:43.251828 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:11:43.251850 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 17:11:43.251871 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:11:43.251899 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 17:11:43.251922 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 17:11:43.251946 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 17:11:43.251992 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 17:11:43.252016 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:11:43.252037 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:11:43.252058 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:11:43.252085 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:11:43.252106 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:11:43.252127 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:11:43.252147 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:11:43.252168 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:11:43.252190 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 17:11:43.252211 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 17:11:43.252232 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:11:43.252253 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:11:43.252278 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:11:43.252299 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:11:43.252319 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 17:11:43.252340 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:11:43.252360 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 17:11:43.252381 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 17:11:43.252401 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:11:43.252422 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:11:43.252447 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:11:43.252468 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 17:11:43.252488 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:11:43.252564 systemd-journald[251]: Collecting audit messages is disabled. Sep 4 17:11:43.252614 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 17:11:43.252637 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 17:11:43.252658 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 17:11:43.252678 systemd-journald[251]: Journal started Sep 4 17:11:43.252720 systemd-journald[251]: Runtime Journal (/run/log/journal/ec23a5357cb86fdd707fe88c4eeeea18) is 8.0M, max 75.3M, 67.3M free. Sep 4 17:11:43.213153 systemd-modules-load[252]: Inserted module 'overlay' Sep 4 17:11:43.267986 kernel: Bridge firewalling registered Sep 4 17:11:43.268030 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:11:43.259488 systemd-modules-load[252]: Inserted module 'br_netfilter' Sep 4 17:11:43.272888 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:11:43.279342 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:11:43.295431 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:11:43.304618 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:11:43.313363 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Sep 4 17:11:43.329930 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 17:11:43.340038 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:11:43.365068 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:11:43.380311 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:11:43.394800 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:11:43.400890 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:11:43.415381 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 17:11:43.424640 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:11:43.451548 dracut-cmdline[288]: dracut-dracut-053 Sep 4 17:11:43.462000 dracut-cmdline[288]: 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=7913866621ae0af53522ae1b4ff4e1e453dd69d966d437a439147039341ecbbc Sep 4 17:11:43.512279 systemd-resolved[290]: Positive Trust Anchors: Sep 4 17:11:43.512317 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:11:43.512378 systemd-resolved[290]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Sep 4 17:11:43.640998 kernel: SCSI subsystem initialized Sep 4 17:11:43.649994 kernel: Loading iSCSI transport class v2.0-870. Sep 4 17:11:43.662002 kernel: iscsi: registered transport (tcp) Sep 4 17:11:43.685028 kernel: iscsi: registered transport (qla4xxx) Sep 4 17:11:43.685101 kernel: QLogic iSCSI HBA Driver Sep 4 17:11:43.745008 kernel: random: crng init done Sep 4 17:11:43.745336 systemd-resolved[290]: Defaulting to hostname 'linux'. Sep 4 17:11:43.750716 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:11:43.757045 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:11:43.781053 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 17:11:43.790211 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 17:11:43.828853 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 17:11:43.828928 kernel: device-mapper: uevent: version 1.0.3 Sep 4 17:11:43.830561 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 17:11:43.897023 kernel: raid6: neonx8 gen() 6691 MB/s Sep 4 17:11:43.914009 kernel: raid6: neonx4 gen() 6408 MB/s Sep 4 17:11:43.931004 kernel: raid6: neonx2 gen() 5348 MB/s Sep 4 17:11:43.947996 kernel: raid6: neonx1 gen() 3911 MB/s Sep 4 17:11:43.964994 kernel: raid6: int64x8 gen() 3783 MB/s Sep 4 17:11:43.981994 kernel: raid6: int64x4 gen() 3684 MB/s Sep 4 17:11:43.998997 kernel: raid6: int64x2 gen() 3561 MB/s Sep 4 17:11:44.016800 kernel: raid6: int64x1 gen() 2764 MB/s Sep 4 17:11:44.016841 kernel: raid6: using algorithm neonx8 gen() 6691 MB/s Sep 4 17:11:44.034794 kernel: raid6: .... xor() 4861 MB/s, rmw enabled Sep 4 17:11:44.034860 kernel: raid6: using neon recovery algorithm Sep 4 17:11:44.042998 kernel: xor: measuring software checksum speed Sep 4 17:11:44.045760 kernel: 8regs : 11030 MB/sec Sep 4 17:11:44.045797 kernel: 32regs : 11941 MB/sec Sep 4 17:11:44.048608 kernel: arm64_neon : 9534 MB/sec Sep 4 17:11:44.048643 kernel: xor: using function: 32regs (11941 MB/sec) Sep 4 17:11:44.135011 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 17:11:44.155045 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:11:44.168293 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:11:44.203956 systemd-udevd[472]: Using default interface naming scheme 'v255'. Sep 4 17:11:44.211934 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:11:44.230237 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 17:11:44.271495 dracut-pre-trigger[480]: rd.md=0: removing MD RAID activation Sep 4 17:11:44.333008 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:11:44.344408 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:11:44.464261 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:11:44.482354 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 17:11:44.537633 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 17:11:44.547901 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:11:44.558897 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:11:44.569138 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:11:44.584880 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 17:11:44.639162 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:11:44.655174 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 4 17:11:44.655236 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Sep 4 17:11:44.674272 kernel: ena 0000:00:05.0: ENA device version: 0.10 Sep 4 17:11:44.674608 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Sep 4 17:11:44.679197 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:11:44.683644 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:11:44.696292 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:11:86:73:01:29 Sep 4 17:11:44.695336 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:11:44.700159 (udev-worker)[523]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:11:44.724771 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Sep 4 17:11:44.724816 kernel: nvme nvme0: pci function 0000:00:04.0 Sep 4 17:11:44.715138 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:11:44.736254 kernel: nvme nvme0: 2/0/0 default/read/poll queues Sep 4 17:11:44.715421 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:11:44.726257 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:11:44.748542 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 17:11:44.748580 kernel: GPT:9289727 != 16777215 Sep 4 17:11:44.744273 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:11:44.759316 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 17:11:44.759354 kernel: GPT:9289727 != 16777215 Sep 4 17:11:44.759379 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 17:11:44.759403 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Sep 4 17:11:44.786886 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:11:44.805274 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 17:11:44.850094 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:11:44.884588 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (525) Sep 4 17:11:44.918056 kernel: BTRFS: device fsid 2be47701-3393-455e-86fc-33755ceb9c20 devid 1 transid 35 /dev/nvme0n1p3 scanned by (udev-worker) (530) Sep 4 17:11:45.005028 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Sep 4 17:11:45.026572 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Sep 4 17:11:45.043797 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Sep 4 17:11:45.050039 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Sep 4 17:11:45.079642 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Sep 4 17:11:45.090274 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 17:11:45.122030 disk-uuid[662]: Primary Header is updated. Sep 4 17:11:45.122030 disk-uuid[662]: Secondary Entries is updated. Sep 4 17:11:45.122030 disk-uuid[662]: Secondary Header is updated. Sep 4 17:11:45.133198 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Sep 4 17:11:45.142000 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Sep 4 17:11:46.150040 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Sep 4 17:11:46.151008 disk-uuid[663]: The operation has completed successfully. Sep 4 17:11:46.334035 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 17:11:46.334267 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 17:11:46.378276 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 17:11:46.401891 sh[921]: Success Sep 4 17:11:46.432003 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 4 17:11:46.552309 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 17:11:46.571274 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 17:11:46.586632 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 17:11:46.600033 kernel: BTRFS info (device dm-0): first mount of filesystem 2be47701-3393-455e-86fc-33755ceb9c20 Sep 4 17:11:46.600096 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:11:46.600135 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 17:11:46.600767 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 17:11:46.603057 kernel: BTRFS info (device dm-0): using free space tree Sep 4 17:11:46.674007 kernel: BTRFS info (device dm-0): enabling ssd optimizations Sep 4 17:11:46.731811 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 17:11:46.732337 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 17:11:46.746332 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 17:11:46.751661 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 17:11:46.783229 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:11:46.783304 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:11:46.784916 kernel: BTRFS info (device nvme0n1p6): using free space tree Sep 4 17:11:46.790606 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Sep 4 17:11:46.810691 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 17:11:46.814478 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:11:46.831021 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 17:11:46.842314 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 17:11:46.954549 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:11:46.968284 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:11:47.028204 systemd-networkd[1114]: lo: Link UP Sep 4 17:11:47.028229 systemd-networkd[1114]: lo: Gained carrier Sep 4 17:11:47.030740 systemd-networkd[1114]: Enumeration completed Sep 4 17:11:47.031476 systemd-networkd[1114]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:11:47.031483 systemd-networkd[1114]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:11:47.032552 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:11:47.038556 systemd[1]: Reached target network.target - Network. Sep 4 17:11:47.062129 systemd-networkd[1114]: eth0: Link UP Sep 4 17:11:47.062144 systemd-networkd[1114]: eth0: Gained carrier Sep 4 17:11:47.062162 systemd-networkd[1114]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:11:47.080061 systemd-networkd[1114]: eth0: DHCPv4 address 172.31.19.17/20, gateway 172.31.16.1 acquired from 172.31.16.1 Sep 4 17:11:47.179105 ignition[1037]: Ignition 2.18.0 Sep 4 17:11:47.179133 ignition[1037]: Stage: fetch-offline Sep 4 17:11:47.179632 ignition[1037]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:47.179656 ignition[1037]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:47.181095 ignition[1037]: Ignition finished successfully Sep 4 17:11:47.195098 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:11:47.212401 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 17:11:47.236907 ignition[1124]: Ignition 2.18.0 Sep 4 17:11:47.237470 ignition[1124]: Stage: fetch Sep 4 17:11:47.238094 ignition[1124]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:47.238119 ignition[1124]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:47.238286 ignition[1124]: PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:47.255091 ignition[1124]: PUT result: OK Sep 4 17:11:47.258588 ignition[1124]: parsed url from cmdline: "" Sep 4 17:11:47.258604 ignition[1124]: no config URL provided Sep 4 17:11:47.258620 ignition[1124]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 17:11:47.258902 ignition[1124]: no config at "/usr/lib/ignition/user.ign" Sep 4 17:11:47.258937 ignition[1124]: PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:47.263452 ignition[1124]: PUT result: OK Sep 4 17:11:47.263597 ignition[1124]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Sep 4 17:11:47.268541 ignition[1124]: GET result: OK Sep 4 17:11:47.269525 ignition[1124]: parsing config with SHA512: 9660b242c5f5fb8011b0a9c65de1489292d5a08554ea0088ce6eb1b1f4f8d895160dd9cf506f458d093767ae88df731141f469d70eee4a463f59d0ca09a6e4d9 Sep 4 17:11:47.278482 unknown[1124]: fetched base config from "system" Sep 4 17:11:47.279070 unknown[1124]: fetched base config from "system" Sep 4 17:11:47.279700 ignition[1124]: fetch: fetch complete Sep 4 17:11:47.279085 unknown[1124]: fetched user config from "aws" Sep 4 17:11:47.279711 ignition[1124]: fetch: fetch passed Sep 4 17:11:47.279789 ignition[1124]: Ignition finished successfully Sep 4 17:11:47.290403 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 17:11:47.313345 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 17:11:47.335566 ignition[1132]: Ignition 2.18.0 Sep 4 17:11:47.335587 ignition[1132]: Stage: kargs Sep 4 17:11:47.336238 ignition[1132]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:47.336263 ignition[1132]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:47.336397 ignition[1132]: PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:47.338509 ignition[1132]: PUT result: OK Sep 4 17:11:47.351953 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 17:11:47.342745 ignition[1132]: kargs: kargs passed Sep 4 17:11:47.342839 ignition[1132]: Ignition finished successfully Sep 4 17:11:47.376381 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 17:11:47.400411 ignition[1140]: Ignition 2.18.0 Sep 4 17:11:47.401013 ignition[1140]: Stage: disks Sep 4 17:11:47.401812 ignition[1140]: no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:47.401836 ignition[1140]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:47.402026 ignition[1140]: PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:47.406781 ignition[1140]: PUT result: OK Sep 4 17:11:47.416281 ignition[1140]: disks: disks passed Sep 4 17:11:47.416644 ignition[1140]: Ignition finished successfully Sep 4 17:11:47.422180 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 17:11:47.429579 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 17:11:47.434856 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 17:11:47.440373 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:11:47.440577 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:11:47.449689 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:11:47.470305 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 17:11:47.499628 systemd-fsck[1149]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 4 17:11:47.511451 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 17:11:47.530353 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 17:11:47.614274 kernel: EXT4-fs (nvme0n1p9): mounted filesystem f2f4f3ba-c5a3-49c0-ace4-444935e9934b r/w with ordered data mode. Quota mode: none. Sep 4 17:11:47.615190 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 17:11:47.620252 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 17:11:47.652130 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:11:47.658823 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 17:11:47.667592 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 17:11:47.667701 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 17:11:47.667755 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:11:47.692753 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1168) Sep 4 17:11:47.696702 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:11:47.696751 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:11:47.697920 kernel: BTRFS info (device nvme0n1p6): using free space tree Sep 4 17:11:47.704007 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Sep 4 17:11:47.707183 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:11:47.711564 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 17:11:47.743223 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 17:11:48.022394 initrd-setup-root[1192]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 17:11:48.032566 initrd-setup-root[1199]: cut: /sysroot/etc/group: No such file or directory Sep 4 17:11:48.042741 initrd-setup-root[1206]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 17:11:48.052998 initrd-setup-root[1213]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 17:11:48.322220 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 17:11:48.336183 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 17:11:48.343254 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 17:11:48.367997 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:11:48.368426 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 17:11:48.394340 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 17:11:48.412932 ignition[1283]: INFO : Ignition 2.18.0 Sep 4 17:11:48.412932 ignition[1283]: INFO : Stage: mount Sep 4 17:11:48.418204 ignition[1283]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:48.418204 ignition[1283]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:48.418204 ignition[1283]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:48.418204 ignition[1283]: INFO : PUT result: OK Sep 4 17:11:48.434207 ignition[1283]: INFO : mount: mount passed Sep 4 17:11:48.436913 ignition[1283]: INFO : Ignition finished successfully Sep 4 17:11:48.440037 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 17:11:48.453170 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 17:11:48.623403 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 17:11:48.653010 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1293) Sep 4 17:11:48.656741 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 26eaee0d-fa47-45db-8665-f2efa4a46ac0 Sep 4 17:11:48.656792 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Sep 4 17:11:48.658016 kernel: BTRFS info (device nvme0n1p6): using free space tree Sep 4 17:11:48.662999 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Sep 4 17:11:48.665858 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 17:11:48.701002 ignition[1310]: INFO : Ignition 2.18.0 Sep 4 17:11:48.703469 ignition[1310]: INFO : Stage: files Sep 4 17:11:48.703469 ignition[1310]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:48.703469 ignition[1310]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:48.703469 ignition[1310]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:48.713478 ignition[1310]: INFO : PUT result: OK Sep 4 17:11:48.717446 ignition[1310]: DEBUG : files: compiled without relabeling support, skipping Sep 4 17:11:48.720778 ignition[1310]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 17:11:48.720778 ignition[1310]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 17:11:48.731255 ignition[1310]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 17:11:48.735367 ignition[1310]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 17:11:48.742029 unknown[1310]: wrote ssh authorized keys file for user: core Sep 4 17:11:48.744707 ignition[1310]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 17:11:48.754004 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Sep 4 17:11:48.758033 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Sep 4 17:11:48.918134 systemd-networkd[1114]: eth0: Gained IPv6LL Sep 4 17:11:49.110559 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 4 17:11:49.500948 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Sep 4 17:11:49.510826 ignition[1310]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:11:49.510826 ignition[1310]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 17:11:49.510826 ignition[1310]: INFO : files: files passed Sep 4 17:11:49.510826 ignition[1310]: INFO : Ignition finished successfully Sep 4 17:11:49.523041 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 17:11:49.535396 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 17:11:49.536780 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 17:11:49.557786 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 17:11:49.558909 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 17:11:49.580590 initrd-setup-root-after-ignition[1339]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:11:49.580590 initrd-setup-root-after-ignition[1339]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:11:49.588645 initrd-setup-root-after-ignition[1343]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 17:11:49.594536 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:11:49.597372 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 17:11:49.612378 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 17:11:49.663251 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 17:11:49.663462 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 17:11:49.667816 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 17:11:49.670820 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 17:11:49.672927 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 17:11:49.691278 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 17:11:49.724085 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:11:49.737565 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 17:11:49.763946 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:11:49.770580 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:11:49.773650 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 17:11:49.778891 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 17:11:49.779345 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 17:11:49.788024 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 17:11:49.791056 systemd[1]: Stopped target basic.target - Basic System. Sep 4 17:11:49.797487 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 17:11:49.800617 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 17:11:49.807810 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 17:11:49.811175 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 17:11:49.817912 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 17:11:49.821186 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 17:11:49.828534 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 17:11:49.831104 systemd[1]: Stopped target swap.target - Swaps. Sep 4 17:11:49.833201 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 17:11:49.833468 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 17:11:49.844532 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:11:49.847183 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:11:49.850200 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 17:11:49.857247 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:11:49.859898 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 17:11:49.860175 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 17:11:49.868663 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 17:11:49.869151 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 17:11:49.877277 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 17:11:49.877507 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 17:11:49.893474 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 17:11:49.893625 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 17:11:49.893891 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:11:49.900107 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 17:11:49.912169 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 17:11:49.913264 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:11:49.928562 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 17:11:49.928813 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 17:11:49.943605 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 17:11:49.976126 ignition[1363]: INFO : Ignition 2.18.0 Sep 4 17:11:49.976126 ignition[1363]: INFO : Stage: umount Sep 4 17:11:49.976126 ignition[1363]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 17:11:49.976126 ignition[1363]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Sep 4 17:11:49.976126 ignition[1363]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Sep 4 17:11:49.943793 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 17:11:49.993488 ignition[1363]: INFO : PUT result: OK Sep 4 17:11:49.993488 ignition[1363]: INFO : umount: umount passed Sep 4 17:11:49.993488 ignition[1363]: INFO : Ignition finished successfully Sep 4 17:11:49.975311 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 17:11:49.994206 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 17:11:49.994425 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 17:11:50.004237 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 17:11:50.004404 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 17:11:50.006919 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 17:11:50.007043 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 17:11:50.009504 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 17:11:50.009584 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 17:11:50.011995 systemd[1]: Stopped target network.target - Network. Sep 4 17:11:50.015055 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 17:11:50.015153 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 17:11:50.019665 systemd[1]: Stopped target paths.target - Path Units. Sep 4 17:11:50.021699 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 17:11:50.026741 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:11:50.057248 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 17:11:50.059417 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 17:11:50.061749 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 17:11:50.061844 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 17:11:50.064300 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 17:11:50.064378 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 17:11:50.066853 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 17:11:50.066956 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 17:11:50.069586 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 17:11:50.069676 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 17:11:50.072619 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 17:11:50.075099 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 17:11:50.107098 systemd-networkd[1114]: eth0: DHCPv6 lease lost Sep 4 17:11:50.118545 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 17:11:50.118797 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 17:11:50.136293 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 17:11:50.136491 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 17:11:50.145647 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 17:11:50.145754 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:11:50.157691 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 17:11:50.164180 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 17:11:50.164303 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 17:11:50.167523 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 17:11:50.167616 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:11:50.171058 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 17:11:50.171148 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 17:11:50.176569 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 17:11:50.176667 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:11:50.176932 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:11:50.216359 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 17:11:50.216792 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 17:11:50.223182 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 17:11:50.225052 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:11:50.229721 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 17:11:50.229853 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 17:11:50.242156 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 17:11:50.242251 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:11:50.244826 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 17:11:50.244918 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 17:11:50.247786 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 17:11:50.247877 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 17:11:50.250476 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 17:11:50.250560 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 17:11:50.275712 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 17:11:50.279067 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 17:11:50.280494 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:11:50.296999 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 17:11:50.297118 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:11:50.304759 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 17:11:50.304986 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 17:11:50.353564 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 17:11:50.353787 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 17:11:50.360136 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 17:11:50.362530 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 17:11:50.362637 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 17:11:50.378385 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 17:11:50.393921 systemd[1]: Switching root. Sep 4 17:11:50.434719 systemd-journald[251]: Journal stopped Sep 4 17:11:52.501504 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Sep 4 17:11:52.501636 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 17:11:52.501679 kernel: SELinux: policy capability open_perms=1 Sep 4 17:11:52.501718 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 17:11:52.501748 kernel: SELinux: policy capability always_check_network=0 Sep 4 17:11:52.501783 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 17:11:52.501814 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 17:11:52.501844 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 17:11:52.501891 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 17:11:52.501921 kernel: audit: type=1403 audit(1725469910.908:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 17:11:52.501954 systemd[1]: Successfully loaded SELinux policy in 56.167ms. Sep 4 17:11:52.502032 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 26.434ms. Sep 4 17:11:52.502067 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) Sep 4 17:11:52.502099 systemd[1]: Detected virtualization amazon. Sep 4 17:11:52.502134 systemd[1]: Detected architecture arm64. Sep 4 17:11:52.502163 systemd[1]: Detected first boot. Sep 4 17:11:52.502195 systemd[1]: Initializing machine ID from VM UUID. Sep 4 17:11:52.502227 zram_generator::config[1406]: No configuration found. Sep 4 17:11:52.502260 systemd[1]: Populated /etc with preset unit settings. Sep 4 17:11:52.502292 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 17:11:52.502329 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 17:11:52.502362 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 17:11:52.502395 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 17:11:52.502426 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 17:11:52.502458 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 17:11:52.502489 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 17:11:52.502520 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 17:11:52.502555 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 17:11:52.502587 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 17:11:52.502617 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 17:11:52.502648 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 17:11:52.502677 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 17:11:52.502709 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 17:11:52.502741 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 17:11:52.502772 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 17:11:52.502806 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 17:11:52.502841 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 17:11:52.502875 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 17:11:52.502905 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 17:11:52.502934 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 17:11:52.507008 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 17:11:52.507065 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 17:11:52.507097 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 17:11:52.507127 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 17:11:52.507166 systemd[1]: Reached target slices.target - Slice Units. Sep 4 17:11:52.507199 systemd[1]: Reached target swap.target - Swaps. Sep 4 17:11:52.507228 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 17:11:52.507259 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 17:11:52.507288 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 17:11:52.507318 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 17:11:52.507347 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 17:11:52.507379 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 17:11:52.507412 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 17:11:52.507455 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 17:11:52.507487 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 17:11:52.507517 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 17:11:52.507548 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 17:11:52.507579 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 17:11:52.507609 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). Sep 4 17:11:52.507640 systemd[1]: Reached target machines.target - Containers. Sep 4 17:11:52.507671 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 17:11:52.507701 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:11:52.507737 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 17:11:52.507768 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 17:11:52.507800 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:11:52.507829 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:11:52.507858 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:11:52.507890 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 17:11:52.507918 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:11:52.507951 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 17:11:52.508004 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 17:11:52.508038 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 17:11:52.508068 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 17:11:52.508097 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 17:11:52.508126 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 17:11:52.508155 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 17:11:52.508184 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 17:11:52.509080 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 17:11:52.509145 kernel: fuse: init (API version 7.39) Sep 4 17:11:52.509188 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 17:11:52.509220 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 17:11:52.509252 systemd[1]: Stopped verity-setup.service. Sep 4 17:11:52.509281 kernel: loop: module loaded Sep 4 17:11:52.509309 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 17:11:52.509338 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 17:11:52.509367 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 17:11:52.509395 kernel: ACPI: bus type drm_connector registered Sep 4 17:11:52.509422 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 17:11:52.509456 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 17:11:52.509486 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 17:11:52.509516 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 17:11:52.509545 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 17:11:52.509574 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 17:11:52.509607 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:11:52.509636 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:11:52.509664 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:11:52.509693 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:11:52.509721 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:11:52.509750 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:11:52.509779 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 17:11:52.509854 systemd-journald[1487]: Collecting audit messages is disabled. Sep 4 17:11:52.509925 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 17:11:52.509956 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:11:52.511913 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:11:52.511953 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 17:11:52.512131 systemd-journald[1487]: Journal started Sep 4 17:11:52.512189 systemd-journald[1487]: Runtime Journal (/run/log/journal/ec23a5357cb86fdd707fe88c4eeeea18) is 8.0M, max 75.3M, 67.3M free. Sep 4 17:11:51.880136 systemd[1]: Queued start job for default target multi-user.target. Sep 4 17:11:51.911768 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Sep 4 17:11:51.912585 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 17:11:52.516990 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 17:11:52.524397 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 17:11:52.530489 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 17:11:52.546366 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 17:11:52.566787 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 17:11:52.581356 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 17:11:52.594609 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 17:11:52.601183 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 17:11:52.601255 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 17:11:52.609712 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 17:11:52.625232 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 17:11:52.633676 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 17:11:52.638220 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:11:52.649265 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 17:11:52.666359 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 17:11:52.671220 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:11:52.675311 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 17:11:52.679794 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:11:52.685307 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 17:11:52.694111 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 17:11:52.705345 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 17:11:52.715528 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 17:11:52.720760 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 17:11:52.731617 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 17:11:52.745185 systemd-journald[1487]: Time spent on flushing to /var/log/journal/ec23a5357cb86fdd707fe88c4eeeea18 is 149.951ms for 889 entries. Sep 4 17:11:52.745185 systemd-journald[1487]: System Journal (/var/log/journal/ec23a5357cb86fdd707fe88c4eeeea18) is 8.0M, max 195.6M, 187.6M free. Sep 4 17:11:52.935636 systemd-journald[1487]: Received client request to flush runtime journal. Sep 4 17:11:52.935733 kernel: loop0: detected capacity change from 0 to 194512 Sep 4 17:11:52.935785 kernel: block loop0: the capability attribute has been deprecated. Sep 4 17:11:52.936886 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 17:11:52.771638 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 17:11:52.776766 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 17:11:52.798280 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 17:11:52.803682 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 17:11:52.825145 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 17:11:52.872312 udevadm[1543]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 17:11:52.898493 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 17:11:52.945462 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 17:11:52.947924 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 17:11:52.954759 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 17:11:52.963463 kernel: loop1: detected capacity change from 0 to 51896 Sep 4 17:11:52.976755 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 17:11:52.991268 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 17:11:53.043767 kernel: loop2: detected capacity change from 0 to 113672 Sep 4 17:11:53.081827 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Sep 4 17:11:53.081871 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Sep 4 17:11:53.088980 kernel: loop3: detected capacity change from 0 to 59688 Sep 4 17:11:53.095325 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 17:11:53.141418 kernel: loop4: detected capacity change from 0 to 194512 Sep 4 17:11:53.166037 kernel: loop5: detected capacity change from 0 to 51896 Sep 4 17:11:53.179328 kernel: loop6: detected capacity change from 0 to 113672 Sep 4 17:11:53.196014 kernel: loop7: detected capacity change from 0 to 59688 Sep 4 17:11:53.219227 (sd-merge)[1561]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Sep 4 17:11:53.221631 (sd-merge)[1561]: Merged extensions into '/usr'. Sep 4 17:11:53.239427 systemd[1]: Reloading requested from client PID 1534 ('systemd-sysext') (unit systemd-sysext.service)... Sep 4 17:11:53.239465 systemd[1]: Reloading... Sep 4 17:11:53.447307 zram_generator::config[1585]: No configuration found. Sep 4 17:11:53.760654 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. Sep 4 17:11:53.805002 ldconfig[1529]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 17:11:53.875469 systemd[1]: Reloading finished in 634 ms. Sep 4 17:11:53.925076 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 17:11:53.929634 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 17:11:53.949431 systemd[1]: Starting ensure-sysext.service... Sep 4 17:11:53.965316 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Sep 4 17:11:53.997309 systemd[1]: Reloading requested from client PID 1637 ('systemctl') (unit ensure-sysext.service)... Sep 4 17:11:53.997502 systemd[1]: Reloading... Sep 4 17:11:54.045127 systemd-tmpfiles[1638]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 17:11:54.047436 systemd-tmpfiles[1638]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 17:11:54.051645 systemd-tmpfiles[1638]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 17:11:54.053151 systemd-tmpfiles[1638]: ACLs are not supported, ignoring. Sep 4 17:11:54.053300 systemd-tmpfiles[1638]: ACLs are not supported, ignoring. Sep 4 17:11:54.063534 systemd-tmpfiles[1638]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:11:54.064068 systemd-tmpfiles[1638]: Skipping /boot Sep 4 17:11:54.094495 systemd-tmpfiles[1638]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 17:11:54.094683 systemd-tmpfiles[1638]: Skipping /boot Sep 4 17:11:54.207000 zram_generator::config[1664]: No configuration found. Sep 4 17:11:54.427656 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. Sep 4 17:11:54.539930 systemd[1]: Reloading finished in 540 ms. Sep 4 17:11:54.566822 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 17:11:54.575923 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Sep 4 17:11:54.593345 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 17:11:54.608624 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 17:11:54.621880 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 17:11:54.629417 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 17:11:54.644522 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 17:11:54.652228 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 17:11:54.669180 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:11:54.680483 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 17:11:54.688514 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 17:11:54.696532 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 17:11:54.699806 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:11:54.711037 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 17:11:54.719448 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 17:11:54.722211 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 17:11:54.722650 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 17:11:54.731535 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 17:11:54.747037 systemd[1]: Finished ensure-sysext.service. Sep 4 17:11:54.783702 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 17:11:54.802658 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 17:11:54.805938 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 17:11:54.811613 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 17:11:54.840945 augenrules[1746]: No rules Sep 4 17:11:54.846849 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 17:11:54.853497 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 17:11:54.855250 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 17:11:54.860776 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 17:11:54.866523 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 17:11:54.866912 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 17:11:54.881308 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 17:11:54.881743 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 17:11:54.888400 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 17:11:54.900860 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 17:11:54.905778 systemd-udevd[1728]: Using default interface naming scheme 'v255'. Sep 4 17:11:54.912336 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 17:11:54.917251 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). Sep 4 17:11:54.917596 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 17:11:54.961152 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 17:11:54.968270 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 17:11:54.985266 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 17:11:55.119626 systemd-networkd[1768]: lo: Link UP Sep 4 17:11:55.120144 systemd-networkd[1768]: lo: Gained carrier Sep 4 17:11:55.121336 systemd-networkd[1768]: Enumeration completed Sep 4 17:11:55.121954 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 17:11:55.136237 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 17:11:55.157195 systemd-resolved[1727]: Positive Trust Anchors: Sep 4 17:11:55.157232 systemd-resolved[1727]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 17:11:55.157293 systemd-resolved[1727]: 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 corp home internal intranet lan local private test Sep 4 17:11:55.170659 systemd-resolved[1727]: Defaulting to hostname 'linux'. Sep 4 17:11:55.175040 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 17:11:55.180009 systemd[1]: Reached target network.target - Network. Sep 4 17:11:55.184328 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 17:11:55.199006 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1783) Sep 4 17:11:55.240699 (udev-worker)[1765]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:11:55.245646 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 17:11:55.338835 systemd-networkd[1768]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:11:55.339835 systemd-networkd[1768]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 17:11:55.342107 systemd-networkd[1768]: eth0: Link UP Sep 4 17:11:55.343487 systemd-networkd[1768]: eth0: Gained carrier Sep 4 17:11:55.343527 systemd-networkd[1768]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 17:11:55.355101 systemd-networkd[1768]: eth0: DHCPv4 address 172.31.19.17/20, gateway 172.31.16.1 acquired from 172.31.16.1 Sep 4 17:11:55.433480 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 17:11:55.471135 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 35 scanned by (udev-worker) (1765) Sep 4 17:11:55.578505 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 17:11:55.640620 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Sep 4 17:11:55.649295 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 17:11:55.652730 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 17:11:55.673365 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 17:11:55.694645 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 17:11:55.707559 lvm[1888]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:11:55.745847 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 17:11:55.749391 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 17:11:55.752148 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 17:11:55.754814 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 17:11:55.757738 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 17:11:55.760873 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 17:11:55.763673 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 17:11:55.766611 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 17:11:55.769503 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 17:11:55.769556 systemd[1]: Reached target paths.target - Path Units. Sep 4 17:11:55.771565 systemd[1]: Reached target timers.target - Timer Units. Sep 4 17:11:55.774888 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 17:11:55.780357 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 17:11:55.794376 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 17:11:55.800290 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 17:11:55.804254 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 17:11:55.807453 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 17:11:55.811193 systemd[1]: Reached target basic.target - Basic System. Sep 4 17:11:55.817461 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:11:55.817529 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 17:11:55.823489 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 17:11:55.829368 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 17:11:55.837371 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 17:11:55.845511 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 17:11:55.853616 lvm[1894]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 17:11:55.861234 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 17:11:55.863475 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). Sep 4 17:11:55.867727 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 17:11:55.877387 systemd[1]: Started ntpd.service - Network Time Service. Sep 4 17:11:55.884924 systemd[1]: Starting setup-oem.service - Setup OEM... Sep 4 17:11:55.891542 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 17:11:55.896573 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 17:11:55.900998 jq[1898]: false Sep 4 17:11:55.916778 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 17:11:55.919998 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 17:11:55.920903 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 17:11:55.925350 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 17:11:55.935155 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 17:11:55.950739 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 17:11:55.953392 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 17:11:56.083025 jq[1907]: true Sep 4 17:11:56.085855 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 17:11:56.086250 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 17:11:56.115825 dbus-daemon[1897]: [system] SELinux support is enabled Sep 4 17:11:56.118177 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 17:11:56.130686 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). Sep 4 17:11:56.130834 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 17:11:56.136233 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). Sep 4 17:11:56.136295 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 17:11:56.143609 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 17:11:56.149887 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 17:11:56.150249 (ntainerd)[1925]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 17:11:56.150333 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 17:11:56.160625 dbus-daemon[1897]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1768 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Sep 4 17:11:56.164660 dbus-daemon[1897]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 4 17:11:56.180999 extend-filesystems[1899]: Found loop4 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found loop5 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found loop6 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found loop7 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1p1 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1p2 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1p3 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found usr Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1p4 Sep 4 17:11:56.180999 extend-filesystems[1899]: Found nvme0n1p6 Sep 4 17:11:56.236829 extend-filesystems[1899]: Found nvme0n1p7 Sep 4 17:11:56.236829 extend-filesystems[1899]: Found nvme0n1p9 Sep 4 17:11:56.236829 extend-filesystems[1899]: Checking size of /dev/nvme0n1p9 Sep 4 17:11:56.194557 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: ntpd 4.2.8p17@1.4004-o Wed Sep 4 15:13:39 UTC 2024 (1): Starting Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: ---------------------------------------------------- Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: ntp-4 is maintained by Network Time Foundation, Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: corporation. Support and training for ntp-4 are Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: available at https://www.nwtime.org/support Sep 4 17:11:56.253880 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: ---------------------------------------------------- Sep 4 17:11:56.242401 ntpd[1901]: ntpd 4.2.8p17@1.4004-o Wed Sep 4 15:13:39 UTC 2024 (1): Starting Sep 4 17:11:56.249880 systemd[1]: Finished setup-oem.service - Setup OEM. Sep 4 17:11:56.260631 update_engine[1906]: I0904 17:11:56.255592 1906 main.cc:92] Flatcar Update Engine starting Sep 4 17:11:56.263170 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: proto: precision = 0.096 usec (-23) Sep 4 17:11:56.242449 ntpd[1901]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Sep 4 17:11:56.255272 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 17:11:56.242473 ntpd[1901]: ---------------------------------------------------- Sep 4 17:11:56.242493 ntpd[1901]: ntp-4 is maintained by Network Time Foundation, Sep 4 17:11:56.242512 ntpd[1901]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Sep 4 17:11:56.242531 ntpd[1901]: corporation. Support and training for ntp-4 are Sep 4 17:11:56.242550 ntpd[1901]: available at https://www.nwtime.org/support Sep 4 17:11:56.242568 ntpd[1901]: ---------------------------------------------------- Sep 4 17:11:56.257061 ntpd[1901]: proto: precision = 0.096 usec (-23) Sep 4 17:11:56.266558 jq[1929]: true Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: basedate set to 2024-08-23 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: gps base set to 2024-08-25 (week 2329) Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listen and drop on 0 v6wildcard [::]:123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listen normally on 2 lo 127.0.0.1:123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listen normally on 3 eth0 172.31.19.17:123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listen normally on 4 lo [::1]:123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: bind(21) AF_INET6 fe80::411:86ff:fe73:129%2#123 flags 0x11 failed: Cannot assign requested address Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: unable to create socket on eth0 (5) for fe80::411:86ff:fe73:129%2#123 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: failed to init interface for address fe80::411:86ff:fe73:129%2 Sep 4 17:11:56.283222 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: Listening on routing socket on fd #21 for interface updates Sep 4 17:11:56.266298 ntpd[1901]: basedate set to 2024-08-23 Sep 4 17:11:56.283774 update_engine[1906]: I0904 17:11:56.282820 1906 update_check_scheduler.cc:74] Next update check in 5m55s Sep 4 17:11:56.266331 ntpd[1901]: gps base set to 2024-08-25 (week 2329) Sep 4 17:11:56.275038 ntpd[1901]: Listen and drop on 0 v6wildcard [::]:123 Sep 4 17:11:56.275112 ntpd[1901]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 4 17:11:56.275380 ntpd[1901]: Listen normally on 2 lo 127.0.0.1:123 Sep 4 17:11:56.275441 ntpd[1901]: Listen normally on 3 eth0 172.31.19.17:123 Sep 4 17:11:56.275507 ntpd[1901]: Listen normally on 4 lo [::1]:123 Sep 4 17:11:56.275584 ntpd[1901]: bind(21) AF_INET6 fe80::411:86ff:fe73:129%2#123 flags 0x11 failed: Cannot assign requested address Sep 4 17:11:56.275622 ntpd[1901]: unable to create socket on eth0 (5) for fe80::411:86ff:fe73:129%2#123 Sep 4 17:11:56.275650 ntpd[1901]: failed to init interface for address fe80::411:86ff:fe73:129%2 Sep 4 17:11:56.275699 ntpd[1901]: Listening on routing socket on fd #21 for interface updates Sep 4 17:11:56.286322 ntpd[1901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 4 17:11:56.288395 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 4 17:11:56.288395 ntpd[1901]: 4 Sep 17:11:56 ntpd[1901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 4 17:11:56.286388 ntpd[1901]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 4 17:11:56.293247 systemd[1]: Started update-engine.service - Update Engine. Sep 4 17:11:56.307004 extend-filesystems[1899]: Resized partition /dev/nvme0n1p9 Sep 4 17:11:56.317487 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 17:11:56.325444 coreos-metadata[1896]: Sep 04 17:11:56.325 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Sep 4 17:11:56.327350 coreos-metadata[1896]: Sep 04 17:11:56.327 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Sep 4 17:11:56.328041 coreos-metadata[1896]: Sep 04 17:11:56.327 INFO Fetch successful Sep 4 17:11:56.328041 coreos-metadata[1896]: Sep 04 17:11:56.327 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Sep 4 17:11:56.328858 coreos-metadata[1896]: Sep 04 17:11:56.328 INFO Fetch successful Sep 4 17:11:56.329052 coreos-metadata[1896]: Sep 04 17:11:56.328 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Sep 4 17:11:56.329487 extend-filesystems[1950]: resize2fs 1.47.0 (5-Feb-2023) Sep 4 17:11:56.334287 coreos-metadata[1896]: Sep 04 17:11:56.334 INFO Fetch successful Sep 4 17:11:56.335030 coreos-metadata[1896]: Sep 04 17:11:56.334 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Sep 4 17:11:56.336705 coreos-metadata[1896]: Sep 04 17:11:56.336 INFO Fetch successful Sep 4 17:11:56.336705 coreos-metadata[1896]: Sep 04 17:11:56.336 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Sep 4 17:11:56.337554 coreos-metadata[1896]: Sep 04 17:11:56.337 INFO Fetch failed with 404: resource not found Sep 4 17:11:56.337554 coreos-metadata[1896]: Sep 04 17:11:56.337 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Sep 4 17:11:56.338779 coreos-metadata[1896]: Sep 04 17:11:56.338 INFO Fetch successful Sep 4 17:11:56.338779 coreos-metadata[1896]: Sep 04 17:11:56.338 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Sep 4 17:11:56.339678 coreos-metadata[1896]: Sep 04 17:11:56.339 INFO Fetch successful Sep 4 17:11:56.339678 coreos-metadata[1896]: Sep 04 17:11:56.339 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Sep 4 17:11:56.340631 coreos-metadata[1896]: Sep 04 17:11:56.340 INFO Fetch successful Sep 4 17:11:56.340631 coreos-metadata[1896]: Sep 04 17:11:56.340 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Sep 4 17:11:56.341446 coreos-metadata[1896]: Sep 04 17:11:56.341 INFO Fetch successful Sep 4 17:11:56.341446 coreos-metadata[1896]: Sep 04 17:11:56.341 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Sep 4 17:11:56.341864 coreos-metadata[1896]: Sep 04 17:11:56.341 INFO Fetch successful Sep 4 17:11:56.348638 systemd-logind[1905]: Watching system buttons on /dev/input/event0 (Power Button) Sep 4 17:11:56.388780 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Sep 4 17:11:56.348697 systemd-logind[1905]: Watching system buttons on /dev/input/event1 (Sleep Button) Sep 4 17:11:56.349410 systemd-logind[1905]: New seat seat0. Sep 4 17:11:56.379406 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 17:11:56.440017 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Sep 4 17:11:56.440621 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 17:11:56.446138 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 17:11:56.475918 extend-filesystems[1950]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Sep 4 17:11:56.475918 extend-filesystems[1950]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 4 17:11:56.475918 extend-filesystems[1950]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Sep 4 17:11:56.498573 extend-filesystems[1899]: Resized filesystem in /dev/nvme0n1p9 Sep 4 17:11:56.477296 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 17:11:56.520598 bash[1969]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:11:56.480507 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 17:11:56.533824 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 17:11:56.554907 systemd[1]: Starting sshkeys.service... Sep 4 17:11:56.635979 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 4 17:11:56.650618 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 4 17:11:56.698212 dbus-daemon[1897]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 4 17:11:56.698647 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Sep 4 17:11:56.699253 dbus-daemon[1897]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1938 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Sep 4 17:11:56.724019 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 35 scanned by (udev-worker) (1779) Sep 4 17:11:56.728304 systemd[1]: Starting polkit.service - Authorization Manager... Sep 4 17:11:56.776800 polkitd[1992]: Started polkitd version 121 Sep 4 17:11:56.812205 locksmithd[1949]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 17:11:56.814558 polkitd[1992]: Loading rules from directory /etc/polkit-1/rules.d Sep 4 17:11:56.814679 polkitd[1992]: Loading rules from directory /usr/share/polkit-1/rules.d Sep 4 17:11:56.828029 polkitd[1992]: Finished loading, compiling and executing 2 rules Sep 4 17:11:56.830268 dbus-daemon[1897]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 4 17:11:56.830544 systemd[1]: Started polkit.service - Authorization Manager. Sep 4 17:11:56.835383 polkitd[1992]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Sep 4 17:11:56.838286 coreos-metadata[1987]: Sep 04 17:11:56.838 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Sep 4 17:11:56.842073 coreos-metadata[1987]: Sep 04 17:11:56.840 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Sep 4 17:11:56.842073 coreos-metadata[1987]: Sep 04 17:11:56.842 INFO Fetch successful Sep 4 17:11:56.843278 coreos-metadata[1987]: Sep 04 17:11:56.842 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Sep 4 17:11:56.843278 coreos-metadata[1987]: Sep 04 17:11:56.842 INFO Fetch successful Sep 4 17:11:56.848993 unknown[1987]: wrote ssh authorized keys file for user: core Sep 4 17:11:56.910731 containerd[1925]: time="2024-09-04T17:11:56.910560719Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Sep 4 17:11:56.915190 systemd-resolved[1727]: System hostname changed to 'ip-172-31-19-17'. Sep 4 17:11:56.915198 systemd-hostnamed[1938]: Hostname set to (transient) Sep 4 17:11:56.928624 update-ssh-keys[2028]: Updated "/home/core/.ssh/authorized_keys" Sep 4 17:11:56.931184 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 4 17:11:56.943671 systemd[1]: Finished sshkeys.service. Sep 4 17:11:56.982178 systemd-networkd[1768]: eth0: Gained IPv6LL Sep 4 17:11:56.992093 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 17:11:57.000815 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 17:11:57.019582 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Sep 4 17:11:57.029326 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:11:57.039555 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 17:11:57.175825 containerd[1925]: time="2024-09-04T17:11:57.175651760Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 17:11:57.179514 containerd[1925]: time="2024-09-04T17:11:57.177770132Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.188044340Z" 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.48-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.188168720Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.188574776Z" 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 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.188621408Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.188832536Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189045488Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189106916Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189313088Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189803684Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189849236Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Sep 4 17:11:57.189996 containerd[1925]: time="2024-09-04T17:11:57.189874412Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 17:11:57.196724 containerd[1925]: time="2024-09-04T17:11:57.196659356Z" 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 Sep 4 17:11:57.200584 containerd[1925]: time="2024-09-04T17:11:57.197806557Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 17:11:57.200584 containerd[1925]: time="2024-09-04T17:11:57.199310049Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Sep 4 17:11:57.200584 containerd[1925]: time="2024-09-04T17:11:57.199357269Z" level=info msg="metadata content store policy set" policy=shared Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210118065Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210189369Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210223245Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210275037Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210309789Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210336525Z" level=info msg="NRI interface is disabled by configuration." Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210366789Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210627105Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210674433Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210705633Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210737949Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210777081Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210817545Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.211543 containerd[1925]: time="2024-09-04T17:11:57.210850509Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.212378 containerd[1925]: time="2024-09-04T17:11:57.210881961Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.212378 containerd[1925]: time="2024-09-04T17:11:57.210917793Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.212378 containerd[1925]: time="2024-09-04T17:11:57.210951957Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.215998 containerd[1925]: time="2024-09-04T17:11:57.215040393Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.215998 containerd[1925]: time="2024-09-04T17:11:57.215118573Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 17:11:57.215998 containerd[1925]: time="2024-09-04T17:11:57.215460009Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 17:11:57.215998 containerd[1925]: time="2024-09-04T17:11:57.215912805Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221103645Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221175981Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221234241Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221417757Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221463249Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221496453Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221527149Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221561901Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221597301Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221627661Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221656569Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.221696133Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.222131337Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.222188913Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223009 containerd[1925]: time="2024-09-04T17:11:57.222219861Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223753 containerd[1925]: time="2024-09-04T17:11:57.222252525Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223753 containerd[1925]: time="2024-09-04T17:11:57.222283089Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223753 containerd[1925]: time="2024-09-04T17:11:57.222320145Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223753 containerd[1925]: time="2024-09-04T17:11:57.222350121Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.223753 containerd[1925]: time="2024-09-04T17:11:57.222377469Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.222890529Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.229328769Z" level=info msg="Connect containerd service" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.229402977Z" level=info msg="using legacy CRI server" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.229421889Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.229613493Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.230799621Z" 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" Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.230889645Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 17:11:57.231030 containerd[1925]: time="2024-09-04T17:11:57.230930949Z" 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 Sep 4 17:11:57.234068 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234327573Z" level=info msg="Start subscribing containerd event" Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234477045Z" level=info msg="Start recovering state" Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234621969Z" level=info msg="Start event monitor" Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234652197Z" level=info msg="Start snapshots syncer" Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234675669Z" level=info msg="Start cni network conf syncer for default" Sep 4 17:11:57.239444 containerd[1925]: time="2024-09-04T17:11:57.234696729Z" level=info msg="Start streaming server" Sep 4 17:11:57.248011 containerd[1925]: time="2024-09-04T17:11:57.243286533Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 17:11:57.248011 containerd[1925]: time="2024-09-04T17:11:57.243415629Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 17:11:57.248011 containerd[1925]: time="2024-09-04T17:11:57.243797049Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 17:11:57.248011 containerd[1925]: time="2024-09-04T17:11:57.243912105Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 17:11:57.248011 containerd[1925]: time="2024-09-04T17:11:57.244067793Z" level=info msg="containerd successfully booted in 0.349509s" Sep 4 17:11:57.251788 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 17:11:57.318926 amazon-ssm-agent[2072]: Initializing new seelog logger Sep 4 17:11:57.321017 amazon-ssm-agent[2072]: New Seelog Logger Creation Complete Sep 4 17:11:57.321337 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.321429 amazon-ssm-agent[2072]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 processing appconfig overrides Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 processing appconfig overrides Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO Proxy environment variables: Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.326139 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 processing appconfig overrides Sep 4 17:11:57.337007 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.337007 amazon-ssm-agent[2072]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Sep 4 17:11:57.337007 amazon-ssm-agent[2072]: 2024/09/04 17:11:57 processing appconfig overrides Sep 4 17:11:57.423840 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO https_proxy: Sep 4 17:11:57.524079 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO http_proxy: Sep 4 17:11:57.624064 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO no_proxy: Sep 4 17:11:57.722925 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO Checking if agent identity type OnPrem can be assumed Sep 4 17:11:57.821417 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO Checking if agent identity type EC2 can be assumed Sep 4 17:11:57.921015 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO Agent will take identity from EC2 Sep 4 17:11:58.021953 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] using named pipe channel for IPC Sep 4 17:11:58.122128 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] using named pipe channel for IPC Sep 4 17:11:58.222948 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] using named pipe channel for IPC Sep 4 17:11:58.322346 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Sep 4 17:11:58.410279 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:11:58.428009 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Sep 4 17:11:58.438688 (kubelet)[2123]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 4 17:11:58.526377 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] Starting Core Agent Sep 4 17:11:58.626827 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [amazon-ssm-agent] registrar detected. Attempting registration Sep 4 17:11:58.730040 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [Registrar] Starting registrar module Sep 4 17:11:58.829782 amazon-ssm-agent[2072]: 2024-09-04 17:11:57 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Sep 4 17:11:59.243192 ntpd[1901]: Listen normally on 6 eth0 [fe80::411:86ff:fe73:129%2]:123 Sep 4 17:11:59.243812 ntpd[1901]: 4 Sep 17:11:59 ntpd[1901]: Listen normally on 6 eth0 [fe80::411:86ff:fe73:129%2]:123 Sep 4 17:11:59.399609 sshd_keygen[1935]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 17:11:59.443136 kubelet[2123]: E0904 17:11:59.440910 2123 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" Sep 4 17:11:59.453939 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 4 17:11:59.454356 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 4 17:11:59.454833 systemd[1]: kubelet.service: Consumed 1.354s CPU time. Sep 4 17:11:59.459443 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 17:11:59.476434 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 17:11:59.500340 systemd[1]: Started sshd@0-172.31.19.17:22-139.178.89.65:59202.service - OpenSSH per-connection server daemon (139.178.89.65:59202). Sep 4 17:11:59.511644 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 17:11:59.512026 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 17:11:59.534496 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 17:11:59.583653 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 17:11:59.597709 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 17:11:59.608522 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 17:11:59.611389 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 17:11:59.614361 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 17:11:59.628411 systemd[1]: Startup finished in 1.227s (kernel) + 8.101s (initrd) + 8.773s (userspace) = 18.102s. Sep 4 17:11:59.724089 sshd[2140]: Accepted publickey for core from 139.178.89.65 port 59202 ssh2: RSA SHA256:kUAc/AK3NORsNqodfN7sFAtyAL1l41RPtj57UtNEeKU Sep 4 17:11:59.727164 sshd[2140]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:11:59.747324 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 17:11:59.756648 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 17:11:59.762254 systemd-logind[1905]: New session 1 of user core. Sep 4 17:11:59.794897 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 17:11:59.806431 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 17:11:59.817898 (systemd)[2155]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:11:59.824859 amazon-ssm-agent[2072]: 2024-09-04 17:11:59 INFO [EC2Identity] EC2 registration was successful. Sep 4 17:11:59.857929 amazon-ssm-agent[2072]: 2024-09-04 17:11:59 INFO [CredentialRefresher] credentialRefresher has started Sep 4 17:11:59.857929 amazon-ssm-agent[2072]: 2024-09-04 17:11:59 INFO [CredentialRefresher] Starting credentials refresher loop Sep 4 17:11:59.857929 amazon-ssm-agent[2072]: 2024-09-04 17:11:59 INFO EC2RoleProvider Successfully connected with instance profile role credentials Sep 4 17:11:59.925202 amazon-ssm-agent[2072]: 2024-09-04 17:11:59 INFO [CredentialRefresher] Next credential rotation will be in 30.433326319033334 minutes Sep 4 17:12:00.044296 systemd[2155]: Queued start job for default target default.target. Sep 4 17:12:00.057216 systemd[2155]: Created slice app.slice - User Application Slice. Sep 4 17:12:00.057286 systemd[2155]: Reached target paths.target - Paths. Sep 4 17:12:00.057320 systemd[2155]: Reached target timers.target - Timers. Sep 4 17:12:00.059786 systemd[2155]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 17:12:00.092829 systemd[2155]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 17:12:00.093360 systemd[2155]: Reached target sockets.target - Sockets. Sep 4 17:12:00.093552 systemd[2155]: Reached target basic.target - Basic System. Sep 4 17:12:00.093774 systemd[2155]: Reached target default.target - Main User Target. Sep 4 17:12:00.093951 systemd[2155]: Startup finished in 262ms. Sep 4 17:12:00.094295 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 17:12:00.102235 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 17:12:00.261612 systemd[1]: Started sshd@1-172.31.19.17:22-139.178.89.65:48650.service - OpenSSH per-connection server daemon (139.178.89.65:48650). Sep 4 17:12:00.451909 sshd[2166]: Accepted publickey for core from 139.178.89.65 port 48650 ssh2: RSA SHA256:kUAc/AK3NORsNqodfN7sFAtyAL1l41RPtj57UtNEeKU Sep 4 17:12:00.454283 sshd[2166]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:12:00.464097 systemd-logind[1905]: New session 2 of user core. Sep 4 17:12:00.473326 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 17:12:00.604532 sshd[2166]: pam_unix(sshd:session): session closed for user core Sep 4 17:12:00.611143 systemd-logind[1905]: Session 2 logged out. Waiting for processes to exit. Sep 4 17:12:00.611853 systemd[1]: sshd@1-172.31.19.17:22-139.178.89.65:48650.service: Deactivated successfully. Sep 4 17:12:00.616253 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 17:12:00.619273 systemd-logind[1905]: Removed session 2. Sep 4 17:12:00.641508 systemd[1]: Started sshd@2-172.31.19.17:22-139.178.89.65:48654.service - OpenSSH per-connection server daemon (139.178.89.65:48654). Sep 4 17:12:00.823534 sshd[2173]: Accepted publickey for core from 139.178.89.65 port 48654 ssh2: RSA SHA256:kUAc/AK3NORsNqodfN7sFAtyAL1l41RPtj57UtNEeKU Sep 4 17:12:00.826306 sshd[2173]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:12:00.835326 systemd-logind[1905]: New session 3 of user core. Sep 4 17:12:00.843268 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 17:12:00.889752 amazon-ssm-agent[2072]: 2024-09-04 17:12:00 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Sep 4 17:12:00.962193 sshd[2173]: pam_unix(sshd:session): session closed for user core Sep 4 17:12:00.968355 systemd[1]: sshd@2-172.31.19.17:22-139.178.89.65:48654.service: Deactivated successfully. Sep 4 17:12:00.972695 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 17:12:00.976262 systemd-logind[1905]: Session 3 logged out. Waiting for processes to exit. Sep 4 17:12:00.980551 systemd-logind[1905]: Removed session 3. Sep 4 17:12:00.990120 amazon-ssm-agent[2072]: 2024-09-04 17:12:00 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2177) started Sep 4 17:12:01.009613 systemd[1]: Started sshd@3-172.31.19.17:22-139.178.89.65:48670.service - OpenSSH per-connection server daemon (139.178.89.65:48670). Sep 4 17:12:01.091053 amazon-ssm-agent[2072]: 2024-09-04 17:12:00 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Sep 4 17:12:01.196333 sshd[2186]: Accepted publickey for core from 139.178.89.65 port 48670 ssh2: RSA SHA256:kUAc/AK3NORsNqodfN7sFAtyAL1l41RPtj57UtNEeKU Sep 4 17:12:01.198855 sshd[2186]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:12:01.206661 systemd-logind[1905]: New session 4 of user core. Sep 4 17:12:01.216333 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 17:12:01.343700 sshd[2186]: pam_unix(sshd:session): session closed for user core Sep 4 17:12:01.350762 systemd[1]: sshd@3-172.31.19.17:22-139.178.89.65:48670.service: Deactivated successfully. Sep 4 17:12:01.354598 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 17:12:01.356320 systemd-logind[1905]: Session 4 logged out. Waiting for processes to exit. Sep 4 17:12:01.358530 systemd-logind[1905]: Removed session 4. Sep 4 17:12:01.389286 systemd[1]: Started sshd@4-172.31.19.17:22-139.178.89.65:48684.service - OpenSSH per-connection server daemon (139.178.89.65:48684). Sep 4 17:12:01.571254 sshd[2198]: Accepted publickey for core from 139.178.89.65 port 48684 ssh2: RSA SHA256:kUAc/AK3NORsNqodfN7sFAtyAL1l41RPtj57UtNEeKU Sep 4 17:12:01.573718 sshd[2198]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Sep 4 17:12:01.582317 systemd-logind[1905]: New session 5 of user core. Sep 4 17:12:01.592251 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 17:12:01.708744 sudo[2201]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 4 17:12:01.709632 sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Sep 4 17:12:02.546815 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:12:02.547247 systemd[1]: kubelet.service: Consumed 1.354s CPU time. Sep 4 17:12:02.558631 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:12:02.600228 systemd[1]: Reloading requested from client PID 2239 ('systemctl') (unit session-5.scope)... Sep 4 17:12:02.600669 systemd[1]: Reloading... Sep 4 17:12:02.789030 zram_generator::config[2283]: No configuration found. Sep 4 17:12:03.045462 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. Sep 4 17:12:03.220172 systemd[1]: Reloading finished in 618 ms. Sep 4 17:12:03.317363 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 4 17:12:03.317560 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 4 17:12:03.318405 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:12:03.327521 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 17:12:03.788278 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 17:12:03.792443 (kubelet)[2340]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 4 17:12:03.868897 kubelet[2340]: 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. Sep 4 17:12:03.868897 kubelet[2340]: 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. Sep 4 17:12:03.869452 kubelet[2340]: 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. Sep 4 17:12:03.870535 kubelet[2340]: I0904 17:12:03.870423 2340 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" Sep 4 17:12:05.592988 kubelet[2340]: I0904 17:12:05.592916 2340 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Sep 4 17:12:05.593570 kubelet[2340]: I0904 17:12:05.593080 2340 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 4 17:12:05.593570 kubelet[2340]: I0904 17:12:05.593406 2340 server.go:919] "Client rotation is on, will bootstrap in background" Sep 4 17:12:05.625064 kubelet[2340]: I0904 17:12:05.625007 2340 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 4 17:12:05.639499 kubelet[2340]: I0904 17:12:05.639440 2340 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 4 17:12:05.640069 kubelet[2340]: I0904 17:12:05.640036 2340 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 4 17:12:05.640393 kubelet[2340]: I0904 17:12:05.640360 2340 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"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} Sep 4 17:12:05.640537 kubelet[2340]: I0904 17:12:05.640406 2340 topology_manager.go:138] "Creating topology manager with none policy" Sep 4 17:12:05.640537 kubelet[2340]: I0904 17:12:05.640428 2340 container_manager_linux.go:301] "Creating device plugin manager" Sep 4 17:12:05.643813 kubelet[2340]: I0904 17:12:05.643751 2340 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:12:05.647704 kubelet[2340]: I0904 17:12:05.647638 2340 kubelet.go:396] "Attempting to sync node with API server" Sep 4 17:12:05.647704 kubelet[2340]: I0904 17:12:05.647691 2340 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 4 17:12:05.649521 kubelet[2340]: I0904 17:12:05.647747 2340 kubelet.go:312] "Adding apiserver pod source" Sep 4 17:12:05.649521 kubelet[2340]: I0904 17:12:05.647789 2340 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 4 17:12:05.649521 kubelet[2340]: E0904 17:12:05.648424 2340 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:05.649521 kubelet[2340]: E0904 17:12:05.648511 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:05.651399 kubelet[2340]: I0904 17:12:05.651365 2340 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.17" apiVersion="v1" Sep 4 17:12:05.652291 kubelet[2340]: I0904 17:12:05.652259 2340 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 4 17:12:05.652557 kubelet[2340]: W0904 17:12:05.652538 2340 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 4 17:12:05.654178 kubelet[2340]: I0904 17:12:05.654144 2340 server.go:1256] "Started kubelet" Sep 4 17:12:05.663143 kubelet[2340]: I0904 17:12:05.663100 2340 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Sep 4 17:12:05.665932 kubelet[2340]: I0904 17:12:05.665863 2340 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 4 17:12:05.666136 kubelet[2340]: I0904 17:12:05.666111 2340 server.go:461] "Adding debug handlers to kubelet server" Sep 4 17:12:05.678789 kubelet[2340]: I0904 17:12:05.666327 2340 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 4 17:12:05.679254 kubelet[2340]: I0904 17:12:05.679226 2340 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 4 17:12:05.680738 kubelet[2340]: I0904 17:12:05.675122 2340 volume_manager.go:291] "Starting Kubelet Volume Manager" Sep 4 17:12:05.680738 kubelet[2340]: I0904 17:12:05.675159 2340 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Sep 4 17:12:05.682313 kubelet[2340]: I0904 17:12:05.681898 2340 reconciler_new.go:29] "Reconciler: start to sync state" Sep 4 17:12:05.684882 kubelet[2340]: W0904 17:12:05.684827 2340 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Node: nodes "172.31.19.17" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:12:05.684882 kubelet[2340]: E0904 17:12:05.684886 2340 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.19.17" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 4 17:12:05.687293 kubelet[2340]: I0904 17:12:05.685365 2340 factory.go:221] Registration of the systemd container factory successfully Sep 4 17:12:05.687293 kubelet[2340]: I0904 17:12:05.685519 2340 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 Sep 4 17:12:05.694477 kubelet[2340]: I0904 17:12:05.694407 2340 factory.go:221] Registration of the containerd container factory successfully Sep 4 17:12:05.696298 kubelet[2340]: E0904 17:12:05.696251 2340 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.19.17.17f219c1653b997a default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.19.17,UID:172.31.19.17,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.19.17,},FirstTimestamp:2024-09-04 17:12:05.654108538 +0000 UTC m=+1.854709550,LastTimestamp:2024-09-04 17:12:05.654108538 +0000 UTC m=+1.854709550,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.19.17,}" Sep 4 17:12:05.730403 kubelet[2340]: E0904 17:12:05.730354 2340 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.19.17\" not found" node="172.31.19.17" Sep 4 17:12:05.732332 kubelet[2340]: I0904 17:12:05.731914 2340 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 4 17:12:05.732332 kubelet[2340]: I0904 17:12:05.731948 2340 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 4 17:12:05.732332 kubelet[2340]: I0904 17:12:05.732000 2340 state_mem.go:36] "Initialized new in-memory state store" Sep 4 17:12:05.736829 kubelet[2340]: I0904 17:12:05.736789 2340 policy_none.go:49] "None policy: Start" Sep 4 17:12:05.739708 kubelet[2340]: I0904 17:12:05.739186 2340 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 4 17:12:05.739708 kubelet[2340]: I0904 17:12:05.739256 2340 state_mem.go:35] "Initializing new in-memory state store" Sep 4 17:12:05.762435 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 4 17:12:05.781003 kubelet[2340]: I0904 17:12:05.776537 2340 kubelet_node_status.go:73] "Attempting to register node" node="172.31.19.17" Sep 4 17:12:05.778055 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 4 17:12:05.781744 kubelet[2340]: I0904 17:12:05.781713 2340 kubelet_node_status.go:76] "Successfully registered node" node="172.31.19.17" Sep 4 17:12:05.789872 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 4 17:12:05.797312 kubelet[2340]: I0904 17:12:05.797245 2340 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 4 17:12:05.800362 kubelet[2340]: E0904 17:12:05.800305 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:05.803942 kubelet[2340]: I0904 17:12:05.803907 2340 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 4 17:12:05.804249 kubelet[2340]: I0904 17:12:05.804228 2340 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 4 17:12:05.804394 kubelet[2340]: I0904 17:12:05.804375 2340 kubelet.go:2329] "Starting kubelet main sync loop" Sep 4 17:12:05.804561 kubelet[2340]: E0904 17:12:05.804539 2340 kubelet.go:2353] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 4 17:12:05.805576 kubelet[2340]: I0904 17:12:05.805515 2340 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 4 17:12:05.805949 kubelet[2340]: I0904 17:12:05.805906 2340 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 4 17:12:05.814576 kubelet[2340]: E0904 17:12:05.814530 2340 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.19.17\" not found" Sep 4 17:12:05.900732 kubelet[2340]: E0904 17:12:05.900568 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.001347 kubelet[2340]: E0904 17:12:06.001284 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.101920 kubelet[2340]: E0904 17:12:06.101869 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.202707 kubelet[2340]: E0904 17:12:06.202564 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.303244 kubelet[2340]: E0904 17:12:06.303191 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.333511 sudo[2201]: pam_unix(sudo:session): session closed for user root Sep 4 17:12:06.357254 sshd[2198]: pam_unix(sshd:session): session closed for user core Sep 4 17:12:06.364047 systemd-logind[1905]: Session 5 logged out. Waiting for processes to exit. Sep 4 17:12:06.365547 systemd[1]: sshd@4-172.31.19.17:22-139.178.89.65:48684.service: Deactivated successfully. Sep 4 17:12:06.369738 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 17:12:06.371775 systemd-logind[1905]: Removed session 5. Sep 4 17:12:06.403898 kubelet[2340]: E0904 17:12:06.403840 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.504563 kubelet[2340]: E0904 17:12:06.504415 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.597014 kubelet[2340]: I0904 17:12:06.596925 2340 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 4 17:12:06.597717 kubelet[2340]: W0904 17:12:06.597193 2340 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 Sep 4 17:12:06.597717 kubelet[2340]: W0904 17:12:06.597251 2340 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 Sep 4 17:12:06.597717 kubelet[2340]: W0904 17:12:06.597310 2340 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 Sep 4 17:12:06.605165 kubelet[2340]: E0904 17:12:06.605125 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.649579 kubelet[2340]: E0904 17:12:06.649527 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:06.706340 kubelet[2340]: E0904 17:12:06.706287 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.807235 kubelet[2340]: E0904 17:12:06.807100 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:06.907288 kubelet[2340]: E0904 17:12:06.907229 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:07.007979 kubelet[2340]: E0904 17:12:07.007917 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:07.108683 kubelet[2340]: E0904 17:12:07.108562 2340 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.19.17\" not found" Sep 4 17:12:07.210358 kubelet[2340]: I0904 17:12:07.210302 2340 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 4 17:12:07.211047 containerd[1925]: time="2024-09-04T17:12:07.210902396Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 4 17:12:07.212329 kubelet[2340]: I0904 17:12:07.211262 2340 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 4 17:12:07.649944 kubelet[2340]: I0904 17:12:07.649814 2340 apiserver.go:52] "Watching apiserver" Sep 4 17:12:07.650607 kubelet[2340]: E0904 17:12:07.650237 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:07.654191 kubelet[2340]: I0904 17:12:07.654046 2340 topology_manager.go:215] "Topology Admit Handler" podUID="949f0014-cec7-4e68-ba4d-b18517bf6257" podNamespace="kube-system" podName="kube-proxy-8wrmp" Sep 4 17:12:07.654191 kubelet[2340]: I0904 17:12:07.654165 2340 topology_manager.go:215] "Topology Admit Handler" podUID="5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b" podNamespace="kube-flannel" podName="kube-flannel-ds-spdkp" Sep 4 17:12:07.667879 systemd[1]: Created slice kubepods-burstable-pod5fafc57f_bbd4_4f5f_915d_5e90e0f2fb5b.slice - libcontainer container kubepods-burstable-pod5fafc57f_bbd4_4f5f_915d_5e90e0f2fb5b.slice. Sep 4 17:12:07.683089 kubelet[2340]: I0904 17:12:07.682457 2340 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Sep 4 17:12:07.691057 systemd[1]: Created slice kubepods-besteffort-pod949f0014_cec7_4e68_ba4d_b18517bf6257.slice - libcontainer container kubepods-besteffort-pod949f0014_cec7_4e68_ba4d_b18517bf6257.slice. Sep 4 17:12:07.694061 kubelet[2340]: I0904 17:12:07.694024 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/949f0014-cec7-4e68-ba4d-b18517bf6257-kube-proxy\") pod \"kube-proxy-8wrmp\" (UID: \"949f0014-cec7-4e68-ba4d-b18517bf6257\") " pod="kube-system/kube-proxy-8wrmp" Sep 4 17:12:07.694450 kubelet[2340]: I0904 17:12:07.694268 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/949f0014-cec7-4e68-ba4d-b18517bf6257-xtables-lock\") pod \"kube-proxy-8wrmp\" (UID: \"949f0014-cec7-4e68-ba4d-b18517bf6257\") " pod="kube-system/kube-proxy-8wrmp" Sep 4 17:12:07.694450 kubelet[2340]: I0904 17:12:07.694323 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t5g7q\" (UniqueName: \"kubernetes.io/projected/949f0014-cec7-4e68-ba4d-b18517bf6257-kube-api-access-t5g7q\") pod \"kube-proxy-8wrmp\" (UID: \"949f0014-cec7-4e68-ba4d-b18517bf6257\") " pod="kube-system/kube-proxy-8wrmp" Sep 4 17:12:07.694450 kubelet[2340]: I0904 17:12:07.694368 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-cni-plugin\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.694450 kubelet[2340]: I0904 17:12:07.694421 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-cni\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.694693 kubelet[2340]: I0904 17:12:07.694504 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-flannel-cfg\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.694693 kubelet[2340]: I0904 17:12:07.694580 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ccb7f\" (UniqueName: \"kubernetes.io/projected/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-kube-api-access-ccb7f\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.694693 kubelet[2340]: I0904 17:12:07.694636 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/949f0014-cec7-4e68-ba4d-b18517bf6257-lib-modules\") pod \"kube-proxy-8wrmp\" (UID: \"949f0014-cec7-4e68-ba4d-b18517bf6257\") " pod="kube-system/kube-proxy-8wrmp" Sep 4 17:12:07.694693 kubelet[2340]: I0904 17:12:07.694691 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-run\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.695649 kubelet[2340]: I0904 17:12:07.694781 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b-xtables-lock\") pod \"kube-flannel-ds-spdkp\" (UID: \"5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b\") " pod="kube-flannel/kube-flannel-ds-spdkp" Sep 4 17:12:07.985041 containerd[1925]: time="2024-09-04T17:12:07.984394835Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-spdkp,Uid:5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b,Namespace:kube-flannel,Attempt:0,}" Sep 4 17:12:08.002883 containerd[1925]: time="2024-09-04T17:12:08.002820593Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8wrmp,Uid:949f0014-cec7-4e68-ba4d-b18517bf6257,Namespace:kube-system,Attempt:0,}" Sep 4 17:12:08.567525 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount910169590.mount: Deactivated successfully. Sep 4 17:12:08.581045 containerd[1925]: time="2024-09-04T17:12:08.580724258Z" 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\"}" Sep 4 17:12:08.582585 containerd[1925]: time="2024-09-04T17:12:08.582510477Z" 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\"}" Sep 4 17:12:08.584505 containerd[1925]: time="2024-09-04T17:12:08.584432419Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 4 17:12:08.585886 containerd[1925]: time="2024-09-04T17:12:08.585830644Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Sep 4 17:12:08.588109 containerd[1925]: time="2024-09-04T17:12:08.588006176Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 17:12:08.599743 containerd[1925]: time="2024-09-04T17:12:08.599643534Z" 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\"}" Sep 4 17:12:08.602035 containerd[1925]: time="2024-09-04T17:12:08.601661621Z" 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 598.70336ms" Sep 4 17:12:08.606384 containerd[1925]: time="2024-09-04T17:12:08.605993961Z" 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 621.410965ms" Sep 4 17:12:08.650503 kubelet[2340]: E0904 17:12:08.650419 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:08.820306 containerd[1925]: time="2024-09-04T17:12:08.815885808Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:12:08.820306 containerd[1925]: time="2024-09-04T17:12:08.816014215Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:08.820306 containerd[1925]: time="2024-09-04T17:12:08.816078299Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:12:08.820306 containerd[1925]: time="2024-09-04T17:12:08.816106209Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:08.827024 containerd[1925]: time="2024-09-04T17:12:08.826367988Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:12:08.827527 containerd[1925]: time="2024-09-04T17:12:08.827133793Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:08.827743 containerd[1925]: time="2024-09-04T17:12:08.827491586Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:12:08.828108 containerd[1925]: time="2024-09-04T17:12:08.827812053Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:08.963288 systemd[1]: Started cri-containerd-1ce9305b985c7533cc3e13818bc3aede0b6a99b835b2b04339477d6227ed4309.scope - libcontainer container 1ce9305b985c7533cc3e13818bc3aede0b6a99b835b2b04339477d6227ed4309. Sep 4 17:12:08.967398 systemd[1]: Started cri-containerd-772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a.scope - libcontainer container 772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a. Sep 4 17:12:09.022900 containerd[1925]: time="2024-09-04T17:12:09.022806512Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8wrmp,Uid:949f0014-cec7-4e68-ba4d-b18517bf6257,Namespace:kube-system,Attempt:0,} returns sandbox id \"1ce9305b985c7533cc3e13818bc3aede0b6a99b835b2b04339477d6227ed4309\"" Sep 4 17:12:09.027209 containerd[1925]: time="2024-09-04T17:12:09.027146913Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.8\"" Sep 4 17:12:09.054950 containerd[1925]: time="2024-09-04T17:12:09.054872375Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-spdkp,Uid:5fafc57f-bbd4-4f5f-915d-5e90e0f2fb5b,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\"" Sep 4 17:12:09.650932 kubelet[2340]: E0904 17:12:09.650862 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:10.379263 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1097220288.mount: Deactivated successfully. Sep 4 17:12:10.651932 kubelet[2340]: E0904 17:12:10.651759 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:10.868917 containerd[1925]: time="2024-09-04T17:12:10.868854512Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:10.870858 containerd[1925]: time="2024-09-04T17:12:10.870797084Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.8: active requests=0, bytes read=25251883" Sep 4 17:12:10.872554 containerd[1925]: time="2024-09-04T17:12:10.872473665Z" level=info msg="ImageCreate event name:\"sha256:61223b17dfa4bd3d116a0b714c4f2cc2e3d83853942dfb8578f50cc8e91eb399\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:10.877055 containerd[1925]: time="2024-09-04T17:12:10.876956027Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:559a093080f70ca863922f5e4bb90d6926d52653a91edb5b72c685ebb65f1858\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:10.878821 containerd[1925]: time="2024-09-04T17:12:10.878597226Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.8\" with image id \"sha256:61223b17dfa4bd3d116a0b714c4f2cc2e3d83853942dfb8578f50cc8e91eb399\", repo tag \"registry.k8s.io/kube-proxy:v1.29.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:559a093080f70ca863922f5e4bb90d6926d52653a91edb5b72c685ebb65f1858\", size \"25250902\" in 1.85137318s" Sep 4 17:12:10.878821 containerd[1925]: time="2024-09-04T17:12:10.878656069Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.8\" returns image reference \"sha256:61223b17dfa4bd3d116a0b714c4f2cc2e3d83853942dfb8578f50cc8e91eb399\"" Sep 4 17:12:10.880446 containerd[1925]: time="2024-09-04T17:12:10.880363822Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 4 17:12:10.882741 containerd[1925]: time="2024-09-04T17:12:10.882403282Z" level=info msg="CreateContainer within sandbox \"1ce9305b985c7533cc3e13818bc3aede0b6a99b835b2b04339477d6227ed4309\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 4 17:12:10.906619 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount575745990.mount: Deactivated successfully. Sep 4 17:12:10.918267 containerd[1925]: time="2024-09-04T17:12:10.918187945Z" level=info msg="CreateContainer within sandbox \"1ce9305b985c7533cc3e13818bc3aede0b6a99b835b2b04339477d6227ed4309\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c1e81f6d78bacb8d112d5c5c4110df0c5745eccd8c75404195041b937f179699\"" Sep 4 17:12:10.919566 containerd[1925]: time="2024-09-04T17:12:10.919514709Z" level=info msg="StartContainer for \"c1e81f6d78bacb8d112d5c5c4110df0c5745eccd8c75404195041b937f179699\"" Sep 4 17:12:10.976286 systemd[1]: Started cri-containerd-c1e81f6d78bacb8d112d5c5c4110df0c5745eccd8c75404195041b937f179699.scope - libcontainer container c1e81f6d78bacb8d112d5c5c4110df0c5745eccd8c75404195041b937f179699. Sep 4 17:12:11.034216 containerd[1925]: time="2024-09-04T17:12:11.033997631Z" level=info msg="StartContainer for \"c1e81f6d78bacb8d112d5c5c4110df0c5745eccd8c75404195041b937f179699\" returns successfully" Sep 4 17:12:11.652210 kubelet[2340]: E0904 17:12:11.652131 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:12.653347 kubelet[2340]: E0904 17:12:12.653279 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:12.683272 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2246889454.mount: Deactivated successfully. Sep 4 17:12:12.739385 containerd[1925]: time="2024-09-04T17:12:12.739296269Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:12.741073 containerd[1925]: time="2024-09-04T17:12:12.740999405Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 4 17:12:12.742482 containerd[1925]: time="2024-09-04T17:12:12.742390745Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:12.746759 containerd[1925]: time="2024-09-04T17:12:12.746650414Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:12.749405 containerd[1925]: time="2024-09-04T17:12:12.748406815Z" 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.867982735s" Sep 4 17:12:12.749405 containerd[1925]: time="2024-09-04T17:12:12.748466173Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 4 17:12:12.752069 containerd[1925]: time="2024-09-04T17:12:12.751997303Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 4 17:12:12.777831 containerd[1925]: time="2024-09-04T17:12:12.777774400Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48\"" Sep 4 17:12:12.780029 containerd[1925]: time="2024-09-04T17:12:12.778784427Z" level=info msg="StartContainer for \"88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48\"" Sep 4 17:12:12.825276 systemd[1]: Started cri-containerd-88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48.scope - libcontainer container 88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48. Sep 4 17:12:12.872329 containerd[1925]: time="2024-09-04T17:12:12.872171450Z" level=info msg="StartContainer for \"88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48\" returns successfully" Sep 4 17:12:12.876215 systemd[1]: cri-containerd-88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48.scope: Deactivated successfully. Sep 4 17:12:13.330375 containerd[1925]: time="2024-09-04T17:12:13.330283313Z" level=info msg="shim disconnected" id=88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48 namespace=k8s.io Sep 4 17:12:13.330732 containerd[1925]: time="2024-09-04T17:12:13.330372596Z" level=warning msg="cleaning up after shim disconnected" id=88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48 namespace=k8s.io Sep 4 17:12:13.330732 containerd[1925]: time="2024-09-04T17:12:13.330398180Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:12:13.540126 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-88e4a599266e88f3f10f1bcc68ccc6878799529c3b73f9eee0e01b15c0506e48-rootfs.mount: Deactivated successfully. Sep 4 17:12:13.653939 kubelet[2340]: E0904 17:12:13.653784 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:13.866889 containerd[1925]: time="2024-09-04T17:12:13.866837016Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 4 17:12:13.880426 kubelet[2340]: I0904 17:12:13.880328 2340 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-8wrmp" podStartSLOduration=7.027151221 podStartE2EDuration="8.880252473s" podCreationTimestamp="2024-09-04 17:12:05 +0000 UTC" firstStartedPulling="2024-09-04 17:12:09.026284148 +0000 UTC m=+5.226885160" lastFinishedPulling="2024-09-04 17:12:10.8793854 +0000 UTC m=+7.079986412" observedRunningTime="2024-09-04 17:12:11.867383225 +0000 UTC m=+8.067984249" watchObservedRunningTime="2024-09-04 17:12:13.880252473 +0000 UTC m=+10.080853580" Sep 4 17:12:14.653946 kubelet[2340]: E0904 17:12:14.653887 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:15.654464 kubelet[2340]: E0904 17:12:15.654388 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:15.869951 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1295773866.mount: Deactivated successfully. Sep 4 17:12:16.654806 kubelet[2340]: E0904 17:12:16.654751 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:17.008740 containerd[1925]: time="2024-09-04T17:12:17.008646003Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:17.011126 containerd[1925]: time="2024-09-04T17:12:17.011032305Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Sep 4 17:12:17.012773 containerd[1925]: time="2024-09-04T17:12:17.012667171Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:17.022139 containerd[1925]: time="2024-09-04T17:12:17.022004212Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:17.026018 containerd[1925]: time="2024-09-04T17:12:17.024796775Z" 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.157894249s" Sep 4 17:12:17.026018 containerd[1925]: time="2024-09-04T17:12:17.024878766Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 4 17:12:17.029982 containerd[1925]: time="2024-09-04T17:12:17.029677230Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 4 17:12:17.055913 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3694524309.mount: Deactivated successfully. Sep 4 17:12:17.067489 containerd[1925]: time="2024-09-04T17:12:17.067419721Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24\"" Sep 4 17:12:17.069996 containerd[1925]: time="2024-09-04T17:12:17.068666593Z" level=info msg="StartContainer for \"934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24\"" Sep 4 17:12:17.116311 systemd[1]: Started cri-containerd-934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24.scope - libcontainer container 934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24. Sep 4 17:12:17.163788 systemd[1]: cri-containerd-934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24.scope: Deactivated successfully. Sep 4 17:12:17.169199 containerd[1925]: time="2024-09-04T17:12:17.169051612Z" level=info msg="StartContainer for \"934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24\" returns successfully" Sep 4 17:12:17.221863 kubelet[2340]: I0904 17:12:17.221796 2340 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Sep 4 17:12:17.506928 containerd[1925]: time="2024-09-04T17:12:17.506832698Z" level=info msg="shim disconnected" id=934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24 namespace=k8s.io Sep 4 17:12:17.506928 containerd[1925]: time="2024-09-04T17:12:17.506925196Z" level=warning msg="cleaning up after shim disconnected" id=934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24 namespace=k8s.io Sep 4 17:12:17.507665 containerd[1925]: time="2024-09-04T17:12:17.506950779Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 17:12:17.655808 kubelet[2340]: E0904 17:12:17.655730 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:17.880124 containerd[1925]: time="2024-09-04T17:12:17.879498609Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 4 17:12:17.900552 containerd[1925]: time="2024-09-04T17:12:17.900474628Z" level=info msg="CreateContainer within sandbox \"772b7005e55989723529da69a387d180b7e2a68f9b4085e4ee483d41148a7c4a\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ee92d353626f0b5dd043dec3813d1bff704af44171fcfb3170d3e52d0e41b202\"" Sep 4 17:12:17.902301 containerd[1925]: time="2024-09-04T17:12:17.901247257Z" level=info msg="StartContainer for \"ee92d353626f0b5dd043dec3813d1bff704af44171fcfb3170d3e52d0e41b202\"" Sep 4 17:12:17.945323 systemd[1]: Started cri-containerd-ee92d353626f0b5dd043dec3813d1bff704af44171fcfb3170d3e52d0e41b202.scope - libcontainer container ee92d353626f0b5dd043dec3813d1bff704af44171fcfb3170d3e52d0e41b202. Sep 4 17:12:18.002610 containerd[1925]: time="2024-09-04T17:12:18.002429109Z" level=info msg="StartContainer for \"ee92d353626f0b5dd043dec3813d1bff704af44171fcfb3170d3e52d0e41b202\" returns successfully" Sep 4 17:12:18.051379 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-934a21692dc84aec7be5cfd813613ee06ecef02c51d295d37508b0b2cc82ef24-rootfs.mount: Deactivated successfully. Sep 4 17:12:18.338633 kubelet[2340]: I0904 17:12:18.338580 2340 topology_manager.go:215] "Topology Admit Handler" podUID="174be6a0-c947-4cc4-9606-3aa54ac26cd5" podNamespace="default" podName="nginx-deployment-6d5f899847-mgcpk" Sep 4 17:12:18.349230 systemd[1]: Created slice kubepods-besteffort-pod174be6a0_c947_4cc4_9606_3aa54ac26cd5.slice - libcontainer container kubepods-besteffort-pod174be6a0_c947_4cc4_9606_3aa54ac26cd5.slice. Sep 4 17:12:18.362469 kubelet[2340]: I0904 17:12:18.362400 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nx4vf\" (UniqueName: \"kubernetes.io/projected/174be6a0-c947-4cc4-9606-3aa54ac26cd5-kube-api-access-nx4vf\") pod \"nginx-deployment-6d5f899847-mgcpk\" (UID: \"174be6a0-c947-4cc4-9606-3aa54ac26cd5\") " pod="default/nginx-deployment-6d5f899847-mgcpk" Sep 4 17:12:18.655738 containerd[1925]: time="2024-09-04T17:12:18.655587989Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mgcpk,Uid:174be6a0-c947-4cc4-9606-3aa54ac26cd5,Namespace:default,Attempt:0,}" Sep 4 17:12:18.656308 kubelet[2340]: E0904 17:12:18.656211 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:18.708716 systemd[1]: run-netns-cni\x2da56ca3aa\x2ddfe9\x2dd968\x2db855\x2d8714817e86fa.mount: Deactivated successfully. Sep 4 17:12:18.708907 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8-shm.mount: Deactivated successfully. Sep 4 17:12:18.712712 containerd[1925]: time="2024-09-04T17:12:18.712547711Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mgcpk,Uid:174be6a0-c947-4cc4-9606-3aa54ac26cd5,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Sep 4 17:12:18.712955 kubelet[2340]: E0904 17:12:18.712918 2340 remote_runtime.go:193] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Sep 4 17:12:18.713143 kubelet[2340]: E0904 17:12:18.713027 2340 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-mgcpk" Sep 4 17:12:18.713143 kubelet[2340]: E0904 17:12:18.713068 2340 kuberuntime_manager.go:1172] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-6d5f899847-mgcpk" Sep 4 17:12:18.713327 kubelet[2340]: E0904 17:12:18.713159 2340 pod_workers.go:1298] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-6d5f899847-mgcpk_default(174be6a0-c947-4cc4-9606-3aa54ac26cd5)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-6d5f899847-mgcpk_default(174be6a0-c947-4cc4-9606-3aa54ac26cd5)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"5138bab6cfddc4fc81cb4916ba8449a1ff15dcfc53adc4a81cddfe1f4cd69ea8\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-6d5f899847-mgcpk" podUID="174be6a0-c947-4cc4-9606-3aa54ac26cd5" Sep 4 17:12:18.896867 kubelet[2340]: I0904 17:12:18.896789 2340 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-spdkp" podStartSLOduration=5.928307379 podStartE2EDuration="13.896727863s" podCreationTimestamp="2024-09-04 17:12:05 +0000 UTC" firstStartedPulling="2024-09-04 17:12:09.056768567 +0000 UTC m=+5.257369567" lastFinishedPulling="2024-09-04 17:12:17.025189039 +0000 UTC m=+13.225790051" observedRunningTime="2024-09-04 17:12:18.896699041 +0000 UTC m=+15.097300065" watchObservedRunningTime="2024-09-04 17:12:18.896727863 +0000 UTC m=+15.097328887" Sep 4 17:12:19.072299 (udev-worker)[2853]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:12:19.085725 systemd-networkd[1768]: flannel.1: Link UP Sep 4 17:12:19.085743 systemd-networkd[1768]: flannel.1: Gained carrier Sep 4 17:12:19.656566 kubelet[2340]: E0904 17:12:19.656482 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:20.342272 systemd-networkd[1768]: flannel.1: Gained IPv6LL Sep 4 17:12:20.657319 kubelet[2340]: E0904 17:12:20.657144 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:21.657765 kubelet[2340]: E0904 17:12:21.657692 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:22.658592 kubelet[2340]: E0904 17:12:22.658525 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:23.243103 ntpd[1901]: Listen normally on 7 flannel.1 192.168.1.0:123 Sep 4 17:12:23.243237 ntpd[1901]: Listen normally on 8 flannel.1 [fe80::9c31:2aff:feeb:7de7%3]:123 Sep 4 17:12:23.243733 ntpd[1901]: 4 Sep 17:12:23 ntpd[1901]: Listen normally on 7 flannel.1 192.168.1.0:123 Sep 4 17:12:23.243733 ntpd[1901]: 4 Sep 17:12:23 ntpd[1901]: Listen normally on 8 flannel.1 [fe80::9c31:2aff:feeb:7de7%3]:123 Sep 4 17:12:23.659140 kubelet[2340]: E0904 17:12:23.658984 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:24.659898 kubelet[2340]: E0904 17:12:24.659833 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:25.647888 kubelet[2340]: E0904 17:12:25.647819 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:25.661028 kubelet[2340]: E0904 17:12:25.660950 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:26.662139 kubelet[2340]: E0904 17:12:26.662067 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:26.951019 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Sep 4 17:12:27.662486 kubelet[2340]: E0904 17:12:27.662418 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:28.662677 kubelet[2340]: E0904 17:12:28.662608 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:29.662975 kubelet[2340]: E0904 17:12:29.662910 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:30.663381 kubelet[2340]: E0904 17:12:30.663314 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:31.664036 kubelet[2340]: E0904 17:12:31.663938 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:32.664850 kubelet[2340]: E0904 17:12:32.664790 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:33.665700 kubelet[2340]: E0904 17:12:33.665634 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:33.806955 containerd[1925]: time="2024-09-04T17:12:33.806211760Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mgcpk,Uid:174be6a0-c947-4cc4-9606-3aa54ac26cd5,Namespace:default,Attempt:0,}" Sep 4 17:12:33.840457 systemd-networkd[1768]: cni0: Link UP Sep 4 17:12:33.840479 systemd-networkd[1768]: cni0: Gained carrier Sep 4 17:12:33.850631 systemd-networkd[1768]: vethf4804666: Link UP Sep 4 17:12:33.851581 (udev-worker)[2994]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:12:33.853977 kernel: cni0: port 1(vethf4804666) entered blocking state Sep 4 17:12:33.854102 kernel: cni0: port 1(vethf4804666) entered disabled state Sep 4 17:12:33.855125 kernel: vethf4804666: entered allmulticast mode Sep 4 17:12:33.856545 kernel: vethf4804666: entered promiscuous mode Sep 4 17:12:33.857659 systemd-networkd[1768]: cni0: Lost carrier Sep 4 17:12:33.858951 (udev-worker)[2992]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:12:33.882776 kernel: cni0: port 1(vethf4804666) entered blocking state Sep 4 17:12:33.882889 kernel: cni0: port 1(vethf4804666) entered forwarding state Sep 4 17:12:33.886747 systemd-networkd[1768]: vethf4804666: Gained carrier Sep 4 17:12:33.888670 systemd-networkd[1768]: cni0: Gained carrier Sep 4 17:12:33.891040 containerd[1925]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000012938), "name":"cbr0", "type":"bridge"} Sep 4 17:12:33.891040 containerd[1925]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:12:33.921058 containerd[1925]: {"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-09-04T17:12:33.920750742Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:12:33.921058 containerd[1925]: time="2024-09-04T17:12:33.920852727Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:33.921058 containerd[1925]: time="2024-09-04T17:12:33.920896985Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:12:33.921058 containerd[1925]: time="2024-09-04T17:12:33.920931792Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:33.959298 systemd[1]: Started cri-containerd-f7a08f911c17ea204c921f287e40d0f8ab8647c265fd7efef00ad37deda17080.scope - libcontainer container f7a08f911c17ea204c921f287e40d0f8ab8647c265fd7efef00ad37deda17080. Sep 4 17:12:34.018817 containerd[1925]: time="2024-09-04T17:12:34.018753069Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mgcpk,Uid:174be6a0-c947-4cc4-9606-3aa54ac26cd5,Namespace:default,Attempt:0,} returns sandbox id \"f7a08f911c17ea204c921f287e40d0f8ab8647c265fd7efef00ad37deda17080\"" Sep 4 17:12:34.021562 containerd[1925]: time="2024-09-04T17:12:34.021133122Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:12:34.666110 kubelet[2340]: E0904 17:12:34.666039 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:35.446611 systemd-networkd[1768]: vethf4804666: Gained IPv6LL Sep 4 17:12:35.638463 systemd-networkd[1768]: cni0: Gained IPv6LL Sep 4 17:12:35.666854 kubelet[2340]: E0904 17:12:35.666766 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:36.667490 kubelet[2340]: E0904 17:12:36.667428 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:37.197070 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2643315914.mount: Deactivated successfully. Sep 4 17:12:37.668325 kubelet[2340]: E0904 17:12:37.668146 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:38.243141 ntpd[1901]: Listen normally on 9 cni0 192.168.1.1:123 Sep 4 17:12:38.243286 ntpd[1901]: Listen normally on 10 cni0 [fe80::2878:cfff:fe2f:38f%4]:123 Sep 4 17:12:38.244008 ntpd[1901]: 4 Sep 17:12:38 ntpd[1901]: Listen normally on 9 cni0 192.168.1.1:123 Sep 4 17:12:38.244008 ntpd[1901]: 4 Sep 17:12:38 ntpd[1901]: Listen normally on 10 cni0 [fe80::2878:cfff:fe2f:38f%4]:123 Sep 4 17:12:38.244008 ntpd[1901]: 4 Sep 17:12:38 ntpd[1901]: Listen normally on 11 vethf4804666 [fe80::b8:f1ff:fec9:663d%5]:123 Sep 4 17:12:38.243368 ntpd[1901]: Listen normally on 11 vethf4804666 [fe80::b8:f1ff:fec9:663d%5]:123 Sep 4 17:12:38.641940 containerd[1925]: time="2024-09-04T17:12:38.641773444Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:38.643910 containerd[1925]: time="2024-09-04T17:12:38.643834985Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67679186" Sep 4 17:12:38.646171 containerd[1925]: time="2024-09-04T17:12:38.646092208Z" level=info msg="ImageCreate event name:\"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:38.653015 containerd[1925]: time="2024-09-04T17:12:38.652900218Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:38.655161 containerd[1925]: time="2024-09-04T17:12:38.654953183Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\", size \"67679064\" in 4.633738394s" Sep 4 17:12:38.655161 containerd[1925]: time="2024-09-04T17:12:38.655034922Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:12:38.658489 containerd[1925]: time="2024-09-04T17:12:38.658417170Z" level=info msg="CreateContainer within sandbox \"f7a08f911c17ea204c921f287e40d0f8ab8647c265fd7efef00ad37deda17080\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 4 17:12:38.669535 kubelet[2340]: E0904 17:12:38.669476 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:38.680930 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1680825575.mount: Deactivated successfully. Sep 4 17:12:38.685545 containerd[1925]: time="2024-09-04T17:12:38.685480324Z" level=info msg="CreateContainer within sandbox \"f7a08f911c17ea204c921f287e40d0f8ab8647c265fd7efef00ad37deda17080\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781\"" Sep 4 17:12:38.686518 containerd[1925]: time="2024-09-04T17:12:38.686468917Z" level=info msg="StartContainer for \"49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781\"" Sep 4 17:12:38.731132 systemd[1]: run-containerd-runc-k8s.io-49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781-runc.zOnvHW.mount: Deactivated successfully. Sep 4 17:12:38.742318 systemd[1]: Started cri-containerd-49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781.scope - libcontainer container 49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781. Sep 4 17:12:38.786784 containerd[1925]: time="2024-09-04T17:12:38.786645684Z" level=info msg="StartContainer for \"49cccc045d625ba54e391dd798a526b88b64b79327cf61aee5718e691b1b9781\" returns successfully" Sep 4 17:12:38.932760 kubelet[2340]: I0904 17:12:38.932709 2340 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-mgcpk" podStartSLOduration=16.297643999 podStartE2EDuration="20.932655771s" podCreationTimestamp="2024-09-04 17:12:18 +0000 UTC" firstStartedPulling="2024-09-04 17:12:34.020614106 +0000 UTC m=+30.221215118" lastFinishedPulling="2024-09-04 17:12:38.655625866 +0000 UTC m=+34.856226890" observedRunningTime="2024-09-04 17:12:38.932553654 +0000 UTC m=+35.133154690" watchObservedRunningTime="2024-09-04 17:12:38.932655771 +0000 UTC m=+35.133256795" Sep 4 17:12:39.669823 kubelet[2340]: E0904 17:12:39.669751 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:40.670380 kubelet[2340]: E0904 17:12:40.670313 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:41.529370 update_engine[1906]: I0904 17:12:41.529297 1906 update_attempter.cc:509] Updating boot flags... Sep 4 17:12:41.607112 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 35 scanned by (udev-worker) (3193) Sep 4 17:12:41.672032 kubelet[2340]: E0904 17:12:41.671083 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:41.874117 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 35 scanned by (udev-worker) (3195) Sep 4 17:12:42.146012 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 35 scanned by (udev-worker) (3195) Sep 4 17:12:42.672344 kubelet[2340]: E0904 17:12:42.672271 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:43.673334 kubelet[2340]: E0904 17:12:43.673249 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:43.732227 kubelet[2340]: I0904 17:12:43.732156 2340 topology_manager.go:215] "Topology Admit Handler" podUID="cd935c89-0150-483f-b0e9-bd8961ffade7" podNamespace="default" podName="nfs-server-provisioner-0" Sep 4 17:12:43.747804 systemd[1]: Created slice kubepods-besteffort-podcd935c89_0150_483f_b0e9_bd8961ffade7.slice - libcontainer container kubepods-besteffort-podcd935c89_0150_483f_b0e9_bd8961ffade7.slice. Sep 4 17:12:43.805469 kubelet[2340]: I0904 17:12:43.805390 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/cd935c89-0150-483f-b0e9-bd8961ffade7-data\") pod \"nfs-server-provisioner-0\" (UID: \"cd935c89-0150-483f-b0e9-bd8961ffade7\") " pod="default/nfs-server-provisioner-0" Sep 4 17:12:43.805651 kubelet[2340]: I0904 17:12:43.805492 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jjsxv\" (UniqueName: \"kubernetes.io/projected/cd935c89-0150-483f-b0e9-bd8961ffade7-kube-api-access-jjsxv\") pod \"nfs-server-provisioner-0\" (UID: \"cd935c89-0150-483f-b0e9-bd8961ffade7\") " pod="default/nfs-server-provisioner-0" Sep 4 17:12:44.055120 containerd[1925]: time="2024-09-04T17:12:44.054871021Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cd935c89-0150-483f-b0e9-bd8961ffade7,Namespace:default,Attempt:0,}" Sep 4 17:12:44.099130 (udev-worker)[3194]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:12:44.100464 systemd-networkd[1768]: veth286b700d: Link UP Sep 4 17:12:44.105035 kernel: cni0: port 2(veth286b700d) entered blocking state Sep 4 17:12:44.105130 kernel: cni0: port 2(veth286b700d) entered disabled state Sep 4 17:12:44.106291 kernel: veth286b700d: entered allmulticast mode Sep 4 17:12:44.107524 kernel: veth286b700d: entered promiscuous mode Sep 4 17:12:44.116525 kernel: cni0: port 2(veth286b700d) entered blocking state Sep 4 17:12:44.116603 kernel: cni0: port 2(veth286b700d) entered forwarding state Sep 4 17:12:44.119119 systemd-networkd[1768]: veth286b700d: Gained carrier Sep 4 17:12:44.124315 containerd[1925]: 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"} Sep 4 17:12:44.124315 containerd[1925]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:12:44.154528 containerd[1925]: {"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-09-04T17:12:44.154172382Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:12:44.154528 containerd[1925]: time="2024-09-04T17:12:44.154263393Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:44.154528 containerd[1925]: time="2024-09-04T17:12:44.154308358Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:12:44.154528 containerd[1925]: time="2024-09-04T17:12:44.154336292Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:12:44.195264 systemd[1]: Started cri-containerd-9b13faf376999f0b1b23d1e87a1fb5a1c090e88215721f8325fa78a804e460c4.scope - libcontainer container 9b13faf376999f0b1b23d1e87a1fb5a1c090e88215721f8325fa78a804e460c4. Sep 4 17:12:44.266078 containerd[1925]: time="2024-09-04T17:12:44.262743018Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cd935c89-0150-483f-b0e9-bd8961ffade7,Namespace:default,Attempt:0,} returns sandbox id \"9b13faf376999f0b1b23d1e87a1fb5a1c090e88215721f8325fa78a804e460c4\"" Sep 4 17:12:44.271340 containerd[1925]: time="2024-09-04T17:12:44.271254476Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 4 17:12:44.674243 kubelet[2340]: E0904 17:12:44.674175 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:45.495166 systemd-networkd[1768]: veth286b700d: Gained IPv6LL Sep 4 17:12:45.648888 kubelet[2340]: E0904 17:12:45.648828 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:45.674893 kubelet[2340]: E0904 17:12:45.674839 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:46.675903 kubelet[2340]: E0904 17:12:46.675849 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:47.119874 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount343305257.mount: Deactivated successfully. Sep 4 17:12:47.676716 kubelet[2340]: E0904 17:12:47.676649 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:48.243226 ntpd[1901]: Listen normally on 12 veth286b700d [fe80::4809:46ff:fe09:327e%6]:123 Sep 4 17:12:48.244577 ntpd[1901]: 4 Sep 17:12:48 ntpd[1901]: Listen normally on 12 veth286b700d [fe80::4809:46ff:fe09:327e%6]:123 Sep 4 17:12:48.677045 kubelet[2340]: E0904 17:12:48.677003 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:49.678628 kubelet[2340]: E0904 17:12:49.678561 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:50.412170 containerd[1925]: time="2024-09-04T17:12:50.412085187Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:50.414822 containerd[1925]: time="2024-09-04T17:12:50.414734014Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Sep 4 17:12:50.417275 containerd[1925]: time="2024-09-04T17:12:50.417171159Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:50.423898 containerd[1925]: time="2024-09-04T17:12:50.423794054Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:12:50.427336 containerd[1925]: time="2024-09-04T17:12:50.425934323Z" 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.154588177s" Sep 4 17:12:50.427336 containerd[1925]: time="2024-09-04T17:12:50.426030359Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 4 17:12:50.429484 containerd[1925]: time="2024-09-04T17:12:50.429428247Z" level=info msg="CreateContainer within sandbox \"9b13faf376999f0b1b23d1e87a1fb5a1c090e88215721f8325fa78a804e460c4\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 4 17:12:50.449403 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount528889556.mount: Deactivated successfully. Sep 4 17:12:50.483382 containerd[1925]: time="2024-09-04T17:12:50.483301158Z" level=info msg="CreateContainer within sandbox \"9b13faf376999f0b1b23d1e87a1fb5a1c090e88215721f8325fa78a804e460c4\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8c3a0785ba5952ce856d37f332f5ac2ba7c6f81e82cb043f8ca296c02de147e8\"" Sep 4 17:12:50.484144 containerd[1925]: time="2024-09-04T17:12:50.484078789Z" level=info msg="StartContainer for \"8c3a0785ba5952ce856d37f332f5ac2ba7c6f81e82cb043f8ca296c02de147e8\"" Sep 4 17:12:50.537525 systemd[1]: Started cri-containerd-8c3a0785ba5952ce856d37f332f5ac2ba7c6f81e82cb043f8ca296c02de147e8.scope - libcontainer container 8c3a0785ba5952ce856d37f332f5ac2ba7c6f81e82cb043f8ca296c02de147e8. Sep 4 17:12:50.583016 containerd[1925]: time="2024-09-04T17:12:50.582912359Z" level=info msg="StartContainer for \"8c3a0785ba5952ce856d37f332f5ac2ba7c6f81e82cb043f8ca296c02de147e8\" returns successfully" Sep 4 17:12:50.679339 kubelet[2340]: E0904 17:12:50.679278 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:50.984483 kubelet[2340]: I0904 17:12:50.984309 2340 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.8270657099999998 podStartE2EDuration="7.984241184s" podCreationTimestamp="2024-09-04 17:12:43 +0000 UTC" firstStartedPulling="2024-09-04 17:12:44.269132714 +0000 UTC m=+40.469733726" lastFinishedPulling="2024-09-04 17:12:50.426308188 +0000 UTC m=+46.626909200" observedRunningTime="2024-09-04 17:12:50.982795307 +0000 UTC m=+47.183396319" watchObservedRunningTime="2024-09-04 17:12:50.984241184 +0000 UTC m=+47.184842208" Sep 4 17:12:51.680462 kubelet[2340]: E0904 17:12:51.680392 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:52.681352 kubelet[2340]: E0904 17:12:52.681282 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:53.681835 kubelet[2340]: E0904 17:12:53.681772 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:54.682860 kubelet[2340]: E0904 17:12:54.682780 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:55.683780 kubelet[2340]: E0904 17:12:55.683702 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:56.684688 kubelet[2340]: E0904 17:12:56.684609 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:57.685931 kubelet[2340]: E0904 17:12:57.685869 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:58.686866 kubelet[2340]: E0904 17:12:58.686799 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:12:59.687232 kubelet[2340]: E0904 17:12:59.687115 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:00.006710 kubelet[2340]: I0904 17:13:00.006521 2340 topology_manager.go:215] "Topology Admit Handler" podUID="10b6719e-55bf-4b63-8c40-f08a10380e03" podNamespace="default" podName="test-pod-1" Sep 4 17:13:00.019276 systemd[1]: Created slice kubepods-besteffort-pod10b6719e_55bf_4b63_8c40_f08a10380e03.slice - libcontainer container kubepods-besteffort-pod10b6719e_55bf_4b63_8c40_f08a10380e03.slice. Sep 4 17:13:00.196727 kubelet[2340]: I0904 17:13:00.196358 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-35f13a15-eb09-4be8-98f4-0a4252277092\" (UniqueName: \"kubernetes.io/nfs/10b6719e-55bf-4b63-8c40-f08a10380e03-pvc-35f13a15-eb09-4be8-98f4-0a4252277092\") pod \"test-pod-1\" (UID: \"10b6719e-55bf-4b63-8c40-f08a10380e03\") " pod="default/test-pod-1" Sep 4 17:13:00.196727 kubelet[2340]: I0904 17:13:00.196444 2340 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nzw44\" (UniqueName: \"kubernetes.io/projected/10b6719e-55bf-4b63-8c40-f08a10380e03-kube-api-access-nzw44\") pod \"test-pod-1\" (UID: \"10b6719e-55bf-4b63-8c40-f08a10380e03\") " pod="default/test-pod-1" Sep 4 17:13:00.334026 kernel: FS-Cache: Loaded Sep 4 17:13:00.377870 kernel: RPC: Registered named UNIX socket transport module. Sep 4 17:13:00.378041 kernel: RPC: Registered udp transport module. Sep 4 17:13:00.378087 kernel: RPC: Registered tcp transport module. Sep 4 17:13:00.378762 kernel: RPC: Registered tcp-with-tls transport module. Sep 4 17:13:00.379710 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 4 17:13:00.688210 kubelet[2340]: E0904 17:13:00.688051 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:00.717340 kernel: NFS: Registering the id_resolver key type Sep 4 17:13:00.717504 kernel: Key type id_resolver registered Sep 4 17:13:00.717553 kernel: Key type id_legacy registered Sep 4 17:13:00.754368 nfsidmap[3719]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Sep 4 17:13:00.760628 nfsidmap[3720]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Sep 4 17:13:00.926715 containerd[1925]: time="2024-09-04T17:13:00.926543471Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:10b6719e-55bf-4b63-8c40-f08a10380e03,Namespace:default,Attempt:0,}" Sep 4 17:13:00.963587 (udev-worker)[3709]: Network interface NamePolicy= disabled on kernel command line. Sep 4 17:13:00.968620 kernel: cni0: port 3(veth2f07ccd9) entered blocking state Sep 4 17:13:00.968739 kernel: cni0: port 3(veth2f07ccd9) entered disabled state Sep 4 17:13:00.968780 kernel: veth2f07ccd9: entered allmulticast mode Sep 4 17:13:00.966859 systemd-networkd[1768]: veth2f07ccd9: Link UP Sep 4 17:13:00.971047 kernel: veth2f07ccd9: entered promiscuous mode Sep 4 17:13:00.982045 kernel: cni0: port 3(veth2f07ccd9) entered blocking state Sep 4 17:13:00.982166 kernel: cni0: port 3(veth2f07ccd9) entered forwarding state Sep 4 17:13:00.982945 systemd-networkd[1768]: veth2f07ccd9: Gained carrier Sep 4 17:13:00.990080 containerd[1925]: 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)(0x40000a48e8), "name":"cbr0", "type":"bridge"} Sep 4 17:13:00.990080 containerd[1925]: delegateAdd: netconf sent to delegate plugin: Sep 4 17:13:01.024276 containerd[1925]: {"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-09-04T17:13:01.023755297Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 17:13:01.024276 containerd[1925]: time="2024-09-04T17:13:01.023877252Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:13:01.024276 containerd[1925]: time="2024-09-04T17:13:01.023945510Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 17:13:01.024276 containerd[1925]: time="2024-09-04T17:13:01.024035201Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 17:13:01.057366 systemd[1]: Started cri-containerd-a3f285c572aa9d08a00d5bc3de888abda5697844c32ebdc7cf0a35f163136777.scope - libcontainer container a3f285c572aa9d08a00d5bc3de888abda5697844c32ebdc7cf0a35f163136777. Sep 4 17:13:01.124884 containerd[1925]: time="2024-09-04T17:13:01.124736406Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:10b6719e-55bf-4b63-8c40-f08a10380e03,Namespace:default,Attempt:0,} returns sandbox id \"a3f285c572aa9d08a00d5bc3de888abda5697844c32ebdc7cf0a35f163136777\"" Sep 4 17:13:01.128890 containerd[1925]: time="2024-09-04T17:13:01.128808081Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 17:13:01.426252 containerd[1925]: time="2024-09-04T17:13:01.426167240Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 17:13:01.429985 containerd[1925]: time="2024-09-04T17:13:01.429868720Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 4 17:13:01.436453 containerd[1925]: time="2024-09-04T17:13:01.436374050Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:337a19c298c7ca0845a051d89520d044d67a9402e4c33db4f541a93279763a1d\", size \"67679064\" in 307.343624ms" Sep 4 17:13:01.436453 containerd[1925]: time="2024-09-04T17:13:01.436444683Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cc60dce18890651fdb32fb3730e7dea249d9c6bc95568fd0e8cb3650d21aa0f\"" Sep 4 17:13:01.439481 containerd[1925]: time="2024-09-04T17:13:01.439392424Z" level=info msg="CreateContainer within sandbox \"a3f285c572aa9d08a00d5bc3de888abda5697844c32ebdc7cf0a35f163136777\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 4 17:13:01.476579 containerd[1925]: time="2024-09-04T17:13:01.476513195Z" level=info msg="CreateContainer within sandbox \"a3f285c572aa9d08a00d5bc3de888abda5697844c32ebdc7cf0a35f163136777\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859\"" Sep 4 17:13:01.477794 containerd[1925]: time="2024-09-04T17:13:01.477509548Z" level=info msg="StartContainer for \"1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859\"" Sep 4 17:13:01.538317 systemd[1]: Started cri-containerd-1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859.scope - libcontainer container 1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859. Sep 4 17:13:01.584545 containerd[1925]: time="2024-09-04T17:13:01.584469342Z" level=info msg="StartContainer for \"1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859\" returns successfully" Sep 4 17:13:01.688864 kubelet[2340]: E0904 17:13:01.688248 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:02.011096 kubelet[2340]: I0904 17:13:02.010926 2340 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.701893432 podStartE2EDuration="18.010845296s" podCreationTimestamp="2024-09-04 17:12:44 +0000 UTC" firstStartedPulling="2024-09-04 17:13:01.127812795 +0000 UTC m=+57.328413795" lastFinishedPulling="2024-09-04 17:13:01.436764647 +0000 UTC m=+57.637365659" observedRunningTime="2024-09-04 17:13:02.010708289 +0000 UTC m=+58.211309313" watchObservedRunningTime="2024-09-04 17:13:02.010845296 +0000 UTC m=+58.211446332" Sep 4 17:13:02.314536 systemd[1]: run-containerd-runc-k8s.io-1cd297cbd813fdbd42c11cb4fa258ed18dca35eb661663d05ff0ce9d5e499859-runc.LFhj8m.mount: Deactivated successfully. Sep 4 17:13:02.454319 systemd-networkd[1768]: veth2f07ccd9: Gained IPv6LL Sep 4 17:13:02.688914 kubelet[2340]: E0904 17:13:02.688840 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:03.690059 kubelet[2340]: E0904 17:13:03.689949 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:04.691145 kubelet[2340]: E0904 17:13:04.691069 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:05.243357 ntpd[1901]: Listen normally on 13 veth2f07ccd9 [fe80::b40e:64ff:fe04:a545%7]:123 Sep 4 17:13:05.244005 ntpd[1901]: 4 Sep 17:13:05 ntpd[1901]: Listen normally on 13 veth2f07ccd9 [fe80::b40e:64ff:fe04:a545%7]:123 Sep 4 17:13:05.648265 kubelet[2340]: E0904 17:13:05.648110 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:05.691577 kubelet[2340]: E0904 17:13:05.691505 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:06.692544 kubelet[2340]: E0904 17:13:06.692476 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:07.692971 kubelet[2340]: E0904 17:13:07.692914 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:08.693305 kubelet[2340]: E0904 17:13:08.693232 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:09.693808 kubelet[2340]: E0904 17:13:09.693738 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:10.694301 kubelet[2340]: E0904 17:13:10.694233 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:11.694736 kubelet[2340]: E0904 17:13:11.694662 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:12.695708 kubelet[2340]: E0904 17:13:12.695641 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:13.696725 kubelet[2340]: E0904 17:13:13.696656 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:14.697402 kubelet[2340]: E0904 17:13:14.697336 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:15.698026 kubelet[2340]: E0904 17:13:15.697921 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:16.698531 kubelet[2340]: E0904 17:13:16.698467 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:17.699366 kubelet[2340]: E0904 17:13:17.699305 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:18.700371 kubelet[2340]: E0904 17:13:18.700295 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:19.700824 kubelet[2340]: E0904 17:13:19.700765 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:20.701923 kubelet[2340]: E0904 17:13:20.701854 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:21.702603 kubelet[2340]: E0904 17:13:21.702533 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:22.703171 kubelet[2340]: E0904 17:13:22.702883 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:23.703826 kubelet[2340]: E0904 17:13:23.703751 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:24.704743 kubelet[2340]: E0904 17:13:24.704593 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:25.648452 kubelet[2340]: E0904 17:13:25.648392 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:25.705745 kubelet[2340]: E0904 17:13:25.705656 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:26.706099 kubelet[2340]: E0904 17:13:26.706031 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:27.534060 kubelet[2340]: E0904 17:13:27.533897 2340 controller.go:195] "Failed to update lease" err="the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io 172.31.19.17)" Sep 4 17:13:27.706518 kubelet[2340]: E0904 17:13:27.706436 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:28.707298 kubelet[2340]: E0904 17:13:28.707235 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:29.708344 kubelet[2340]: E0904 17:13:29.708256 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:30.709068 kubelet[2340]: E0904 17:13:30.708950 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:31.710109 kubelet[2340]: E0904 17:13:31.710038 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:32.711213 kubelet[2340]: E0904 17:13:32.711149 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:33.712404 kubelet[2340]: E0904 17:13:33.712339 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:34.713156 kubelet[2340]: E0904 17:13:34.713086 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:35.713318 kubelet[2340]: E0904 17:13:35.713248 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:36.714242 kubelet[2340]: E0904 17:13:36.714169 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:37.531205 kubelet[2340]: E0904 17:13:37.531121 2340 controller.go:195] "Failed to update lease" err="the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io 172.31.19.17)" Sep 4 17:13:37.715004 kubelet[2340]: E0904 17:13:37.714917 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:38.715110 kubelet[2340]: E0904 17:13:38.715041 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:39.715707 kubelet[2340]: E0904 17:13:39.715632 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:40.716230 kubelet[2340]: E0904 17:13:40.716163 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:41.717139 kubelet[2340]: E0904 17:13:41.717064 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:42.717288 kubelet[2340]: E0904 17:13:42.717218 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:43.717854 kubelet[2340]: E0904 17:13:43.717789 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:44.718275 kubelet[2340]: E0904 17:13:44.718206 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:45.648664 kubelet[2340]: E0904 17:13:45.648601 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:45.719107 kubelet[2340]: E0904 17:13:45.719053 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:46.719846 kubelet[2340]: E0904 17:13:46.719763 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:47.527910 kubelet[2340]: E0904 17:13:47.527626 2340 controller.go:195] "Failed to update lease" err="the server was unable to return a response in the time allotted, but may still be processing the request (put leases.coordination.k8s.io 172.31.19.17)" Sep 4 17:13:47.720551 kubelet[2340]: E0904 17:13:47.720491 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:48.720828 kubelet[2340]: E0904 17:13:48.720765 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:49.721581 kubelet[2340]: E0904 17:13:49.721515 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:50.722330 kubelet[2340]: E0904 17:13:50.722266 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:51.723515 kubelet[2340]: E0904 17:13:51.723454 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:52.724396 kubelet[2340]: E0904 17:13:52.724333 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:53.519743 kubelet[2340]: E0904 17:13:53.518816 2340 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": unexpected EOF" Sep 4 17:13:53.521631 kubelet[2340]: E0904 17:13:53.521583 2340 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": read tcp 172.31.19.17:40166->172.31.31.116:6443: read: connection reset by peer" Sep 4 17:13:53.521631 kubelet[2340]: I0904 17:13:53.521634 2340 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Sep 4 17:13:53.522489 kubelet[2340]: E0904 17:13:53.522354 2340 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": dial tcp 172.31.31.116:6443: connect: connection refused" interval="200ms" Sep 4 17:13:53.724144 kubelet[2340]: E0904 17:13:53.724106 2340 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": dial tcp 172.31.31.116:6443: connect: connection refused" interval="400ms" Sep 4 17:13:53.725150 kubelet[2340]: E0904 17:13:53.725098 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:54.125836 kubelet[2340]: E0904 17:13:54.125790 2340 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": dial tcp 172.31.31.116:6443: connect: connection refused" interval="800ms" Sep 4 17:13:54.725666 kubelet[2340]: E0904 17:13:54.725603 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:55.725872 kubelet[2340]: E0904 17:13:55.725810 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:56.726352 kubelet[2340]: E0904 17:13:56.726299 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:57.726881 kubelet[2340]: E0904 17:13:57.726822 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:58.727688 kubelet[2340]: E0904 17:13:58.727631 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:13:59.728472 kubelet[2340]: E0904 17:13:59.728411 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:00.729608 kubelet[2340]: E0904 17:14:00.729538 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:01.730110 kubelet[2340]: E0904 17:14:01.730041 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:02.730699 kubelet[2340]: E0904 17:14:02.730634 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:03.731735 kubelet[2340]: E0904 17:14:03.731679 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:04.732892 kubelet[2340]: E0904 17:14:04.732831 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:04.928309 kubelet[2340]: E0904 17:14:04.928256 2340 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.116:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.19.17?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="1.6s" Sep 4 17:14:05.648831 kubelet[2340]: E0904 17:14:05.648766 2340 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:05.733654 kubelet[2340]: E0904 17:14:05.733573 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:06.733774 kubelet[2340]: E0904 17:14:06.733720 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:07.733911 kubelet[2340]: E0904 17:14:07.733846 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:08.734930 kubelet[2340]: E0904 17:14:08.734859 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:08.781455 kubelet[2340]: E0904 17:14:08.781405 2340 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.19.17\": Get \"https://172.31.31.116:6443/api/v1/nodes/172.31.19.17?resourceVersion=0&timeout=10s\": dial tcp 172.31.31.116:6443: i/o timeout" Sep 4 17:14:09.735455 kubelet[2340]: E0904 17:14:09.735390 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:10.736612 kubelet[2340]: E0904 17:14:10.736555 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:11.737109 kubelet[2340]: E0904 17:14:11.737063 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 17:14:12.737548 kubelet[2340]: E0904 17:14:12.737493 2340 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"