Oct 16 23:51:38.939999 kernel: Linux version 6.6.157-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri Oct 16 22:12:49 -00 2026 Oct 16 23:51:38.940043 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=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=e6f976cfb3863fee9fd1f1addd18d31ce811d92bc04253d21ae5d2ad4890c977 Oct 16 23:51:38.940065 kernel: BIOS-provided physical RAM map: Oct 16 23:51:38.940078 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 16 23:51:38.940089 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000786cdfff] usable Oct 16 23:51:38.940099 kernel: BIOS-e820: [mem 0x00000000786ce000-0x00000000787cdfff] type 20 Oct 16 23:51:38.940113 kernel: BIOS-e820: [mem 0x00000000787ce000-0x000000007894dfff] reserved Oct 16 23:51:38.940126 kernel: BIOS-e820: [mem 0x000000007894e000-0x000000007895dfff] ACPI data Oct 16 23:51:38.940138 kernel: BIOS-e820: [mem 0x000000007895e000-0x00000000789ddfff] ACPI NVS Oct 16 23:51:38.940150 kernel: BIOS-e820: [mem 0x00000000789de000-0x000000007c97bfff] usable Oct 16 23:51:38.940165 kernel: BIOS-e820: [mem 0x000000007c97c000-0x000000007c9fffff] reserved Oct 16 23:51:38.940177 kernel: NX (Execute Disable) protection: active Oct 16 23:51:38.940188 kernel: APIC: Static calls initialized Oct 16 23:51:38.940199 kernel: efi: EFI v2.7 by EDK II Oct 16 23:51:38.940214 kernel: efi: SMBIOS=0x7886a000 ACPI=0x7895d000 ACPI 2.0=0x7895d014 MEMATTR=0x76f55518 Oct 16 23:51:38.940227 kernel: SMBIOS 2.7 present. Oct 16 23:51:38.940244 kernel: DMI: Amazon EC2 t3.small/, BIOS 1.0 10/16/2017 Oct 16 23:51:38.940257 kernel: Hypervisor detected: KVM Oct 16 23:51:38.940270 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 16 23:51:38.940284 kernel: kvm-clock: using sched offset of 3643232702 cycles Oct 16 23:51:38.940299 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 16 23:51:38.940313 kernel: tsc: Detected 2499.996 MHz processor Oct 16 23:51:38.940326 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 16 23:51:38.940341 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 16 23:51:38.940356 kernel: last_pfn = 0x7c97c max_arch_pfn = 0x400000000 Oct 16 23:51:38.940376 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Oct 16 23:51:38.940391 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 16 23:51:38.940407 kernel: Using GB pages for direct mapping Oct 16 23:51:38.940421 kernel: Secure boot disabled Oct 16 23:51:38.940435 kernel: ACPI: Early table checksum verification disabled Oct 16 23:51:38.940448 kernel: ACPI: RSDP 0x000000007895D014 000024 (v02 AMAZON) Oct 16 23:51:38.940461 kernel: ACPI: XSDT 0x000000007895C0E8 00006C (v01 AMAZON AMZNFACP 00000001 01000013) Oct 16 23:51:38.940474 kernel: ACPI: FACP 0x0000000078955000 000114 (v01 AMAZON AMZNFACP 00000001 AMZN 00000001) Oct 16 23:51:38.940487 kernel: ACPI: DSDT 0x0000000078956000 00115A (v01 AMAZON AMZNDSDT 00000001 AMZN 00000001) Oct 16 23:51:38.940503 kernel: ACPI: FACS 0x00000000789D0000 000040 Oct 16 23:51:38.940515 kernel: ACPI: WAET 0x000000007895B000 000028 (v01 AMAZON AMZNWAET 00000001 AMZN 00000001) Oct 16 23:51:38.940528 kernel: ACPI: SLIT 0x000000007895A000 00006C (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Oct 16 23:51:38.940540 kernel: ACPI: APIC 0x0000000078959000 000076 (v01 AMAZON AMZNAPIC 00000001 AMZN 00000001) Oct 16 23:51:38.940553 kernel: ACPI: SRAT 0x0000000078958000 0000A0 (v01 AMAZON AMZNSRAT 00000001 AMZN 00000001) Oct 16 23:51:38.940567 kernel: ACPI: HPET 0x0000000078954000 000038 (v01 AMAZON AMZNHPET 00000001 AMZN 00000001) Oct 16 23:51:38.940585 kernel: ACPI: SSDT 0x0000000078953000 000759 (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001) Oct 16 23:51:38.940602 kernel: ACPI: SSDT 0x0000000078952000 0000D1 (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001) Oct 16 23:51:38.940615 kernel: ACPI: BGRT 0x0000000078951000 000038 (v01 AMAZON AMAZON 00000002 01000013) Oct 16 23:51:38.940629 kernel: ACPI: Reserving FACP table memory at [mem 0x78955000-0x78955113] Oct 16 23:51:38.940642 kernel: ACPI: Reserving DSDT table memory at [mem 0x78956000-0x78957159] Oct 16 23:51:38.940655 kernel: ACPI: Reserving FACS table memory at [mem 0x789d0000-0x789d003f] Oct 16 23:51:38.940669 kernel: ACPI: Reserving WAET table memory at [mem 0x7895b000-0x7895b027] Oct 16 23:51:38.940682 kernel: ACPI: Reserving SLIT table memory at [mem 0x7895a000-0x7895a06b] Oct 16 23:51:38.940700 kernel: ACPI: Reserving APIC table memory at [mem 0x78959000-0x78959075] Oct 16 23:51:38.940713 kernel: ACPI: Reserving SRAT table memory at [mem 0x78958000-0x7895809f] Oct 16 23:51:38.940727 kernel: ACPI: Reserving HPET table memory at [mem 0x78954000-0x78954037] Oct 16 23:51:38.940741 kernel: ACPI: Reserving SSDT table memory at [mem 0x78953000-0x78953758] Oct 16 23:51:38.940755 kernel: ACPI: Reserving SSDT table memory at [mem 0x78952000-0x789520d0] Oct 16 23:51:38.940798 kernel: ACPI: Reserving BGRT table memory at [mem 0x78951000-0x78951037] Oct 16 23:51:38.940811 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Oct 16 23:51:38.940824 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Oct 16 23:51:38.940834 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x7fffffff] Oct 16 23:51:38.940849 kernel: NUMA: Initialized distance table, cnt=1 Oct 16 23:51:38.940861 kernel: NODE_DATA(0) allocated [mem 0x7a8f2000-0x7a8f7fff] Oct 16 23:51:38.940873 kernel: Zone ranges: Oct 16 23:51:38.940886 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 16 23:51:38.940899 kernel: DMA32 [mem 0x0000000001000000-0x000000007c97bfff] Oct 16 23:51:38.940911 kernel: Normal empty Oct 16 23:51:38.940923 kernel: Movable zone start for each node Oct 16 23:51:38.940935 kernel: Early memory node ranges Oct 16 23:51:38.940947 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 16 23:51:38.940960 kernel: node 0: [mem 0x0000000000100000-0x00000000786cdfff] Oct 16 23:51:38.940978 kernel: node 0: [mem 0x00000000789de000-0x000000007c97bfff] Oct 16 23:51:38.940992 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007c97bfff] Oct 16 23:51:38.941006 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 16 23:51:38.941018 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 16 23:51:38.941029 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges Oct 16 23:51:38.941117 kernel: On node 0, zone DMA32: 13956 pages in unavailable ranges Oct 16 23:51:38.941132 kernel: ACPI: PM-Timer IO Port: 0xb008 Oct 16 23:51:38.941147 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 16 23:51:38.941161 kernel: IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 Oct 16 23:51:38.941180 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 16 23:51:38.941194 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 16 23:51:38.941209 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 16 23:51:38.941224 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 16 23:51:38.941238 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 16 23:51:38.941253 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Oct 16 23:51:38.941267 kernel: TSC deadline timer available Oct 16 23:51:38.941282 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Oct 16 23:51:38.941295 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 16 23:51:38.941313 kernel: [mem 0x7ca00000-0xffffffff] available for PCI devices Oct 16 23:51:38.941326 kernel: Booting paravirtualized kernel on KVM Oct 16 23:51:38.941339 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 16 23:51:38.941354 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Oct 16 23:51:38.941368 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u1048576 Oct 16 23:51:38.941382 kernel: pcpu-alloc: s196392 r8192 d28888 u1048576 alloc=1*2097152 Oct 16 23:51:38.941395 kernel: pcpu-alloc: [0] 0 1 Oct 16 23:51:38.941407 kernel: kvm-guest: PV spinlocks enabled Oct 16 23:51:38.941422 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 16 23:51:38.941441 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=e6f976cfb3863fee9fd1f1addd18d31ce811d92bc04253d21ae5d2ad4890c977 Oct 16 23:51:38.941458 kernel: random: crng init done Oct 16 23:51:38.941472 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 16 23:51:38.941487 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 16 23:51:38.941501 kernel: Fallback order for Node 0: 0 Oct 16 23:51:38.941515 kernel: Built 1 zonelists, mobility grouping on. Total pages: 501318 Oct 16 23:51:38.941531 kernel: Policy zone: DMA32 Oct 16 23:51:38.941547 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 16 23:51:38.941566 kernel: Memory: 1865148K/2037804K available (14336K kernel code, 2288K rwdata, 22772K rodata, 42928K init, 2228K bss, 172396K reserved, 0K cma-reserved) Oct 16 23:51:38.941581 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 16 23:51:38.941595 kernel: Kernel/User page tables isolation: enabled Oct 16 23:51:38.941608 kernel: ftrace: allocating 37511 entries in 148 pages Oct 16 23:51:38.941621 kernel: ftrace: allocated 148 pages with 3 groups Oct 16 23:51:38.941635 kernel: Dynamic Preempt: voluntary Oct 16 23:51:38.941647 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 16 23:51:38.941662 kernel: rcu: RCU event tracing is enabled. Oct 16 23:51:38.941676 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 16 23:51:38.941694 kernel: Trampoline variant of Tasks RCU enabled. Oct 16 23:51:38.941708 kernel: Rude variant of Tasks RCU enabled. Oct 16 23:51:38.941721 kernel: Tracing variant of Tasks RCU enabled. Oct 16 23:51:38.941733 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 16 23:51:38.941746 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 16 23:51:38.941760 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Oct 16 23:51:38.944816 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 16 23:51:38.944863 kernel: Console: colour dummy device 80x25 Oct 16 23:51:38.944881 kernel: printk: console [tty0] enabled Oct 16 23:51:38.944898 kernel: printk: console [ttyS0] enabled Oct 16 23:51:38.944915 kernel: ACPI: Core revision 20230628 Oct 16 23:51:38.944933 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 30580167144 ns Oct 16 23:51:38.944954 kernel: APIC: Switch to symmetric I/O mode setup Oct 16 23:51:38.944972 kernel: x2apic enabled Oct 16 23:51:38.944989 kernel: APIC: Switched APIC routing to: physical x2apic Oct 16 23:51:38.945007 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x24093623c91, max_idle_ns: 440795291220 ns Oct 16 23:51:38.945025 kernel: Calibrating delay loop (skipped) preset value.. 4999.99 BogoMIPS (lpj=2499996) Oct 16 23:51:38.945056 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Oct 16 23:51:38.945073 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 16 23:51:38.945090 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 16 23:51:38.945106 kernel: Spectre V2 : Mitigation: Retpolines Oct 16 23:51:38.945122 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 16 23:51:38.945138 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Oct 16 23:51:38.945156 kernel: RETBleed: Vulnerable Oct 16 23:51:38.945172 kernel: Speculative Store Bypass: Vulnerable Oct 16 23:51:38.945191 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Oct 16 23:51:38.945207 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 16 23:51:38.945228 kernel: GDS: Unknown: Dependent on hypervisor status Oct 16 23:51:38.945244 kernel: active return thunk: its_return_thunk Oct 16 23:51:38.945261 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 16 23:51:38.945277 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 16 23:51:38.945294 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 16 23:51:38.945311 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 16 23:51:38.945329 kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' Oct 16 23:51:38.945346 kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' Oct 16 23:51:38.945362 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Oct 16 23:51:38.945379 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Oct 16 23:51:38.945395 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Oct 16 23:51:38.945415 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' Oct 16 23:51:38.945432 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 16 23:51:38.945448 kernel: x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 Oct 16 23:51:38.945466 kernel: x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 Oct 16 23:51:38.945482 kernel: x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 Oct 16 23:51:38.945499 kernel: x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 Oct 16 23:51:38.945515 kernel: x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 Oct 16 23:51:38.945532 kernel: x86/fpu: xstate_offset[9]: 2560, xstate_sizes[9]: 8 Oct 16 23:51:38.945549 kernel: x86/fpu: Enabled xstate features 0x2ff, context size is 2568 bytes, using 'compacted' format. Oct 16 23:51:38.945565 kernel: Freeing SMP alternatives memory: 32K Oct 16 23:51:38.945581 kernel: pid_max: default: 32768 minimum: 301 Oct 16 23:51:38.945597 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 16 23:51:38.945616 kernel: landlock: Up and running. Oct 16 23:51:38.945632 kernel: SELinux: Initializing. Oct 16 23:51:38.945648 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 16 23:51:38.945665 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 16 23:51:38.945681 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8259CL CPU @ 2.50GHz (family: 0x6, model: 0x55, stepping: 0x7) Oct 16 23:51:38.945696 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 16 23:51:38.945711 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 16 23:51:38.945726 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Oct 16 23:51:38.945742 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Oct 16 23:51:38.945761 kernel: signal: max sigframe size: 3632 Oct 16 23:51:38.945797 kernel: rcu: Hierarchical SRCU implementation. Oct 16 23:51:38.945813 kernel: rcu: Max phase no-delay instances is 400. Oct 16 23:51:38.945827 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 16 23:51:38.945841 kernel: smp: Bringing up secondary CPUs ... Oct 16 23:51:38.945857 kernel: smpboot: x86: Booting SMP configuration: Oct 16 23:51:38.945872 kernel: .... node #0, CPUs: #1 Oct 16 23:51:38.945888 kernel: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Oct 16 23:51:38.945905 kernel: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Oct 16 23:51:38.945924 kernel: smp: Brought up 1 node, 2 CPUs Oct 16 23:51:38.945940 kernel: smpboot: Max logical packages: 1 Oct 16 23:51:38.945955 kernel: smpboot: Total of 2 processors activated (9999.98 BogoMIPS) Oct 16 23:51:38.945971 kernel: devtmpfs: initialized Oct 16 23:51:38.945987 kernel: x86/mm: Memory block size: 128MB Oct 16 23:51:38.946003 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x7895e000-0x789ddfff] (524288 bytes) Oct 16 23:51:38.946020 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 16 23:51:38.946037 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 16 23:51:38.946054 kernel: pinctrl core: initialized pinctrl subsystem Oct 16 23:51:38.946075 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 16 23:51:38.946092 kernel: audit: initializing netlink subsys (disabled) Oct 16 23:51:38.946111 kernel: audit: type=2000 audit(1792194698.524:1): state=initialized audit_enabled=0 res=1 Oct 16 23:51:38.946128 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 16 23:51:38.946146 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 16 23:51:38.946165 kernel: cpuidle: using governor menu Oct 16 23:51:38.946182 kernel: efi: Freeing EFI boot services memory: 45196K Oct 16 23:51:38.946199 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 16 23:51:38.946217 kernel: dca service started, version 1.12.1 Oct 16 23:51:38.946238 kernel: PCI: Using configuration type 1 for base access Oct 16 23:51:38.946256 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 16 23:51:38.946275 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 16 23:51:38.946292 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 16 23:51:38.946310 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 16 23:51:38.946328 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 16 23:51:38.946345 kernel: ACPI: Added _OSI(Module Device) Oct 16 23:51:38.946362 kernel: ACPI: Added _OSI(Processor Device) Oct 16 23:51:38.946380 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 16 23:51:38.946399 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded Oct 16 23:51:38.946414 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 16 23:51:38.946431 kernel: ACPI: Interpreter enabled Oct 16 23:51:38.946447 kernel: ACPI: PM: (supports S0 S5) Oct 16 23:51:38.946463 kernel: ACPI: Using IOAPIC for interrupt routing Oct 16 23:51:38.946478 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 16 23:51:38.946495 kernel: PCI: Using E820 reservations for host bridge windows Oct 16 23:51:38.946510 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Oct 16 23:51:38.946526 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 16 23:51:38.947846 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Oct 16 23:51:38.948055 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Oct 16 23:51:38.948221 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Oct 16 23:51:38.948241 kernel: acpiphp: Slot [3] registered Oct 16 23:51:38.948258 kernel: acpiphp: Slot [4] registered Oct 16 23:51:38.948273 kernel: acpiphp: Slot [5] registered Oct 16 23:51:38.948289 kernel: acpiphp: Slot [6] registered Oct 16 23:51:38.948309 kernel: acpiphp: Slot [7] registered Oct 16 23:51:38.948325 kernel: acpiphp: Slot [8] registered Oct 16 23:51:38.948339 kernel: acpiphp: Slot [9] registered Oct 16 23:51:38.948354 kernel: acpiphp: Slot [10] registered Oct 16 23:51:38.948369 kernel: acpiphp: Slot [11] registered Oct 16 23:51:38.948384 kernel: acpiphp: Slot [12] registered Oct 16 23:51:38.948399 kernel: acpiphp: Slot [13] registered Oct 16 23:51:38.948415 kernel: acpiphp: Slot [14] registered Oct 16 23:51:38.948430 kernel: acpiphp: Slot [15] registered Oct 16 23:51:38.948445 kernel: acpiphp: Slot [16] registered Oct 16 23:51:38.948464 kernel: acpiphp: Slot [17] registered Oct 16 23:51:38.948479 kernel: acpiphp: Slot [18] registered Oct 16 23:51:38.948495 kernel: acpiphp: Slot [19] registered Oct 16 23:51:38.948510 kernel: acpiphp: Slot [20] registered Oct 16 23:51:38.948525 kernel: acpiphp: Slot [21] registered Oct 16 23:51:38.948540 kernel: acpiphp: Slot [22] registered Oct 16 23:51:38.948556 kernel: acpiphp: Slot [23] registered Oct 16 23:51:38.948571 kernel: acpiphp: Slot [24] registered Oct 16 23:51:38.948587 kernel: acpiphp: Slot [25] registered Oct 16 23:51:38.948606 kernel: acpiphp: Slot [26] registered Oct 16 23:51:38.948621 kernel: acpiphp: Slot [27] registered Oct 16 23:51:38.948637 kernel: acpiphp: Slot [28] registered Oct 16 23:51:38.948652 kernel: acpiphp: Slot [29] registered Oct 16 23:51:38.948668 kernel: acpiphp: Slot [30] registered Oct 16 23:51:38.948684 kernel: acpiphp: Slot [31] registered Oct 16 23:51:38.948700 kernel: PCI host bridge to bus 0000:00 Oct 16 23:51:38.949894 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 16 23:51:38.950052 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 16 23:51:38.950193 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 16 23:51:38.950329 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Oct 16 23:51:38.950454 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x2000ffffffff window] Oct 16 23:51:38.950575 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 16 23:51:38.950733 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Oct 16 23:51:38.954621 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Oct 16 23:51:38.954828 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x000000 Oct 16 23:51:38.954973 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI Oct 16 23:51:38.955113 kernel: pci 0000:00:01.3: PIIX4 devres E PIO at fff0-ffff Oct 16 23:51:38.955251 kernel: pci 0000:00:01.3: PIIX4 devres F MMIO at ffc00000-ffffffff Oct 16 23:51:38.955387 kernel: pci 0000:00:01.3: PIIX4 devres G PIO at fff0-ffff Oct 16 23:51:38.955524 kernel: pci 0000:00:01.3: PIIX4 devres H MMIO at ffc00000-ffffffff Oct 16 23:51:38.955661 kernel: pci 0000:00:01.3: PIIX4 devres I PIO at fff0-ffff Oct 16 23:51:38.955825 kernel: pci 0000:00:01.3: PIIX4 devres J PIO at fff0-ffff Oct 16 23:51:38.955976 kernel: pci 0000:00:03.0: [1d0f:1111] type 00 class 0x030000 Oct 16 23:51:38.956114 kernel: pci 0000:00:03.0: BAR 0 [mem 0x80000000-0x803fffff pref] Oct 16 23:51:38.956251 kernel: pci 0000:00:03.0: ROM [mem 0xffff0000-0xffffffff pref] Oct 16 23:51:38.956393 kernel: pci 0000:00:03.0: BAR 0: assigned to efifb Oct 16 23:51:38.956534 kernel: pci 0000:00:03.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 16 23:51:38.956679 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Oct 16 23:51:38.956905 kernel: pci 0000:00:04.0: BAR 0 [mem 0x80404000-0x80407fff] Oct 16 23:51:38.957097 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Oct 16 23:51:38.957258 kernel: pci 0000:00:05.0: BAR 0 [mem 0x80400000-0x80403fff] Oct 16 23:51:38.957284 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 16 23:51:38.957304 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 16 23:51:38.957323 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 16 23:51:38.957343 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 16 23:51:38.957368 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Oct 16 23:51:38.957388 kernel: iommu: Default domain type: Translated Oct 16 23:51:38.957407 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 16 23:51:38.957427 kernel: efivars: Registered efivars operations Oct 16 23:51:38.957446 kernel: PCI: Using ACPI for IRQ routing Oct 16 23:51:38.957466 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 16 23:51:38.957486 kernel: e820: reserve RAM buffer [mem 0x786ce000-0x7bffffff] Oct 16 23:51:38.957504 kernel: e820: reserve RAM buffer [mem 0x7c97c000-0x7fffffff] Oct 16 23:51:38.957661 kernel: pci 0000:00:03.0: vgaarb: setting as boot VGA device Oct 16 23:51:38.958921 kernel: pci 0000:00:03.0: vgaarb: bridge control possible Oct 16 23:51:38.959101 kernel: pci 0000:00:03.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 16 23:51:38.959125 kernel: vgaarb: loaded Oct 16 23:51:38.959143 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 Oct 16 23:51:38.959157 kernel: hpet0: 8 comparators, 32-bit 62.500000 MHz counter Oct 16 23:51:38.959170 kernel: clocksource: Switched to clocksource kvm-clock Oct 16 23:51:38.959185 kernel: VFS: Disk quotas dquot_6.6.0 Oct 16 23:51:38.959201 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 16 23:51:38.959215 kernel: pnp: PnP ACPI init Oct 16 23:51:38.959235 kernel: pnp: PnP ACPI: found 5 devices Oct 16 23:51:38.959247 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 16 23:51:38.959262 kernel: NET: Registered PF_INET protocol family Oct 16 23:51:38.959275 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 16 23:51:38.959290 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Oct 16 23:51:38.959307 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 16 23:51:38.959323 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 16 23:51:38.959339 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Oct 16 23:51:38.959359 kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 16 23:51:38.959375 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 16 23:51:38.959390 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 16 23:51:38.959406 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 16 23:51:38.959422 kernel: NET: Registered PF_XDP protocol family Oct 16 23:51:38.959581 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 16 23:51:38.959712 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 16 23:51:38.961946 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 16 23:51:38.962096 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Oct 16 23:51:38.962260 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x2000ffffffff window] Oct 16 23:51:38.962422 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Oct 16 23:51:38.962445 kernel: PCI: CLS 0 bytes, default 64 Oct 16 23:51:38.962462 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 16 23:51:38.962479 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x24093623c91, max_idle_ns: 440795291220 ns Oct 16 23:51:38.962496 kernel: clocksource: Switched to clocksource tsc Oct 16 23:51:38.962512 kernel: Initialise system trusted keyrings Oct 16 23:51:38.962528 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Oct 16 23:51:38.962550 kernel: Key type asymmetric registered Oct 16 23:51:38.962565 kernel: Asymmetric key parser 'x509' registered Oct 16 23:51:38.962581 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 16 23:51:38.962598 kernel: io scheduler mq-deadline registered Oct 16 23:51:38.962615 kernel: io scheduler kyber registered Oct 16 23:51:38.962631 kernel: io scheduler bfq registered Oct 16 23:51:38.962647 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 16 23:51:38.962664 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 16 23:51:38.962680 kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 16 23:51:38.962700 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 16 23:51:38.962716 kernel: i8042: Warning: Keylock active Oct 16 23:51:38.962732 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 16 23:51:38.962749 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 16 23:51:38.962918 kernel: rtc_cmos 00:00: RTC can wake from S4 Oct 16 23:51:38.963047 kernel: rtc_cmos 00:00: registered as rtc0 Oct 16 23:51:38.963173 kernel: rtc_cmos 00:00: setting system clock to 2026-10-16T23:51:38 UTC (1792194698) Oct 16 23:51:38.963296 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram Oct 16 23:51:38.963320 kernel: intel_pstate: CPU model not supported Oct 16 23:51:38.963337 kernel: efifb: probing for efifb Oct 16 23:51:38.963354 kernel: efifb: framebuffer at 0x80000000, using 1920k, total 1920k Oct 16 23:51:38.963370 kernel: efifb: mode is 800x600x32, linelength=3200, pages=1 Oct 16 23:51:38.963387 kernel: efifb: scrolling: redraw Oct 16 23:51:38.963403 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 16 23:51:38.963419 kernel: Console: switching to colour frame buffer device 100x37 Oct 16 23:51:38.963436 kernel: fb0: EFI VGA frame buffer device Oct 16 23:51:38.963452 kernel: pstore: Using crash dump compression: deflate Oct 16 23:51:38.963472 kernel: pstore: Registered efi_pstore as persistent store backend Oct 16 23:51:38.963488 kernel: NET: Registered PF_INET6 protocol family Oct 16 23:51:38.963504 kernel: Segment Routing with IPv6 Oct 16 23:51:38.963520 kernel: In-situ OAM (IOAM) with IPv6 Oct 16 23:51:38.963536 kernel: NET: Registered PF_PACKET protocol family Oct 16 23:51:38.963552 kernel: Key type dns_resolver registered Oct 16 23:51:38.963594 kernel: IPI shorthand broadcast: enabled Oct 16 23:51:38.963614 kernel: sched_clock: Marking stable (478001464, 133281455)->(703034248, -91751329) Oct 16 23:51:38.963631 kernel: registered taskstats version 1 Oct 16 23:51:38.963651 kernel: Loading compiled-in X.509 certificates Oct 16 23:51:38.963668 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.157-flatcar: 36b75ce1b227d9b3c384e550ebf5739bdec2813e' Oct 16 23:51:38.963685 kernel: Key type .fscrypt registered Oct 16 23:51:38.963702 kernel: Key type fscrypt-provisioning registered Oct 16 23:51:38.963718 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 16 23:51:38.963735 kernel: ima: Allocated hash algorithm: sha1 Oct 16 23:51:38.963752 kernel: ima: No architecture policies found Oct 16 23:51:38.963769 kernel: clk: Disabling unused clocks Oct 16 23:51:38.963797 kernel: Freeing unused kernel image (initmem) memory: 42928K Oct 16 23:51:38.963818 kernel: Write protecting the kernel read-only data: 38912k Oct 16 23:51:38.963835 kernel: Freeing unused kernel image (rodata/data gap) memory: 1804K Oct 16 23:51:38.963852 kernel: Run /init as init process Oct 16 23:51:38.963869 kernel: with arguments: Oct 16 23:51:38.963886 kernel: /init Oct 16 23:51:38.963903 kernel: with environment: Oct 16 23:51:38.963919 kernel: HOME=/ Oct 16 23:51:38.963936 kernel: TERM=linux Oct 16 23:51:38.963956 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) Oct 16 23:51:38.963979 systemd[1]: Detected virtualization amazon. Oct 16 23:51:38.963997 systemd[1]: Detected architecture x86-64. Oct 16 23:51:38.964014 systemd[1]: Running in initrd. Oct 16 23:51:38.964031 systemd[1]: No hostname configured, using default hostname. Oct 16 23:51:38.964048 systemd[1]: Hostname set to . Oct 16 23:51:38.964067 systemd[1]: Initializing machine ID from VM UUID. Oct 16 23:51:38.964084 systemd[1]: Queued start job for default target initrd.target. Oct 16 23:51:38.964102 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 16 23:51:38.964123 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 16 23:51:38.964142 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 16 23:51:38.964163 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 16 23:51:38.964184 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 16 23:51:38.964202 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 16 23:51:38.964225 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 16 23:51:38.964243 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 16 23:51:38.964261 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 16 23:51:38.964279 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 16 23:51:38.964297 systemd[1]: Reached target paths.target - Path Units. Oct 16 23:51:38.964315 systemd[1]: Reached target slices.target - Slice Units. Oct 16 23:51:38.964333 systemd[1]: Reached target swap.target - Swaps. Oct 16 23:51:38.964353 systemd[1]: Reached target timers.target - Timer Units. Oct 16 23:51:38.964371 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 16 23:51:38.964389 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 16 23:51:38.964407 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 16 23:51:38.964424 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 16 23:51:38.964442 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 16 23:51:38.964460 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 16 23:51:38.964478 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 16 23:51:38.964499 systemd[1]: Reached target sockets.target - Socket Units. Oct 16 23:51:38.964517 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 16 23:51:38.964535 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 16 23:51:38.964553 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 16 23:51:38.964571 systemd[1]: Starting systemd-fsck-usr.service... Oct 16 23:51:38.964589 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 16 23:51:38.964607 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 16 23:51:38.964625 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:38.964643 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 16 23:51:38.964664 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 16 23:51:38.964682 systemd[1]: Finished systemd-fsck-usr.service. Oct 16 23:51:38.964728 systemd-journald[179]: Collecting audit messages is disabled. Oct 16 23:51:38.964809 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 16 23:51:38.964837 systemd-journald[179]: Journal started Oct 16 23:51:38.964870 systemd-journald[179]: Runtime Journal (/run/log/journal/ec242ac44817dbf697d4c28d65c9f8fa) is 4.7M, max 38.1M, 33.4M free. Oct 16 23:51:38.968900 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:38.965157 systemd-modules-load[180]: Inserted module 'overlay' Oct 16 23:51:38.974257 systemd[1]: Started systemd-journald.service - Journal Service. Oct 16 23:51:38.983023 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 16 23:51:38.992984 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 16 23:51:38.996842 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 16 23:51:39.006803 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 16 23:51:39.009750 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 16 23:51:39.025825 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 16 23:51:39.028495 systemd-modules-load[180]: Inserted module 'br_netfilter' Oct 16 23:51:39.031917 kernel: Bridge firewalling registered Oct 16 23:51:39.030868 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 16 23:51:39.034846 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 16 23:51:39.037384 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 16 23:51:39.039311 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 16 23:51:39.046044 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 16 23:51:39.048584 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 16 23:51:39.065715 dracut-cmdline[210]: dracut-dracut-053 Oct 16 23:51:39.067032 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 16 23:51:39.069611 dracut-cmdline[210]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=e6f976cfb3863fee9fd1f1addd18d31ce811d92bc04253d21ae5d2ad4890c977 Oct 16 23:51:39.079387 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 16 23:51:39.121020 systemd-resolved[226]: Positive Trust Anchors: Oct 16 23:51:39.122095 systemd-resolved[226]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 16 23:51:39.122162 systemd-resolved[226]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 16 23:51:39.131153 systemd-resolved[226]: Defaulting to hostname 'linux'. Oct 16 23:51:39.132562 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 16 23:51:39.133740 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 16 23:51:39.166807 kernel: SCSI subsystem initialized Oct 16 23:51:39.176798 kernel: Loading iSCSI transport class v2.0-870. Oct 16 23:51:39.188813 kernel: iscsi: registered transport (tcp) Oct 16 23:51:39.210866 kernel: iscsi: registered transport (qla4xxx) Oct 16 23:51:39.210944 kernel: QLogic iSCSI HBA Driver Oct 16 23:51:39.251464 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 16 23:51:39.257104 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 16 23:51:39.285858 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 16 23:51:39.285937 kernel: device-mapper: uevent: version 1.0.3 Oct 16 23:51:39.287344 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 16 23:51:39.331837 kernel: raid6: avx512x4 gen() 17900 MB/s Oct 16 23:51:39.349810 kernel: raid6: avx512x2 gen() 16325 MB/s Oct 16 23:51:39.367827 kernel: raid6: avx512x1 gen() 15294 MB/s Oct 16 23:51:39.385819 kernel: raid6: avx2x4 gen() 15684 MB/s Oct 16 23:51:39.403813 kernel: raid6: avx2x2 gen() 17345 MB/s Oct 16 23:51:39.421989 kernel: raid6: avx2x1 gen() 13315 MB/s Oct 16 23:51:39.422077 kernel: raid6: using algorithm avx512x4 gen() 17900 MB/s Oct 16 23:51:39.440972 kernel: raid6: .... xor() 7135 MB/s, rmw enabled Oct 16 23:51:39.441120 kernel: raid6: using avx512x2 recovery algorithm Oct 16 23:51:39.464804 kernel: xor: automatically using best checksumming function avx Oct 16 23:51:39.636802 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 16 23:51:39.647424 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 16 23:51:39.652014 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 16 23:51:39.678441 systemd-udevd[398]: Using default interface naming scheme 'v255'. Oct 16 23:51:39.683732 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 16 23:51:39.695044 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 16 23:51:39.716147 dracut-pre-trigger[404]: rd.md=0: removing MD RAID activation Oct 16 23:51:39.749478 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 16 23:51:39.753991 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 16 23:51:39.811586 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 16 23:51:39.820664 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 16 23:51:39.849010 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 16 23:51:39.851447 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 16 23:51:39.853925 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 16 23:51:39.855079 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 16 23:51:39.863039 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 16 23:51:39.894822 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 16 23:51:39.914828 kernel: cryptd: max_cpu_qlen set to 1000 Oct 16 23:51:39.927665 kernel: ena 0000:00:05.0: ENA device version: 0.10 Oct 16 23:51:39.928024 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Oct 16 23:51:39.937325 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 16 23:51:39.943085 kernel: AVX2 version of gcm_enc/dec engaged. Oct 16 23:51:39.943123 kernel: AES CTR mode by8 optimization enabled Oct 16 23:51:39.937495 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 16 23:51:39.938351 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 16 23:51:39.947901 kernel: ena 0000:00:05.0: LLQ is not supported Fallback to host mode policy. Oct 16 23:51:39.938978 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 16 23:51:39.939188 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:39.943913 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:39.953295 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:39.964795 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80400000, mac addr 06:ff:ec:c7:b7:61 Oct 16 23:51:39.961304 (udev-worker)[458]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:51:39.991684 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 16 23:51:39.998332 kernel: nvme nvme0: pci function 0000:00:04.0 Oct 16 23:51:39.998563 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Oct 16 23:51:39.993961 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:40.008794 kernel: nvme nvme0: 2/0/0 default/read/poll queues Oct 16 23:51:40.012074 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:40.019063 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 16 23:51:40.019127 kernel: GPT:9289727 != 33554431 Oct 16 23:51:40.020251 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 16 23:51:40.020306 kernel: GPT:9289727 != 33554431 Oct 16 23:51:40.020326 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 16 23:51:40.020345 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 16 23:51:40.035068 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:40.043071 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 16 23:51:40.074698 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 16 23:51:40.093175 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/nvme0n1p6 scanned by (udev-worker) (447) Oct 16 23:51:40.099852 kernel: BTRFS: device fsid 9c98ddf1-5877-4958-82de-0d39127d9d38 devid 1 transid 29 /dev/nvme0n1p3 scanned by (udev-worker) (458) Oct 16 23:51:40.170022 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Oct 16 23:51:40.193734 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 16 23:51:40.200530 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Oct 16 23:51:40.206554 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Oct 16 23:51:40.207252 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Oct 16 23:51:40.213963 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 16 23:51:40.221503 disk-uuid[634]: Primary Header is updated. Oct 16 23:51:40.221503 disk-uuid[634]: Secondary Entries is updated. Oct 16 23:51:40.221503 disk-uuid[634]: Secondary Header is updated. Oct 16 23:51:40.229805 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 16 23:51:40.238807 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 16 23:51:40.246809 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 16 23:51:41.249681 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 16 23:51:41.249747 disk-uuid[635]: The operation has completed successfully. Oct 16 23:51:41.392748 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 16 23:51:41.392928 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 16 23:51:41.421192 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 16 23:51:41.429251 sh[978]: Success Oct 16 23:51:41.449017 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Oct 16 23:51:41.560912 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 16 23:51:41.575591 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 16 23:51:41.578921 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 16 23:51:41.618511 kernel: BTRFS info (device dm-0): first mount of filesystem 9c98ddf1-5877-4958-82de-0d39127d9d38 Oct 16 23:51:41.618590 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 16 23:51:41.618614 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 16 23:51:41.620931 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 16 23:51:41.623385 kernel: BTRFS info (device dm-0): using free space tree Oct 16 23:51:41.637806 kernel: BTRFS info (device dm-0): enabling ssd optimizations Oct 16 23:51:41.641144 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 16 23:51:41.642365 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 16 23:51:41.648978 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 16 23:51:41.652122 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 16 23:51:41.675610 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem b68cb5ed-e0a7-4edf-ad04-bb5772eb7ffc Oct 16 23:51:41.675680 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Oct 16 23:51:41.677398 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 16 23:51:41.685805 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 16 23:51:41.697209 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 16 23:51:41.699957 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem b68cb5ed-e0a7-4edf-ad04-bb5772eb7ffc Oct 16 23:51:41.708218 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 16 23:51:41.717087 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 16 23:51:41.785049 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 16 23:51:41.797834 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 16 23:51:41.843437 systemd-networkd[1172]: lo: Link UP Oct 16 23:51:41.843809 systemd-networkd[1172]: lo: Gained carrier Oct 16 23:51:41.847029 systemd-networkd[1172]: Enumeration completed Oct 16 23:51:41.848149 systemd-networkd[1172]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 16 23:51:41.848154 systemd-networkd[1172]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 16 23:51:41.849935 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 16 23:51:41.851186 systemd[1]: Reached target network.target - Network. Oct 16 23:51:41.863080 systemd-networkd[1172]: eth0: Link UP Oct 16 23:51:41.863086 systemd-networkd[1172]: eth0: Gained carrier Oct 16 23:51:41.863103 systemd-networkd[1172]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 16 23:51:41.878888 systemd-networkd[1172]: eth0: DHCPv4 address 172.31.28.79/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 16 23:51:41.887259 ignition[1095]: Ignition 2.19.0 Oct 16 23:51:41.887271 ignition[1095]: Stage: fetch-offline Oct 16 23:51:41.887544 ignition[1095]: no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:41.887558 ignition[1095]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:41.889733 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 16 23:51:41.888114 ignition[1095]: Ignition finished successfully Oct 16 23:51:41.896993 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 16 23:51:41.912013 ignition[1180]: Ignition 2.19.0 Oct 16 23:51:41.912026 ignition[1180]: Stage: fetch Oct 16 23:51:41.912483 ignition[1180]: no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:41.912498 ignition[1180]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:41.912625 ignition[1180]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:41.923897 ignition[1180]: PUT result: OK Oct 16 23:51:41.926202 ignition[1180]: parsed url from cmdline: "" Oct 16 23:51:41.926216 ignition[1180]: no config URL provided Oct 16 23:51:41.926228 ignition[1180]: reading system config file "/usr/lib/ignition/user.ign" Oct 16 23:51:41.926243 ignition[1180]: no config at "/usr/lib/ignition/user.ign" Oct 16 23:51:41.926267 ignition[1180]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:41.927189 ignition[1180]: PUT result: OK Oct 16 23:51:41.927240 ignition[1180]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Oct 16 23:51:41.927912 ignition[1180]: GET result: OK Oct 16 23:51:41.927967 ignition[1180]: parsing config with SHA512: 159e887d881a5c3d7a21708fffe3e6381b47f08d3fc3fe5c5187ebd2bb2607a3fa98985e17b8afebe1e0606f1fca0f3c9b021f869471affc7216856d9a1e72bb Oct 16 23:51:41.932240 unknown[1180]: fetched base config from "system" Oct 16 23:51:41.932254 unknown[1180]: fetched base config from "system" Oct 16 23:51:41.932716 ignition[1180]: fetch: fetch complete Oct 16 23:51:41.932264 unknown[1180]: fetched user config from "aws" Oct 16 23:51:41.932723 ignition[1180]: fetch: fetch passed Oct 16 23:51:41.935840 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 16 23:51:41.932794 ignition[1180]: Ignition finished successfully Oct 16 23:51:41.941001 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 16 23:51:41.957669 ignition[1187]: Ignition 2.19.0 Oct 16 23:51:41.957682 ignition[1187]: Stage: kargs Oct 16 23:51:41.958189 ignition[1187]: no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:41.958203 ignition[1187]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:41.958325 ignition[1187]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:41.959242 ignition[1187]: PUT result: OK Oct 16 23:51:41.962382 ignition[1187]: kargs: kargs passed Oct 16 23:51:41.962455 ignition[1187]: Ignition finished successfully Oct 16 23:51:41.964359 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 16 23:51:41.970043 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 16 23:51:41.985577 ignition[1193]: Ignition 2.19.0 Oct 16 23:51:41.985590 ignition[1193]: Stage: disks Oct 16 23:51:41.986082 ignition[1193]: no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:41.986097 ignition[1193]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:41.986218 ignition[1193]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:41.987410 ignition[1193]: PUT result: OK Oct 16 23:51:41.990335 ignition[1193]: disks: disks passed Oct 16 23:51:41.990413 ignition[1193]: Ignition finished successfully Oct 16 23:51:41.991892 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 16 23:51:41.992914 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 16 23:51:41.993674 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 16 23:51:41.994046 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 16 23:51:41.994592 systemd[1]: Reached target sysinit.target - System Initialization. Oct 16 23:51:41.995151 systemd[1]: Reached target basic.target - Basic System. Oct 16 23:51:42.000019 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 16 23:51:42.027006 systemd-fsck[1201]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 16 23:51:42.030945 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 16 23:51:42.035902 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 16 23:51:42.162797 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 22c9db06-419a-4610-8021-33f9316f26bd r/w with ordered data mode. Quota mode: none. Oct 16 23:51:42.163688 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 16 23:51:42.164823 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 16 23:51:42.177993 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 16 23:51:42.181175 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 16 23:51:42.183193 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 16 23:51:42.183981 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 16 23:51:42.184016 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 16 23:51:42.195421 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 16 23:51:42.201076 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 16 23:51:42.203799 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/nvme0n1p6 scanned by mount (1221) Oct 16 23:51:42.211061 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem b68cb5ed-e0a7-4edf-ad04-bb5772eb7ffc Oct 16 23:51:42.211146 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Oct 16 23:51:42.211169 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 16 23:51:42.223478 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 16 23:51:42.225360 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 16 23:51:42.298615 initrd-setup-root[1246]: cut: /sysroot/etc/passwd: No such file or directory Oct 16 23:51:42.305357 initrd-setup-root[1253]: cut: /sysroot/etc/group: No such file or directory Oct 16 23:51:42.311195 initrd-setup-root[1260]: cut: /sysroot/etc/shadow: No such file or directory Oct 16 23:51:42.315731 initrd-setup-root[1267]: cut: /sysroot/etc/gshadow: No such file or directory Oct 16 23:51:42.441574 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 16 23:51:42.446971 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 16 23:51:42.453977 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 16 23:51:42.466871 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem b68cb5ed-e0a7-4edf-ad04-bb5772eb7ffc Oct 16 23:51:42.502159 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 16 23:51:42.502962 ignition[1339]: INFO : Ignition 2.19.0 Oct 16 23:51:42.502962 ignition[1339]: INFO : Stage: mount Oct 16 23:51:42.504968 ignition[1339]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:42.504968 ignition[1339]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:42.504968 ignition[1339]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:42.506468 ignition[1339]: INFO : PUT result: OK Oct 16 23:51:42.508325 ignition[1339]: INFO : mount: mount passed Oct 16 23:51:42.508857 ignition[1339]: INFO : Ignition finished successfully Oct 16 23:51:42.510492 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 16 23:51:42.514976 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 16 23:51:42.614337 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 16 23:51:42.622092 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 16 23:51:42.643809 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/nvme0n1p6 scanned by mount (1350) Oct 16 23:51:42.643876 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem b68cb5ed-e0a7-4edf-ad04-bb5772eb7ffc Oct 16 23:51:42.647534 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Oct 16 23:51:42.647625 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 16 23:51:42.655802 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 16 23:51:42.658650 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 16 23:51:42.684008 ignition[1366]: INFO : Ignition 2.19.0 Oct 16 23:51:42.684008 ignition[1366]: INFO : Stage: files Oct 16 23:51:42.685611 ignition[1366]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:42.685611 ignition[1366]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:42.685611 ignition[1366]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:42.686959 ignition[1366]: INFO : PUT result: OK Oct 16 23:51:42.690264 ignition[1366]: DEBUG : files: compiled without relabeling support, skipping Oct 16 23:51:42.691540 ignition[1366]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 16 23:51:42.691540 ignition[1366]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 16 23:51:42.697293 ignition[1366]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 16 23:51:42.698238 ignition[1366]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 16 23:51:42.699138 ignition[1366]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 16 23:51:42.698505 unknown[1366]: wrote ssh authorized keys file for user: core Oct 16 23:51:42.702160 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-x86-64.raw" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.35.1-x86-64.raw" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-x86-64.raw" Oct 16 23:51:42.703143 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.35.1-x86-64.raw: attempt #1 Oct 16 23:51:43.211980 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK Oct 16 23:51:43.548064 systemd-networkd[1172]: eth0: Gained IPv6LL Oct 16 23:51:43.739768 ignition[1366]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.35.1-x86-64.raw" Oct 16 23:51:43.739768 ignition[1366]: INFO : files: op(8): [started] processing unit "containerd.service" Oct 16 23:51:43.742183 ignition[1366]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 16 23:51:43.744287 ignition[1366]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" Oct 16 23:51:43.744287 ignition[1366]: INFO : files: op(8): [finished] processing unit "containerd.service" Oct 16 23:51:43.744287 ignition[1366]: INFO : files: createResultFile: createFiles: op(a): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 16 23:51:43.744287 ignition[1366]: INFO : files: createResultFile: createFiles: op(a): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 16 23:51:43.744287 ignition[1366]: INFO : files: files passed Oct 16 23:51:43.744287 ignition[1366]: INFO : Ignition finished successfully Oct 16 23:51:43.743951 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 16 23:51:43.751026 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 16 23:51:43.753975 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 16 23:51:43.759369 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 16 23:51:43.760839 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 16 23:51:43.778149 initrd-setup-root-after-ignition[1396]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 16 23:51:43.778149 initrd-setup-root-after-ignition[1396]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 16 23:51:43.782193 initrd-setup-root-after-ignition[1400]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 16 23:51:43.783805 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 16 23:51:43.785139 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 16 23:51:43.791035 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 16 23:51:43.818836 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 16 23:51:43.819005 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 16 23:51:43.821537 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 16 23:51:43.822341 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 16 23:51:43.823412 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 16 23:51:43.830999 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 16 23:51:43.846547 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 16 23:51:43.853008 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 16 23:51:43.864695 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 16 23:51:43.866132 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 16 23:51:43.867340 systemd[1]: Stopped target timers.target - Timer Units. Oct 16 23:51:43.868310 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 16 23:51:43.868536 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 16 23:51:43.869886 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 16 23:51:43.870802 systemd[1]: Stopped target basic.target - Basic System. Oct 16 23:51:43.871620 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 16 23:51:43.872448 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 16 23:51:43.873332 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 16 23:51:43.874128 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 16 23:51:43.874906 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 16 23:51:43.875698 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 16 23:51:43.876885 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 16 23:51:43.877787 systemd[1]: Stopped target swap.target - Swaps. Oct 16 23:51:43.878541 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 16 23:51:43.878728 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 16 23:51:43.879849 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 16 23:51:43.880666 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 16 23:51:43.881471 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 16 23:51:43.882235 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 16 23:51:43.882709 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 16 23:51:43.882906 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 16 23:51:43.884378 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 16 23:51:43.884565 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 16 23:51:43.885369 systemd[1]: ignition-files.service: Deactivated successfully. Oct 16 23:51:43.885528 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 16 23:51:43.897167 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 16 23:51:43.898194 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 16 23:51:43.898478 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 16 23:51:43.902103 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 16 23:51:43.903591 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 16 23:51:43.903810 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 16 23:51:43.906351 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 16 23:51:43.906536 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 16 23:51:43.919963 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 16 23:51:43.920918 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 16 23:51:43.928007 ignition[1420]: INFO : Ignition 2.19.0 Oct 16 23:51:43.928007 ignition[1420]: INFO : Stage: umount Oct 16 23:51:43.929938 ignition[1420]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 16 23:51:43.929938 ignition[1420]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 16 23:51:43.929938 ignition[1420]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 16 23:51:43.933911 ignition[1420]: INFO : PUT result: OK Oct 16 23:51:43.938376 ignition[1420]: INFO : umount: umount passed Oct 16 23:51:43.941291 ignition[1420]: INFO : Ignition finished successfully Oct 16 23:51:43.940493 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 16 23:51:43.940616 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 16 23:51:43.943321 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 16 23:51:43.944350 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 16 23:51:43.944460 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 16 23:51:43.945007 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 16 23:51:43.945180 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 16 23:51:43.945980 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 16 23:51:43.946026 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 16 23:51:43.946414 systemd[1]: Stopped target network.target - Network. Oct 16 23:51:43.948874 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 16 23:51:43.948976 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 16 23:51:43.949572 systemd[1]: Stopped target paths.target - Path Units. Oct 16 23:51:43.950515 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 16 23:51:43.950595 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 16 23:51:43.951007 systemd[1]: Stopped target slices.target - Slice Units. Oct 16 23:51:43.951616 systemd[1]: Stopped target sockets.target - Socket Units. Oct 16 23:51:43.952272 systemd[1]: iscsid.socket: Deactivated successfully. Oct 16 23:51:43.952332 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 16 23:51:43.952995 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 16 23:51:43.953168 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 16 23:51:43.953682 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 16 23:51:43.953750 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 16 23:51:43.954326 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 16 23:51:43.954385 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 16 23:51:43.955615 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 16 23:51:43.956187 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 16 23:51:43.957461 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 16 23:51:43.957584 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 16 23:51:43.959119 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 16 23:51:43.959234 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 16 23:51:43.960882 systemd-networkd[1172]: eth0: DHCPv6 lease lost Oct 16 23:51:43.964934 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 16 23:51:43.965339 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 16 23:51:43.966514 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 16 23:51:43.966643 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 16 23:51:43.969808 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 16 23:51:43.969899 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 16 23:51:43.974912 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 16 23:51:43.975510 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 16 23:51:43.975592 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 16 23:51:43.976238 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 16 23:51:43.976297 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 16 23:51:43.976974 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 16 23:51:43.977093 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 16 23:51:43.977873 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 16 23:51:43.977929 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 16 23:51:43.978619 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 16 23:51:43.997558 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 16 23:51:43.997814 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 16 23:51:44.001561 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 16 23:51:44.001614 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 16 23:51:44.002160 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 16 23:51:44.002222 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 16 23:51:44.002687 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 16 23:51:44.002794 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 16 23:51:44.003996 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 16 23:51:44.004066 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 16 23:51:44.005448 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 16 23:51:44.005514 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 16 23:51:44.011066 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 16 23:51:44.012301 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 16 23:51:44.012995 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 16 23:51:44.014514 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 16 23:51:44.014584 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:44.015554 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 16 23:51:44.015682 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 16 23:51:44.024169 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 16 23:51:44.024280 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 16 23:51:44.025183 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 16 23:51:44.031797 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 16 23:51:44.045094 systemd[1]: Switching root. Oct 16 23:51:44.070878 systemd-journald[179]: Journal stopped Oct 16 23:51:45.566837 systemd-journald[179]: Received SIGTERM from PID 1 (systemd). Oct 16 23:51:45.566950 kernel: SELinux: policy capability network_peer_controls=1 Oct 16 23:51:45.566984 kernel: SELinux: policy capability open_perms=1 Oct 16 23:51:45.567005 kernel: SELinux: policy capability extended_socket_class=1 Oct 16 23:51:45.567026 kernel: SELinux: policy capability always_check_network=0 Oct 16 23:51:45.567046 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 16 23:51:45.567077 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 16 23:51:45.567098 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 16 23:51:45.567118 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 16 23:51:45.567139 kernel: audit: type=1403 audit(1792194704.422:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 16 23:51:45.567160 systemd[1]: Successfully loaded SELinux policy in 52.545ms. Oct 16 23:51:45.567195 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 15.105ms. Oct 16 23:51:45.567218 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) Oct 16 23:51:45.567240 systemd[1]: Detected virtualization amazon. Oct 16 23:51:45.567262 systemd[1]: Detected architecture x86-64. Oct 16 23:51:45.567286 systemd[1]: Detected first boot. Oct 16 23:51:45.567308 systemd[1]: Initializing machine ID from VM UUID. Oct 16 23:51:45.567330 zram_generator::config[1481]: No configuration found. Oct 16 23:51:45.567352 systemd[1]: Populated /etc with preset unit settings. Oct 16 23:51:45.567374 systemd[1]: Queued start job for default target multi-user.target. Oct 16 23:51:45.567395 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Oct 16 23:51:45.567419 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 16 23:51:45.567440 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 16 23:51:45.567466 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 16 23:51:45.567487 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 16 23:51:45.567509 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 16 23:51:45.567531 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 16 23:51:45.567552 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 16 23:51:45.567573 systemd[1]: Created slice user.slice - User and Session Slice. Oct 16 23:51:45.567595 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 16 23:51:45.567617 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 16 23:51:45.567638 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 16 23:51:45.567664 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 16 23:51:45.567686 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 16 23:51:45.567708 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 16 23:51:45.567730 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 16 23:51:45.567751 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 16 23:51:45.569879 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 16 23:51:45.569941 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 16 23:51:45.569964 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 16 23:51:45.569986 systemd[1]: Reached target slices.target - Slice Units. Oct 16 23:51:45.570013 systemd[1]: Reached target swap.target - Swaps. Oct 16 23:51:45.570034 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 16 23:51:45.570055 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 16 23:51:45.570075 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 16 23:51:45.570096 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 16 23:51:45.570116 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 16 23:51:45.570137 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 16 23:51:45.570158 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 16 23:51:45.570182 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 16 23:51:45.570201 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 16 23:51:45.570219 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 16 23:51:45.570235 systemd[1]: Mounting media.mount - External Media Directory... Oct 16 23:51:45.570254 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:45.570273 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 16 23:51:45.570291 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 16 23:51:45.570310 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 16 23:51:45.570333 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 16 23:51:45.570353 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 16 23:51:45.570372 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 16 23:51:45.570390 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 16 23:51:45.570409 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 23:51:45.570428 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 16 23:51:45.570446 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 23:51:45.570465 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 16 23:51:45.570484 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 23:51:45.570506 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 16 23:51:45.570526 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. Oct 16 23:51:45.570545 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) Oct 16 23:51:45.570564 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 16 23:51:45.570583 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 16 23:51:45.570601 kernel: fuse: init (API version 7.39) Oct 16 23:51:45.570624 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 16 23:51:45.570643 kernel: loop: module loaded Oct 16 23:51:45.570661 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 16 23:51:45.570683 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 16 23:51:45.570702 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:45.570720 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 16 23:51:45.570740 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 16 23:51:45.570758 systemd[1]: Mounted media.mount - External Media Directory. Oct 16 23:51:45.570791 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 16 23:51:45.570810 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 16 23:51:45.570873 systemd-journald[1583]: Collecting audit messages is disabled. Oct 16 23:51:45.570913 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 16 23:51:45.570932 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 16 23:51:45.570950 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 16 23:51:45.570969 systemd-journald[1583]: Journal started Oct 16 23:51:45.571004 systemd-journald[1583]: Runtime Journal (/run/log/journal/ec242ac44817dbf697d4c28d65c9f8fa) is 4.7M, max 38.1M, 33.4M free. Oct 16 23:51:45.576548 systemd[1]: Started systemd-journald.service - Journal Service. Oct 16 23:51:45.579985 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 16 23:51:45.580279 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 16 23:51:45.583118 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 23:51:45.583407 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 23:51:45.586066 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 23:51:45.586347 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 23:51:45.588217 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 16 23:51:45.588627 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 16 23:51:45.591194 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 23:51:45.591477 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 23:51:45.594124 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 16 23:51:45.596508 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 16 23:51:45.598412 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 16 23:51:45.607837 kernel: ACPI: bus type drm_connector registered Oct 16 23:51:45.609625 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 16 23:51:45.609945 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 16 23:51:45.630221 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 16 23:51:45.636968 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 16 23:51:45.646930 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 16 23:51:45.649512 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 16 23:51:45.665983 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 16 23:51:45.675127 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 16 23:51:45.676225 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 16 23:51:45.680001 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 16 23:51:45.680921 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 16 23:51:45.691684 systemd-journald[1583]: Time spent on flushing to /var/log/journal/ec242ac44817dbf697d4c28d65c9f8fa is 99.221ms for 951 entries. Oct 16 23:51:45.691684 systemd-journald[1583]: System Journal (/var/log/journal/ec242ac44817dbf697d4c28d65c9f8fa) is 8.0M, max 195.6M, 187.6M free. Oct 16 23:51:45.808899 systemd-journald[1583]: Received client request to flush runtime journal. Oct 16 23:51:45.694066 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 16 23:51:45.697259 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 16 23:51:45.712596 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 16 23:51:45.716128 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 16 23:51:45.716952 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 16 23:51:45.726387 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 16 23:51:45.732305 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 16 23:51:45.744009 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 16 23:51:45.789578 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 16 23:51:45.805331 udevadm[1637]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 16 23:51:45.812714 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 16 23:51:45.816892 systemd-tmpfiles[1632]: ACLs are not supported, ignoring. Oct 16 23:51:45.816918 systemd-tmpfiles[1632]: ACLs are not supported, ignoring. Oct 16 23:51:45.826346 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 16 23:51:45.834107 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 16 23:51:45.884468 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 16 23:51:45.891129 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 16 23:51:45.917178 systemd-tmpfiles[1653]: ACLs are not supported, ignoring. Oct 16 23:51:45.917648 systemd-tmpfiles[1653]: ACLs are not supported, ignoring. Oct 16 23:51:45.924665 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 16 23:51:46.403314 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 16 23:51:46.413083 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 16 23:51:46.438583 systemd-udevd[1659]: Using default interface naming scheme 'v255'. Oct 16 23:51:46.473442 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 16 23:51:46.483255 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 16 23:51:46.519198 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 16 23:51:46.561613 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. Oct 16 23:51:46.580608 (udev-worker)[1674]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:51:46.617243 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 16 23:51:46.715837 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr Oct 16 23:51:46.719930 systemd-networkd[1664]: lo: Link UP Oct 16 23:51:46.719940 systemd-networkd[1664]: lo: Gained carrier Oct 16 23:51:46.723092 systemd-networkd[1664]: Enumeration completed Oct 16 23:51:46.723277 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 16 23:51:46.725226 systemd-networkd[1664]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 16 23:51:46.725242 systemd-networkd[1664]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 16 23:51:46.730975 systemd-networkd[1664]: eth0: Link UP Oct 16 23:51:46.731242 systemd-networkd[1664]: eth0: Gained carrier Oct 16 23:51:46.731272 systemd-networkd[1664]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 16 23:51:46.732028 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 16 23:51:46.747919 systemd-networkd[1664]: eth0: DHCPv4 address 172.31.28.79/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 16 23:51:46.754793 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 16 23:51:46.759188 systemd-networkd[1664]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 16 23:51:46.763532 kernel: ACPI: button: Power Button [PWRF] Oct 16 23:51:46.769591 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input4 Oct 16 23:51:46.769784 kernel: ACPI: button: Sleep Button [SLPF] Oct 16 23:51:46.769822 kernel: input: ImPS/2 Generic Wheel Mouse as /devices/platform/i8042/serio1/input/input3 Oct 16 23:51:46.786856 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 29 scanned by (udev-worker) (1670) Oct 16 23:51:46.862182 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:46.898800 kernel: mousedev: PS/2 mouse device common for all mice Oct 16 23:51:46.900642 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 16 23:51:46.902083 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:46.916227 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 16 23:51:46.984398 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 16 23:51:46.994480 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 16 23:51:47.002239 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 16 23:51:47.024386 lvm[1783]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 16 23:51:47.052383 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 16 23:51:47.056121 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 16 23:51:47.065136 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 16 23:51:47.066838 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 16 23:51:47.080246 lvm[1788]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 16 23:51:47.109494 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 16 23:51:47.111405 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 16 23:51:47.112343 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). Oct 16 23:51:47.112530 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 16 23:51:47.113327 systemd[1]: Reached target machines.target - Containers. Oct 16 23:51:47.115814 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 16 23:51:47.125087 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 16 23:51:47.130027 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 16 23:51:47.132113 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 16 23:51:47.140995 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 16 23:51:47.144760 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 16 23:51:47.156067 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 16 23:51:47.160276 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 16 23:51:47.176655 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 16 23:51:47.199122 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 16 23:51:47.204670 kernel: loop0: detected capacity change from 0 to 217752 Oct 16 23:51:47.200668 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 16 23:51:47.307977 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 16 23:51:47.334808 kernel: loop1: detected capacity change from 0 to 140768 Oct 16 23:51:47.398957 kernel: loop2: detected capacity change from 0 to 142488 Oct 16 23:51:47.473800 kernel: loop3: detected capacity change from 0 to 61336 Oct 16 23:51:47.539803 kernel: loop4: detected capacity change from 0 to 217752 Oct 16 23:51:47.579804 kernel: loop5: detected capacity change from 0 to 140768 Oct 16 23:51:47.616813 kernel: loop6: detected capacity change from 0 to 142488 Oct 16 23:51:47.657854 kernel: loop7: detected capacity change from 0 to 61336 Oct 16 23:51:47.676661 (sd-merge)[1815]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Oct 16 23:51:47.677522 (sd-merge)[1815]: Merged extensions into '/usr'. Oct 16 23:51:47.699190 systemd[1]: Reloading requested from client PID 1798 ('systemd-sysext') (unit systemd-sysext.service)... Oct 16 23:51:47.699215 systemd[1]: Reloading... Oct 16 23:51:47.804805 zram_generator::config[1843]: No configuration found. Oct 16 23:51:47.930190 ldconfig[1794]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 16 23:51:47.977759 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. Oct 16 23:51:48.057672 systemd[1]: Reloading finished in 357 ms. Oct 16 23:51:48.071102 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 16 23:51:48.072499 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 16 23:51:48.082125 systemd[1]: Starting ensure-sysext.service... Oct 16 23:51:48.086970 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 16 23:51:48.091909 systemd-networkd[1664]: eth0: Gained IPv6LL Oct 16 23:51:48.095389 systemd[1]: Reloading requested from client PID 1902 ('systemctl') (unit ensure-sysext.service)... Oct 16 23:51:48.095412 systemd[1]: Reloading... Oct 16 23:51:48.130297 systemd-tmpfiles[1903]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 16 23:51:48.130892 systemd-tmpfiles[1903]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 16 23:51:48.134859 systemd-tmpfiles[1903]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 16 23:51:48.136805 systemd-tmpfiles[1903]: ACLs are not supported, ignoring. Oct 16 23:51:48.137120 systemd-tmpfiles[1903]: ACLs are not supported, ignoring. Oct 16 23:51:48.143943 systemd-tmpfiles[1903]: Detected autofs mount point /boot during canonicalization of boot. Oct 16 23:51:48.144640 systemd-tmpfiles[1903]: Skipping /boot Oct 16 23:51:48.168468 systemd-tmpfiles[1903]: Detected autofs mount point /boot during canonicalization of boot. Oct 16 23:51:48.168637 systemd-tmpfiles[1903]: Skipping /boot Oct 16 23:51:48.214800 zram_generator::config[1935]: No configuration found. Oct 16 23:51:48.351648 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. Oct 16 23:51:48.433287 systemd[1]: Reloading finished in 337 ms. Oct 16 23:51:48.449284 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 16 23:51:48.462629 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 16 23:51:48.475043 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 16 23:51:48.486028 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 16 23:51:48.495979 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 16 23:51:48.507470 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 16 23:51:48.523078 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 16 23:51:48.542905 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 16 23:51:48.547649 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:48.548017 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 16 23:51:48.552073 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 23:51:48.562131 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 23:51:48.565838 augenrules[2015]: No rules Oct 16 23:51:48.576029 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 23:51:48.580582 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 16 23:51:48.580826 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:48.590532 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 16 23:51:48.595178 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 16 23:51:48.597574 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 23:51:48.598074 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 23:51:48.601360 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 23:51:48.601609 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 23:51:48.605082 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 23:51:48.605898 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 23:51:48.624882 systemd[1]: Finished ensure-sysext.service. Oct 16 23:51:48.629153 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:48.629486 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 16 23:51:48.635159 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 16 23:51:48.645060 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 16 23:51:48.649875 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 16 23:51:48.666651 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 16 23:51:48.667501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 16 23:51:48.667594 systemd[1]: Reached target time-set.target - System Time Set. Oct 16 23:51:48.682227 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 16 23:51:48.683879 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 16 23:51:48.684737 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 16 23:51:48.686426 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 16 23:51:48.689123 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 16 23:51:48.689355 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 16 23:51:48.693324 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 16 23:51:48.693599 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 16 23:51:48.698901 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 16 23:51:48.699397 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 16 23:51:48.706364 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 16 23:51:48.706515 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 16 23:51:48.721551 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 16 23:51:48.738912 systemd-resolved[2006]: Positive Trust Anchors: Oct 16 23:51:48.738932 systemd-resolved[2006]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 16 23:51:48.738995 systemd-resolved[2006]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 16 23:51:48.746584 systemd-resolved[2006]: Defaulting to hostname 'linux'. Oct 16 23:51:48.746884 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 16 23:51:48.750517 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 16 23:51:48.752251 systemd[1]: Reached target network.target - Network. Oct 16 23:51:48.753008 systemd[1]: Reached target network-online.target - Network is Online. Oct 16 23:51:48.753533 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 16 23:51:48.753976 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). Oct 16 23:51:48.754019 systemd[1]: Reached target sysinit.target - System Initialization. Oct 16 23:51:48.754508 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 16 23:51:48.754931 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 16 23:51:48.755471 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 16 23:51:48.755952 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 16 23:51:48.756307 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 16 23:51:48.756703 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 16 23:51:48.756745 systemd[1]: Reached target paths.target - Path Units. Oct 16 23:51:48.757145 systemd[1]: Reached target timers.target - Timer Units. Oct 16 23:51:48.758087 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 16 23:51:48.760164 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 16 23:51:48.762518 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 16 23:51:48.764881 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 16 23:51:48.765694 systemd[1]: Reached target sockets.target - Socket Units. Oct 16 23:51:48.766229 systemd[1]: Reached target basic.target - Basic System. Oct 16 23:51:48.767156 systemd[1]: System is tainted: cgroupsv1 Oct 16 23:51:48.767315 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 16 23:51:48.767448 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 16 23:51:48.769897 systemd[1]: Starting containerd.service - containerd container runtime... Oct 16 23:51:48.774988 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 16 23:51:48.780001 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 16 23:51:48.784494 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 16 23:51:48.794968 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 16 23:51:48.795997 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). Oct 16 23:51:48.801601 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 16 23:51:48.804798 jq[2062]: false Oct 16 23:51:48.835230 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 16 23:51:48.843972 systemd[1]: Started ntpd.service - Network Time Service. Oct 16 23:51:48.858995 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 16 23:51:48.866435 systemd[1]: Starting setup-oem.service - Setup OEM... Oct 16 23:51:48.878728 dbus-daemon[2060]: [system] SELinux support is enabled Oct 16 23:51:48.880385 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 16 23:51:48.889080 dbus-daemon[2060]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1664 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Oct 16 23:51:48.891894 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 16 23:51:48.913036 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 16 23:51:48.915119 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 16 23:51:48.922551 systemd[1]: Starting update-engine.service - Update Engine... Oct 16 23:51:48.939577 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 16 23:51:48.944636 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 16 23:51:48.960857 extend-filesystems[2063]: Found loop4 Oct 16 23:51:48.965262 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 16 23:51:48.965611 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 16 23:51:48.982501 extend-filesystems[2063]: Found loop5 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found loop6 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found loop7 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p1 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p2 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p3 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found usr Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p4 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p6 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p7 Oct 16 23:51:48.982501 extend-filesystems[2063]: Found nvme0n1p9 Oct 16 23:51:48.982501 extend-filesystems[2063]: Checking size of /dev/nvme0n1p9 Oct 16 23:51:49.039618 update_engine[2086]: I20261016 23:51:49.011162 2086 main.cc:92] Flatcar Update Engine starting Oct 16 23:51:49.039618 update_engine[2086]: I20261016 23:51:49.035597 2086 update_check_scheduler.cc:74] Next update check in 10m3s Oct 16 23:51:48.977747 systemd[1]: motdgen.service: Deactivated successfully. Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:48.997 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.008 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.013 INFO Fetch successful Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.013 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.021 INFO Fetch successful Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.021 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.035 INFO Fetch successful Oct 16 23:51:49.040157 coreos-metadata[2059]: Oct 16 23:51:49.035 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Oct 16 23:51:49.040710 jq[2087]: true Oct 16 23:51:48.979108 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 16 23:51:48.989466 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 16 23:51:48.994009 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 16 23:51:49.051321 (ntainerd)[2105]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 16 23:51:49.066040 coreos-metadata[2059]: Oct 16 23:51:49.066 INFO Fetch successful Oct 16 23:51:49.066040 coreos-metadata[2059]: Oct 16 23:51:49.066 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Oct 16 23:51:49.071166 coreos-metadata[2059]: Oct 16 23:51:49.071 INFO Fetch failed with 404: resource not found Oct 16 23:51:49.071166 coreos-metadata[2059]: Oct 16 23:51:49.071 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Oct 16 23:51:49.077636 coreos-metadata[2059]: Oct 16 23:51:49.077 INFO Fetch successful Oct 16 23:51:49.077636 coreos-metadata[2059]: Oct 16 23:51:49.077 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Oct 16 23:51:49.084516 coreos-metadata[2059]: Oct 16 23:51:49.081 INFO Fetch successful Oct 16 23:51:49.084516 coreos-metadata[2059]: Oct 16 23:51:49.081 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Oct 16 23:51:49.084667 coreos-metadata[2059]: Oct 16 23:51:49.084 INFO Fetch successful Oct 16 23:51:49.084667 coreos-metadata[2059]: Oct 16 23:51:49.084 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Oct 16 23:51:49.087969 dbus-daemon[2060]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 16 23:51:49.089947 coreos-metadata[2059]: Oct 16 23:51:49.089 INFO Fetch successful Oct 16 23:51:49.089947 coreos-metadata[2059]: Oct 16 23:51:49.089 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Oct 16 23:51:49.091847 coreos-metadata[2059]: Oct 16 23:51:49.091 INFO Fetch successful Oct 16 23:51:49.094703 systemd[1]: Started update-engine.service - Update Engine. Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: ntpd 4.2.8p17@1.4004-o Fri Oct 16 21:47:31 UTC 2026 (1): Starting Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: ---------------------------------------------------- Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: ntp-4 is maintained by Network Time Foundation, Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: corporation. Support and training for ntp-4 are Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: available at https://www.nwtime.org/support Oct 16 23:51:49.096312 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: ---------------------------------------------------- Oct 16 23:51:49.094890 ntpd[2069]: ntpd 4.2.8p17@1.4004-o Fri Oct 16 21:47:31 UTC 2026 (1): Starting Oct 16 23:51:49.094918 ntpd[2069]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 16 23:51:49.094930 ntpd[2069]: ---------------------------------------------------- Oct 16 23:51:49.094939 ntpd[2069]: ntp-4 is maintained by Network Time Foundation, Oct 16 23:51:49.094949 ntpd[2069]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 16 23:51:49.094959 ntpd[2069]: corporation. Support and training for ntp-4 are Oct 16 23:51:49.094969 ntpd[2069]: available at https://www.nwtime.org/support Oct 16 23:51:49.094978 ntpd[2069]: ---------------------------------------------------- Oct 16 23:51:49.103400 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 16 23:51:49.104639 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: proto: precision = 0.072 usec (-24) Oct 16 23:51:49.104246 ntpd[2069]: proto: precision = 0.072 usec (-24) Oct 16 23:51:49.105225 ntpd[2069]: basedate set to 2026-10-04 Oct 16 23:51:49.107085 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: basedate set to 2026-10-04 Oct 16 23:51:49.107085 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: gps base set to 2026-10-04 (week 2439) Oct 16 23:51:49.105246 ntpd[2069]: gps base set to 2026-10-04 (week 2439) Oct 16 23:51:49.110228 ntpd[2069]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 23:51:49.110368 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 23:51:49.110459 ntpd[2069]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 23:51:49.110521 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 23:51:49.110800 ntpd[2069]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 23:51:49.110893 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 23:51:49.110971 ntpd[2069]: Listen normally on 3 eth0 172.31.28.79:123 Oct 16 23:51:49.111027 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen normally on 3 eth0 172.31.28.79:123 Oct 16 23:51:49.111112 ntpd[2069]: Listen normally on 4 lo [::1]:123 Oct 16 23:51:49.114050 ntpd[2069]: Listen normally on 5 eth0 [fe80::4ff:ecff:fec7:b761%2]:123 Oct 16 23:51:49.113569 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). Oct 16 23:51:49.117229 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen normally on 4 lo [::1]:123 Oct 16 23:51:49.117229 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listen normally on 5 eth0 [fe80::4ff:ecff:fec7:b761%2]:123 Oct 16 23:51:49.117229 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: Listening on routing socket on fd #22 for interface updates Oct 16 23:51:49.114108 ntpd[2069]: Listening on routing socket on fd #22 for interface updates Oct 16 23:51:49.113640 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 16 23:51:49.118524 ntpd[2069]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 23:51:49.120060 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 23:51:49.120167 ntpd[2069]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 23:51:49.120240 ntpd[2069]: 16 Oct 23:51:49 ntpd[2069]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 23:51:49.127003 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 16 23:51:49.130339 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). Oct 16 23:51:49.130391 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 16 23:51:49.137647 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 16 23:51:49.153097 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 16 23:51:49.156480 jq[2109]: true Oct 16 23:51:49.159937 extend-filesystems[2063]: Resized partition /dev/nvme0n1p9 Oct 16 23:51:49.170000 extend-filesystems[2129]: resize2fs 1.47.1 (20-May-2024) Oct 16 23:51:49.196538 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 3587067 blocks Oct 16 23:51:49.225332 systemd[1]: Finished setup-oem.service - Setup OEM. Oct 16 23:51:49.231204 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Oct 16 23:51:49.316869 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 16 23:51:49.318133 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 16 23:51:49.393426 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 29 scanned by (udev-worker) (1662) Oct 16 23:51:49.382026 dbus-daemon[2060]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 16 23:51:49.393724 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 16 23:51:49.395074 dbus-daemon[2060]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.4' (uid=0 pid=2122 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Oct 16 23:51:49.409409 bash[2162]: Updated "/home/core/.ssh/authorized_keys" Oct 16 23:51:49.404114 systemd[1]: Starting polkit.service - Authorization Manager... Oct 16 23:51:49.406922 systemd-logind[2080]: Watching system buttons on /dev/input/event1 (Power Button) Oct 16 23:51:49.406949 systemd-logind[2080]: Watching system buttons on /dev/input/event2 (Sleep Button) Oct 16 23:51:49.406974 systemd-logind[2080]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 16 23:51:49.413412 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 3587067 Oct 16 23:51:49.412382 systemd-logind[2080]: New seat seat0. Oct 16 23:51:49.418936 systemd[1]: Started systemd-logind.service - User Login Management. Oct 16 23:51:49.419991 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 16 23:51:49.437342 systemd[1]: Starting sshkeys.service... Oct 16 23:51:49.441974 extend-filesystems[2129]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Oct 16 23:51:49.441974 extend-filesystems[2129]: old_desc_blocks = 1, new_desc_blocks = 2 Oct 16 23:51:49.441974 extend-filesystems[2129]: The filesystem on /dev/nvme0n1p9 is now 3587067 (4k) blocks long. Oct 16 23:51:49.456243 extend-filesystems[2063]: Resized filesystem in /dev/nvme0n1p9 Oct 16 23:51:49.446125 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 16 23:51:49.446471 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 16 23:51:49.481020 polkitd[2164]: Started polkitd version 121 Oct 16 23:51:49.515500 polkitd[2164]: Loading rules from directory /etc/polkit-1/rules.d Oct 16 23:51:49.515596 polkitd[2164]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 16 23:51:49.520609 amazon-ssm-agent[2148]: Initializing new seelog logger Oct 16 23:51:49.521294 amazon-ssm-agent[2148]: New Seelog Logger Creation Complete Oct 16 23:51:49.521455 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.521509 amazon-ssm-agent[2148]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.522097 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 processing appconfig overrides Oct 16 23:51:49.523914 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.523999 amazon-ssm-agent[2148]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.524181 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 processing appconfig overrides Oct 16 23:51:49.524570 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.524638 amazon-ssm-agent[2148]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.524819 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 processing appconfig overrides Oct 16 23:51:49.525562 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO Proxy environment variables: Oct 16 23:51:49.530575 polkitd[2164]: Finished loading, compiling and executing 2 rules Oct 16 23:51:49.531086 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 16 23:51:49.552786 dbus-daemon[2060]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 16 23:51:49.557239 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 16 23:51:49.559336 systemd[1]: Started polkit.service - Authorization Manager. Oct 16 23:51:49.564803 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.564803 amazon-ssm-agent[2148]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 16 23:51:49.564803 amazon-ssm-agent[2148]: 2026/10/16 23:51:49 processing appconfig overrides Oct 16 23:51:49.572173 polkitd[2164]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 16 23:51:49.643876 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO no_proxy: Oct 16 23:51:49.657618 systemd-hostnamed[2122]: Hostname set to (transient) Oct 16 23:51:49.657890 systemd-resolved[2006]: System hostname changed to 'ip-172-31-28-79'. Oct 16 23:51:49.744127 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO https_proxy: Oct 16 23:51:49.745210 coreos-metadata[2200]: Oct 16 23:51:49.744 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 16 23:51:49.747110 coreos-metadata[2200]: Oct 16 23:51:49.746 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Oct 16 23:51:49.747967 coreos-metadata[2200]: Oct 16 23:51:49.747 INFO Fetch successful Oct 16 23:51:49.747967 coreos-metadata[2200]: Oct 16 23:51:49.747 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 16 23:51:49.749542 coreos-metadata[2200]: Oct 16 23:51:49.748 INFO Fetch successful Oct 16 23:51:49.751959 unknown[2200]: wrote ssh authorized keys file for user: core Oct 16 23:51:49.813455 update-ssh-keys[2255]: Updated "/home/core/.ssh/authorized_keys" Oct 16 23:51:49.820362 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 16 23:51:49.840670 systemd[1]: Finished sshkeys.service. Oct 16 23:51:49.851595 locksmithd[2125]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 16 23:51:49.853542 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO http_proxy: Oct 16 23:51:49.958011 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO Checking if agent identity type OnPrem can be assumed Oct 16 23:51:50.056261 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO Checking if agent identity type EC2 can be assumed Oct 16 23:51:50.064230 containerd[2105]: time="2026-10-16T23:51:50.064121387Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Oct 16 23:51:50.155790 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO Agent will take identity from EC2 Oct 16 23:51:50.190998 containerd[2105]: time="2026-10-16T23:51:50.190913185Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.195245 containerd[2105]: time="2026-10-16T23:51:50.195189714Z" 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.157-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 16 23:51:50.195388 containerd[2105]: time="2026-10-16T23:51:50.195367537Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 16 23:51:50.195459 containerd[2105]: time="2026-10-16T23:51:50.195446853Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 16 23:51:50.195695 containerd[2105]: time="2026-10-16T23:51:50.195679739Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 16 23:51:50.195768 containerd[2105]: time="2026-10-16T23:51:50.195755794Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.195944 containerd[2105]: time="2026-10-16T23:51:50.195925284Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 16 23:51:50.196008 containerd[2105]: time="2026-10-16T23:51:50.195996308Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.197056 containerd[2105]: time="2026-10-16T23:51:50.197025716Z" 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 Oct 16 23:51:50.197169 containerd[2105]: time="2026-10-16T23:51:50.197154610Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.197242 containerd[2105]: time="2026-10-16T23:51:50.197225874Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 16 23:51:50.197302 containerd[2105]: time="2026-10-16T23:51:50.197290656Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.197498 containerd[2105]: time="2026-10-16T23:51:50.197480470Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.197856 containerd[2105]: time="2026-10-16T23:51:50.197837608Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 16 23:51:50.199053 containerd[2105]: time="2026-10-16T23:51:50.199026929Z" 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 Oct 16 23:51:50.199461 containerd[2105]: time="2026-10-16T23:51:50.199132775Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 16 23:51:50.199461 containerd[2105]: time="2026-10-16T23:51:50.199235247Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 16 23:51:50.199461 containerd[2105]: time="2026-10-16T23:51:50.199289515Z" level=info msg="metadata content store policy set" policy=shared Oct 16 23:51:50.204802 containerd[2105]: time="2026-10-16T23:51:50.204754459Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 16 23:51:50.204957 containerd[2105]: time="2026-10-16T23:51:50.204941104Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 16 23:51:50.205238 containerd[2105]: time="2026-10-16T23:51:50.205219277Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 16 23:51:50.205328 containerd[2105]: time="2026-10-16T23:51:50.205314427Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 16 23:51:50.205416 containerd[2105]: time="2026-10-16T23:51:50.205402845Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 16 23:51:50.206360 containerd[2105]: time="2026-10-16T23:51:50.206258375Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.209850511Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210044086Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210069306Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210090370Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210110912Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210137150Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210167679Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210189358Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210211498Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210231290Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210249798Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210267991Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210302694Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.210798 containerd[2105]: time="2026-10-16T23:51:50.210323293Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210342495Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210362819Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210381303Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210415072Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210433201Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210452204Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210471704Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210493524Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210519345Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210543613Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210562430Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210592629Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210621819Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210639921Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.211350 containerd[2105]: time="2026-10-16T23:51:50.210659251Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 16 23:51:50.211878 containerd[2105]: time="2026-10-16T23:51:50.210723901Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 16 23:51:50.211878 containerd[2105]: time="2026-10-16T23:51:50.210748514Z" 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 Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.210767010Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.212820667Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.212842151Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.212863188Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.212878039Z" level=info msg="NRI interface is disabled by configuration." Oct 16 23:51:50.214989 containerd[2105]: time="2026-10-16T23:51:50.212892575Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.213568765Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:false] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:false SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.213669699Z" level=info msg="Connect containerd service" Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.213726416Z" level=info msg="using legacy CRI server" Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.213744326Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.214128797Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 16 23:51:50.215277 containerd[2105]: time="2026-10-16T23:51:50.215176629Z" 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" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216311143Z" level=info msg="Start subscribing containerd event" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216375262Z" level=info msg="Start recovering state" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216457519Z" level=info msg="Start event monitor" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216480594Z" level=info msg="Start snapshots syncer" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216498439Z" level=info msg="Start cni network conf syncer for default" Oct 16 23:51:50.217792 containerd[2105]: time="2026-10-16T23:51:50.216510595Z" level=info msg="Start streaming server" Oct 16 23:51:50.218659 containerd[2105]: time="2026-10-16T23:51:50.218631435Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 16 23:51:50.222583 containerd[2105]: time="2026-10-16T23:51:50.219862465Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 16 23:51:50.222583 containerd[2105]: time="2026-10-16T23:51:50.219944623Z" level=info msg="containerd successfully booted in 0.159781s" Oct 16 23:51:50.221537 systemd[1]: Started containerd.service - containerd container runtime. Oct 16 23:51:50.253790 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 16 23:51:50.351957 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 16 23:51:50.374479 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 16 23:51:50.374479 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Oct 16 23:51:50.374479 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] OS: linux, Arch: amd64 Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] Starting Core Agent Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [amazon-ssm-agent] registrar detected. Attempting registration Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [Registrar] Starting registrar module Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:49 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:50 INFO [EC2Identity] EC2 registration was successful. Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:50 INFO [CredentialRefresher] credentialRefresher has started Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:50 INFO [CredentialRefresher] Starting credentials refresher loop Oct 16 23:51:50.374689 amazon-ssm-agent[2148]: 2026-10-16 23:51:50 INFO EC2RoleProvider Successfully connected with instance profile role credentials Oct 16 23:51:50.450864 amazon-ssm-agent[2148]: 2026-10-16 23:51:50 INFO [CredentialRefresher] Next credential rotation will be in 31.924993833966667 minutes Oct 16 23:51:50.464236 sshd_keygen[2098]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 16 23:51:50.492100 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 16 23:51:50.504209 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 16 23:51:50.515086 systemd[1]: issuegen.service: Deactivated successfully. Oct 16 23:51:50.515445 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 16 23:51:50.524150 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 16 23:51:50.539178 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 16 23:51:50.547199 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 16 23:51:50.555224 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 16 23:51:50.557261 systemd[1]: Reached target getty.target - Login Prompts. Oct 16 23:51:51.390049 amazon-ssm-agent[2148]: 2026-10-16 23:51:51 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Oct 16 23:51:51.492970 amazon-ssm-agent[2148]: 2026-10-16 23:51:51 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2320) started Oct 16 23:51:51.589054 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 16 23:51:51.590546 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 16 23:51:51.593579 systemd[1]: Startup finished in 6.322s (kernel) + 7.221s (userspace) = 13.543s. Oct 16 23:51:51.594332 amazon-ssm-agent[2148]: 2026-10-16 23:51:51 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Oct 16 23:51:51.604359 (kubelet)[2338]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 16 23:51:52.469304 kubelet[2338]: E1016 23:51:52.469234 2338 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 16 23:51:52.471947 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 16 23:51:52.472268 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 16 23:51:53.125903 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 16 23:51:53.132143 systemd[1]: Started sshd@0-172.31.28.79:22-98.64.94.113:39976.service - OpenSSH per-connection server daemon (98.64.94.113:39976). Oct 16 23:51:54.090506 sshd[2350]: Accepted publickey for core from 98.64.94.113 port 39976 ssh2: RSA SHA256:MeBDnxvGewbGpGNRHzDbWpAXnAQMNvbJ3a98jfmGPUM Oct 16 23:51:54.092960 sshd[2350]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 16 23:51:54.102595 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 16 23:51:54.109242 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 16 23:51:54.111766 systemd-logind[2080]: New session 1 of user core. Oct 16 23:51:54.127617 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 16 23:51:54.137284 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 16 23:51:54.147965 (systemd)[2356]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 16 23:51:54.267083 systemd[2356]: Queued start job for default target default.target. Oct 16 23:51:54.267599 systemd[2356]: Created slice app.slice - User Application Slice. Oct 16 23:51:54.267631 systemd[2356]: Reached target paths.target - Paths. Oct 16 23:51:54.267652 systemd[2356]: Reached target timers.target - Timers. Oct 16 23:51:54.272922 systemd[2356]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 16 23:51:54.281331 systemd[2356]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 16 23:51:54.281422 systemd[2356]: Reached target sockets.target - Sockets. Oct 16 23:51:54.281445 systemd[2356]: Reached target basic.target - Basic System. Oct 16 23:51:54.281503 systemd[2356]: Reached target default.target - Main User Target. Oct 16 23:51:54.281543 systemd[2356]: Startup finished in 126ms. Oct 16 23:51:54.281708 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 16 23:51:54.292170 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 16 23:51:54.963146 systemd[1]: Started sshd@1-172.31.28.79:22-98.64.94.113:39978.service - OpenSSH per-connection server daemon (98.64.94.113:39978). Oct 16 23:51:55.909117 sshd[2368]: Accepted publickey for core from 98.64.94.113 port 39978 ssh2: RSA SHA256:MeBDnxvGewbGpGNRHzDbWpAXnAQMNvbJ3a98jfmGPUM Oct 16 23:51:55.910578 sshd[2368]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 16 23:51:55.916279 systemd-logind[2080]: New session 2 of user core. Oct 16 23:51:55.922173 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 16 23:51:56.745987 systemd-resolved[2006]: Clock change detected. Flushing caches. Oct 16 23:51:57.221260 sshd[2368]: pam_unix(sshd:session): session closed for user core Oct 16 23:51:57.224747 systemd[1]: sshd@1-172.31.28.79:22-98.64.94.113:39978.service: Deactivated successfully. Oct 16 23:51:57.228746 systemd-logind[2080]: Session 2 logged out. Waiting for processes to exit. Oct 16 23:51:57.229722 systemd[1]: session-2.scope: Deactivated successfully. Oct 16 23:51:57.231193 systemd-logind[2080]: Removed session 2. Oct 16 23:51:57.389724 systemd[1]: Started sshd@2-172.31.28.79:22-98.64.94.113:39994.service - OpenSSH per-connection server daemon (98.64.94.113:39994). Oct 16 23:51:58.343666 sshd[2376]: Accepted publickey for core from 98.64.94.113 port 39994 ssh2: RSA SHA256:MeBDnxvGewbGpGNRHzDbWpAXnAQMNvbJ3a98jfmGPUM Oct 16 23:51:58.345348 sshd[2376]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 16 23:51:58.350706 systemd-logind[2080]: New session 3 of user core. Oct 16 23:51:58.360550 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 16 23:51:59.004891 sshd[2376]: pam_unix(sshd:session): session closed for user core Oct 16 23:51:59.009323 systemd[1]: sshd@2-172.31.28.79:22-98.64.94.113:39994.service: Deactivated successfully. Oct 16 23:51:59.014984 systemd[1]: session-3.scope: Deactivated successfully. Oct 16 23:51:59.016027 systemd-logind[2080]: Session 3 logged out. Waiting for processes to exit. Oct 16 23:51:59.017226 systemd-logind[2080]: Removed session 3. Oct 16 23:51:59.171655 systemd[1]: Started sshd@3-172.31.28.79:22-98.64.94.113:40008.service - OpenSSH per-connection server daemon (98.64.94.113:40008). Oct 16 23:52:00.148234 sshd[2384]: Accepted publickey for core from 98.64.94.113 port 40008 ssh2: RSA SHA256:MeBDnxvGewbGpGNRHzDbWpAXnAQMNvbJ3a98jfmGPUM Oct 16 23:52:00.157512 sshd[2384]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 16 23:52:00.208351 systemd-logind[2080]: New session 4 of user core. Oct 16 23:52:00.220239 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 16 23:52:00.810769 sshd[2384]: pam_unix(sshd:session): session closed for user core Oct 16 23:52:00.814658 systemd[1]: sshd@3-172.31.28.79:22-98.64.94.113:40008.service: Deactivated successfully. Oct 16 23:52:00.819679 systemd-logind[2080]: Session 4 logged out. Waiting for processes to exit. Oct 16 23:52:00.820129 systemd[1]: session-4.scope: Deactivated successfully. Oct 16 23:52:00.822262 systemd-logind[2080]: Removed session 4. Oct 16 23:52:00.970533 systemd[1]: Started sshd@4-172.31.28.79:22-98.64.94.113:36496.service - OpenSSH per-connection server daemon (98.64.94.113:36496). Oct 16 23:52:02.032895 sshd[2392]: Accepted publickey for core from 98.64.94.113 port 36496 ssh2: RSA SHA256:MeBDnxvGewbGpGNRHzDbWpAXnAQMNvbJ3a98jfmGPUM Oct 16 23:52:02.039901 sshd[2392]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 16 23:52:02.056530 systemd-logind[2080]: New session 5 of user core. Oct 16 23:52:02.064580 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 16 23:52:02.600059 sudo[2396]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 16 23:52:02.600631 sudo[2396]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 16 23:52:03.372760 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Oct 16 23:52:03.391576 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 16 23:52:04.190362 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 16 23:52:04.209820 (kubelet)[2422]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 16 23:52:04.278123 kubelet[2422]: E1016 23:52:04.278053 2422 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 16 23:52:04.283433 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 16 23:52:04.283657 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 16 23:52:04.684604 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 16 23:52:04.692858 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 16 23:52:04.741428 systemd[1]: Reloading requested from client PID 2456 ('systemctl') (unit session-5.scope)... Oct 16 23:52:04.741447 systemd[1]: Reloading... Oct 16 23:52:04.873087 zram_generator::config[2496]: No configuration found. Oct 16 23:52:05.082984 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. Oct 16 23:52:05.281051 systemd[1]: Reloading finished in 539 ms. Oct 16 23:52:05.348993 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 16 23:52:05.349145 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 16 23:52:05.349663 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 16 23:52:05.359767 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 16 23:52:05.633314 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 16 23:52:05.646260 (kubelet)[2571]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 16 23:52:05.706604 kubelet[2571]: 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. Oct 16 23:52:05.932799 kubelet[2571]: I1016 23:52:05.932629 2571 server.go:525] "Kubelet version" kubeletVersion="v1.35.1" Oct 16 23:52:05.932799 kubelet[2571]: I1016 23:52:05.932685 2571 server.go:527] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 16 23:52:05.932799 kubelet[2571]: I1016 23:52:05.932707 2571 watchdog_linux.go:95] "Systemd watchdog is not enabled" Oct 16 23:52:05.932799 kubelet[2571]: I1016 23:52:05.932714 2571 watchdog_linux.go:138] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 16 23:52:05.933508 kubelet[2571]: I1016 23:52:05.933117 2571 server.go:951] "Client rotation is on, will bootstrap in background" Oct 16 23:52:05.940520 kubelet[2571]: I1016 23:52:05.939789 2571 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 16 23:52:05.944507 kubelet[2571]: E1016 23:52:05.943655 2571 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 16 23:52:05.944507 kubelet[2571]: I1016 23:52:05.943714 2571 server.go:1395] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Oct 16 23:52:05.949874 kubelet[2571]: I1016 23:52:05.949837 2571 server.go:775] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Oct 16 23:52:05.951889 kubelet[2571]: I1016 23:52:05.951838 2571 container_manager_linux.go:272] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 16 23:52:05.952261 kubelet[2571]: I1016 23:52:05.951893 2571 container_manager_linux.go:277] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.28.79","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} Oct 16 23:52:05.952453 kubelet[2571]: I1016 23:52:05.952272 2571 topology_manager.go:143] "Creating topology manager with none policy" Oct 16 23:52:05.952453 kubelet[2571]: I1016 23:52:05.952285 2571 container_manager_linux.go:308] "Creating device plugin manager" Oct 16 23:52:05.952453 kubelet[2571]: I1016 23:52:05.952413 2571 container_manager_linux.go:317] "Creating Dynamic Resource Allocation (DRA) manager" Oct 16 23:52:05.957258 kubelet[2571]: I1016 23:52:05.957217 2571 state_mem.go:41] "Initialized" logger="CPUManager state memory" Oct 16 23:52:05.957453 kubelet[2571]: I1016 23:52:05.957445 2571 kubelet.go:482] "Attempting to sync node with API server" Oct 16 23:52:05.957506 kubelet[2571]: I1016 23:52:05.957463 2571 kubelet.go:383] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 16 23:52:05.957506 kubelet[2571]: I1016 23:52:05.957497 2571 kubelet.go:394] "Adding apiserver pod source" Oct 16 23:52:05.958142 kubelet[2571]: I1016 23:52:05.957511 2571 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 16 23:52:05.958142 kubelet[2571]: E1016 23:52:05.957925 2571 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:05.958249 kubelet[2571]: E1016 23:52:05.958188 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:05.960549 kubelet[2571]: I1016 23:52:05.960529 2571 kuberuntime_manager.go:294] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Oct 16 23:52:05.963972 kubelet[2571]: I1016 23:52:05.963943 2571 kubelet.go:943] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 16 23:52:05.964105 kubelet[2571]: I1016 23:52:05.963990 2571 kubelet.go:970] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Oct 16 23:52:05.964105 kubelet[2571]: W1016 23:52:05.964101 2571 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 16 23:52:05.966739 kubelet[2571]: I1016 23:52:05.966715 2571 server.go:1257] "Started kubelet" Oct 16 23:52:05.970532 kubelet[2571]: I1016 23:52:05.970498 2571 server.go:182] "Starting to listen" address="0.0.0.0" port=10250 Oct 16 23:52:05.972464 kubelet[2571]: I1016 23:52:05.971826 2571 server.go:317] "Adding debug handlers to kubelet server" Oct 16 23:52:05.974236 kubelet[2571]: I1016 23:52:05.974163 2571 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 16 23:52:05.974368 kubelet[2571]: I1016 23:52:05.974246 2571 server_v1.go:49] "podresources" method="list" useActivePods=true Oct 16 23:52:05.974562 kubelet[2571]: I1016 23:52:05.974537 2571 server.go:254] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 16 23:52:05.978795 kubelet[2571]: I1016 23:52:05.978770 2571 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Oct 16 23:52:05.992086 kubelet[2571]: I1016 23:52:05.991855 2571 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 16 23:52:05.994261 kubelet[2571]: I1016 23:52:05.994229 2571 volume_manager.go:311] "Starting Kubelet Volume Manager" Oct 16 23:52:05.994662 kubelet[2571]: E1016 23:52:05.994543 2571 kubelet_node_status.go:392] "Error getting the current node from lister" err="node \"172.31.28.79\" not found" Oct 16 23:52:05.994894 kubelet[2571]: I1016 23:52:05.994828 2571 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Oct 16 23:52:05.994953 kubelet[2571]: I1016 23:52:05.994905 2571 reconciler.go:29] "Reconciler: start to sync state" Oct 16 23:52:06.000338 kubelet[2571]: E1016 23:52:06.000226 2571 kubelet.go:1656] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 16 23:52:06.001310 kubelet[2571]: I1016 23:52:06.001210 2571 factory.go:223] Registration of the systemd container factory successfully Oct 16 23:52:06.001456 kubelet[2571]: I1016 23:52:06.001426 2571 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Oct 16 23:52:06.003396 kubelet[2571]: E1016 23:52:06.003367 2571 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.28.79\" not found" node="172.31.28.79" Oct 16 23:52:06.004002 kubelet[2571]: I1016 23:52:06.003955 2571 factory.go:223] Registration of the containerd container factory successfully Oct 16 23:52:06.035680 kubelet[2571]: I1016 23:52:06.035376 2571 cpu_manager.go:225] "Starting" policy="none" Oct 16 23:52:06.035680 kubelet[2571]: I1016 23:52:06.035406 2571 cpu_manager.go:226] "Reconciling" reconcilePeriod="10s" Oct 16 23:52:06.035680 kubelet[2571]: I1016 23:52:06.035428 2571 state_mem.go:41] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Oct 16 23:52:06.039630 kubelet[2571]: I1016 23:52:06.039583 2571 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Oct 16 23:52:06.044326 kubelet[2571]: I1016 23:52:06.039810 2571 policy_none.go:50] "Start" Oct 16 23:52:06.044836 kubelet[2571]: I1016 23:52:06.044471 2571 memory_manager.go:187] "Starting memorymanager" policy="None" Oct 16 23:52:06.044836 kubelet[2571]: I1016 23:52:06.044499 2571 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Oct 16 23:52:06.050090 kubelet[2571]: I1016 23:52:06.048873 2571 policy_none.go:44] "Start" Oct 16 23:52:06.053568 kubelet[2571]: E1016 23:52:06.053538 2571 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 16 23:52:06.053951 kubelet[2571]: I1016 23:52:06.053933 2571 eviction_manager.go:194] "Eviction manager: starting control loop" Oct 16 23:52:06.054109 kubelet[2571]: I1016 23:52:06.054046 2571 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 16 23:52:06.056022 kubelet[2571]: I1016 23:52:06.056002 2571 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Oct 16 23:52:06.077113 kubelet[2571]: E1016 23:52:06.077084 2571 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 16 23:52:06.077302 kubelet[2571]: E1016 23:52:06.077287 2571 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.28.79\" not found" Oct 16 23:52:06.110676 kubelet[2571]: I1016 23:52:06.110631 2571 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Oct 16 23:52:06.110676 kubelet[2571]: I1016 23:52:06.110660 2571 status_manager.go:249] "Starting to sync pod status with apiserver" Oct 16 23:52:06.110836 kubelet[2571]: I1016 23:52:06.110691 2571 kubelet.go:2501] "Starting kubelet main sync loop" Oct 16 23:52:06.110836 kubelet[2571]: E1016 23:52:06.110757 2571 kubelet.go:2525] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Oct 16 23:52:06.154445 sudo[2396]: pam_unix(sudo:session): session closed for user root Oct 16 23:52:06.156433 kubelet[2571]: I1016 23:52:06.156132 2571 kubelet_node_status.go:74] "Attempting to register node" node="172.31.28.79" Oct 16 23:52:06.163167 kubelet[2571]: I1016 23:52:06.162596 2571 kubelet_node_status.go:77] "Successfully registered node" node="172.31.28.79" Oct 16 23:52:06.279041 kubelet[2571]: I1016 23:52:06.278796 2571 kuberuntime_manager.go:2062] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 16 23:52:06.281014 containerd[2105]: time="2026-10-16T23:52:06.279433608Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 16 23:52:06.281476 kubelet[2571]: I1016 23:52:06.279713 2571 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 16 23:52:06.309649 sshd[2392]: pam_unix(sshd:session): session closed for user core Oct 16 23:52:06.314568 systemd[1]: sshd@4-172.31.28.79:22-98.64.94.113:36496.service: Deactivated successfully. Oct 16 23:52:06.320695 systemd[1]: session-5.scope: Deactivated successfully. Oct 16 23:52:06.321318 systemd-logind[2080]: Session 5 logged out. Waiting for processes to exit. Oct 16 23:52:06.323889 systemd-logind[2080]: Removed session 5. Oct 16 23:52:06.937252 kubelet[2571]: I1016 23:52:06.937151 2571 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 16 23:52:06.937776 kubelet[2571]: I1016 23:52:06.937424 2571 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Oct 16 23:52:06.937776 kubelet[2571]: I1016 23:52:06.937484 2571 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Oct 16 23:52:06.937946 kubelet[2571]: I1016 23:52:06.937843 2571 reflector.go:578] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:161" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:161: Unexpected watch close - watch lasted less than a second and no items received" Oct 16 23:52:06.958614 kubelet[2571]: I1016 23:52:06.958343 2571 apiserver.go:52] "Watching apiserver" Oct 16 23:52:06.958614 kubelet[2571]: E1016 23:52:06.958353 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:06.995460 kubelet[2571]: I1016 23:52:06.995428 2571 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Oct 16 23:52:07.001736 kubelet[2571]: I1016 23:52:07.001687 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9n9mw\" (UniqueName: \"kubernetes.io/projected/4a1e8361-94c7-4aef-bcdf-82fd985174b7-kube-api-access-9n9mw\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.001736 kubelet[2571]: I1016 23:52:07.001732 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/95e1b5f2-2601-45f8-8847-1cd7afeee368-xtables-lock\") pod \"kube-proxy-gclpm\" (UID: \"95e1b5f2-2601-45f8-8847-1cd7afeee368\") " pod="kube-system/kube-proxy-gclpm" Oct 16 23:52:07.001961 kubelet[2571]: I1016 23:52:07.001764 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/4a1e8361-94c7-4aef-bcdf-82fd985174b7-run\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.001961 kubelet[2571]: I1016 23:52:07.001789 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/4a1e8361-94c7-4aef-bcdf-82fd985174b7-cni\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.001961 kubelet[2571]: I1016 23:52:07.001811 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/95e1b5f2-2601-45f8-8847-1cd7afeee368-kube-proxy\") pod \"kube-proxy-gclpm\" (UID: \"95e1b5f2-2601-45f8-8847-1cd7afeee368\") " pod="kube-system/kube-proxy-gclpm" Oct 16 23:52:07.001961 kubelet[2571]: I1016 23:52:07.001829 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/95e1b5f2-2601-45f8-8847-1cd7afeee368-lib-modules\") pod \"kube-proxy-gclpm\" (UID: \"95e1b5f2-2601-45f8-8847-1cd7afeee368\") " pod="kube-system/kube-proxy-gclpm" Oct 16 23:52:07.001961 kubelet[2571]: I1016 23:52:07.001877 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xdzr8\" (UniqueName: \"kubernetes.io/projected/95e1b5f2-2601-45f8-8847-1cd7afeee368-kube-api-access-xdzr8\") pod \"kube-proxy-gclpm\" (UID: \"95e1b5f2-2601-45f8-8847-1cd7afeee368\") " pod="kube-system/kube-proxy-gclpm" Oct 16 23:52:07.002174 kubelet[2571]: I1016 23:52:07.001915 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/4a1e8361-94c7-4aef-bcdf-82fd985174b7-cni-plugin\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.002174 kubelet[2571]: I1016 23:52:07.002015 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/4a1e8361-94c7-4aef-bcdf-82fd985174b7-flannel-cfg\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.002174 kubelet[2571]: I1016 23:52:07.002049 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/4a1e8361-94c7-4aef-bcdf-82fd985174b7-xtables-lock\") pod \"kube-flannel-ds-bvbbh\" (UID: \"4a1e8361-94c7-4aef-bcdf-82fd985174b7\") " pod="kube-flannel/kube-flannel-ds-bvbbh" Oct 16 23:52:07.287061 containerd[2105]: time="2026-10-16T23:52:07.284990113Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-gclpm,Uid:95e1b5f2-2601-45f8-8847-1cd7afeee368,Namespace:kube-system,Attempt:0,}" Oct 16 23:52:07.289248 containerd[2105]: time="2026-10-16T23:52:07.289190063Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bvbbh,Uid:4a1e8361-94c7-4aef-bcdf-82fd985174b7,Namespace:kube-flannel,Attempt:0,}" Oct 16 23:52:07.959539 kubelet[2571]: E1016 23:52:07.959473 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:08.198147 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount807182298.mount: Deactivated successfully. Oct 16 23:52:08.217233 containerd[2105]: time="2026-10-16T23:52:08.217099219Z" 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\"}" Oct 16 23:52:08.219847 containerd[2105]: time="2026-10-16T23:52:08.219357891Z" 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\"}" Oct 16 23:52:08.221313 containerd[2105]: time="2026-10-16T23:52:08.221006701Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312210" Oct 16 23:52:08.223502 containerd[2105]: time="2026-10-16T23:52:08.223444803Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Oct 16 23:52:08.225364 containerd[2105]: time="2026-10-16T23:52:08.225294115Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 16 23:52:08.229918 containerd[2105]: time="2026-10-16T23:52:08.229607067Z" 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\"}" Oct 16 23:52:08.231860 containerd[2105]: time="2026-10-16T23:52:08.230570341Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 945.496786ms" Oct 16 23:52:08.233973 containerd[2105]: time="2026-10-16T23:52:08.233922039Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 944.639399ms" Oct 16 23:52:08.417603 containerd[2105]: time="2026-10-16T23:52:08.417253783Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 16 23:52:08.417603 containerd[2105]: time="2026-10-16T23:52:08.417354384Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 16 23:52:08.417603 containerd[2105]: time="2026-10-16T23:52:08.417374414Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:08.418165 containerd[2105]: time="2026-10-16T23:52:08.417655184Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:08.421781 containerd[2105]: time="2026-10-16T23:52:08.420429354Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 16 23:52:08.421781 containerd[2105]: time="2026-10-16T23:52:08.420491222Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 16 23:52:08.421781 containerd[2105]: time="2026-10-16T23:52:08.420508857Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:08.421781 containerd[2105]: time="2026-10-16T23:52:08.420601344Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:08.583164 containerd[2105]: time="2026-10-16T23:52:08.582983982Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-gclpm,Uid:95e1b5f2-2601-45f8-8847-1cd7afeee368,Namespace:kube-system,Attempt:0,} returns sandbox id \"db0f822d944f6d626796d0868a0cd71a12aa0b9eae6a339f2606a2cbcfeeed2c\"" Oct 16 23:52:08.589582 containerd[2105]: time="2026-10-16T23:52:08.589288330Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.9\"" Oct 16 23:52:08.601258 containerd[2105]: time="2026-10-16T23:52:08.601218490Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bvbbh,Uid:4a1e8361-94c7-4aef-bcdf-82fd985174b7,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\"" Oct 16 23:52:08.959911 kubelet[2571]: E1016 23:52:08.959768 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:09.960197 kubelet[2571]: E1016 23:52:09.960133 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:10.286502 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2923208578.mount: Deactivated successfully. Oct 16 23:52:10.711078 containerd[2105]: time="2026-10-16T23:52:10.710904874Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.35.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:10.713097 containerd[2105]: time="2026-10-16T23:52:10.712889754Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.35.9: active requests=0, bytes read=27180514" Oct 16 23:52:10.715495 containerd[2105]: time="2026-10-16T23:52:10.715415929Z" level=info msg="ImageCreate event name:\"sha256:96f81a7f09902e7b4d576db8ea407eecd69a63b3f0aed2e2992ad5b23a73c5fa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:10.718925 containerd[2105]: time="2026-10-16T23:52:10.718837642Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:10.720103 containerd[2105]: time="2026-10-16T23:52:10.719698851Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.35.9\" with image id \"sha256:96f81a7f09902e7b4d576db8ea407eecd69a63b3f0aed2e2992ad5b23a73c5fa\", repo tag \"registry.k8s.io/kube-proxy:v1.35.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\", size \"27179348\" in 2.130361407s" Oct 16 23:52:10.720103 containerd[2105]: time="2026-10-16T23:52:10.719749403Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.35.9\" returns image reference \"sha256:96f81a7f09902e7b4d576db8ea407eecd69a63b3f0aed2e2992ad5b23a73c5fa\"" Oct 16 23:52:10.721277 containerd[2105]: time="2026-10-16T23:52:10.721246632Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 16 23:52:10.727401 containerd[2105]: time="2026-10-16T23:52:10.727349448Z" level=info msg="CreateContainer within sandbox \"db0f822d944f6d626796d0868a0cd71a12aa0b9eae6a339f2606a2cbcfeeed2c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 16 23:52:10.756056 containerd[2105]: time="2026-10-16T23:52:10.755990582Z" level=info msg="CreateContainer within sandbox \"db0f822d944f6d626796d0868a0cd71a12aa0b9eae6a339f2606a2cbcfeeed2c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"6f7703c4dbf4253449d82b61c89b6891ff2fbffee1dda3a0414d028b15283244\"" Oct 16 23:52:10.757542 containerd[2105]: time="2026-10-16T23:52:10.757505837Z" level=info msg="StartContainer for \"6f7703c4dbf4253449d82b61c89b6891ff2fbffee1dda3a0414d028b15283244\"" Oct 16 23:52:10.842342 containerd[2105]: time="2026-10-16T23:52:10.842296394Z" level=info msg="StartContainer for \"6f7703c4dbf4253449d82b61c89b6891ff2fbffee1dda3a0414d028b15283244\" returns successfully" Oct 16 23:52:10.960580 kubelet[2571]: E1016 23:52:10.960507 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:11.960917 kubelet[2571]: E1016 23:52:11.960846 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:12.024381 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1682146072.mount: Deactivated successfully. Oct 16 23:52:12.098568 containerd[2105]: time="2026-10-16T23:52:12.098513786Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:12.100718 containerd[2105]: time="2026-10-16T23:52:12.100426098Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Oct 16 23:52:12.103754 containerd[2105]: time="2026-10-16T23:52:12.103076472Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:12.107737 containerd[2105]: time="2026-10-16T23:52:12.107661732Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:12.109401 containerd[2105]: time="2026-10-16T23:52:12.109102702Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.38780917s" Oct 16 23:52:12.109401 containerd[2105]: time="2026-10-16T23:52:12.109151930Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Oct 16 23:52:12.117215 containerd[2105]: time="2026-10-16T23:52:12.117161724Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 16 23:52:12.147589 containerd[2105]: time="2026-10-16T23:52:12.147542499Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3\"" Oct 16 23:52:12.148240 containerd[2105]: time="2026-10-16T23:52:12.148086744Z" level=info msg="StartContainer for \"38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3\"" Oct 16 23:52:12.209009 containerd[2105]: time="2026-10-16T23:52:12.208811340Z" level=info msg="StartContainer for \"38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3\" returns successfully" Oct 16 23:52:12.389535 containerd[2105]: time="2026-10-16T23:52:12.389371478Z" level=info msg="shim disconnected" id=38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3 namespace=k8s.io Oct 16 23:52:12.389535 containerd[2105]: time="2026-10-16T23:52:12.389438465Z" level=warning msg="cleaning up after shim disconnected" id=38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3 namespace=k8s.io Oct 16 23:52:12.389535 containerd[2105]: time="2026-10-16T23:52:12.389451805Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 16 23:52:12.903703 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-38102de1f3d4af4b7c70de28d081c5fe02b2e88d9a56ad6e74b555b945e664b3-rootfs.mount: Deactivated successfully. Oct 16 23:52:12.961592 kubelet[2571]: E1016 23:52:12.961541 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:13.140344 containerd[2105]: time="2026-10-16T23:52:13.140306366Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 16 23:52:13.152651 kubelet[2571]: I1016 23:52:13.152582 2571 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-system/kube-proxy-gclpm" podStartSLOduration=5.020060119 podStartE2EDuration="7.152565888s" podCreationTimestamp="2026-10-16 23:52:06 +0000 UTC" firstStartedPulling="2026-10-16 23:52:08.588519519 +0000 UTC m=+2.934539948" lastFinishedPulling="2026-10-16 23:52:10.72102527 +0000 UTC m=+5.067045717" observedRunningTime="2026-10-16 23:52:11.1481672 +0000 UTC m=+5.494187651" watchObservedRunningTime="2026-10-16 23:52:13.152565888 +0000 UTC m=+7.498586337" Oct 16 23:52:13.962154 kubelet[2571]: E1016 23:52:13.962100 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:14.963127 kubelet[2571]: E1016 23:52:14.963056 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:15.490859 containerd[2105]: time="2026-10-16T23:52:15.490801951Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:15.492903 containerd[2105]: time="2026-10-16T23:52:15.492621954Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Oct 16 23:52:15.495757 containerd[2105]: time="2026-10-16T23:52:15.495006570Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:15.500697 containerd[2105]: time="2026-10-16T23:52:15.500632059Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:15.503147 containerd[2105]: time="2026-10-16T23:52:15.503094062Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 2.362745532s" Oct 16 23:52:15.503147 containerd[2105]: time="2026-10-16T23:52:15.503150530Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Oct 16 23:52:15.511058 containerd[2105]: time="2026-10-16T23:52:15.511010005Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 16 23:52:15.536500 containerd[2105]: time="2026-10-16T23:52:15.536449879Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374\"" Oct 16 23:52:15.537158 containerd[2105]: time="2026-10-16T23:52:15.537092155Z" level=info msg="StartContainer for \"46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374\"" Oct 16 23:52:15.610509 containerd[2105]: time="2026-10-16T23:52:15.610468288Z" level=info msg="StartContainer for \"46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374\" returns successfully" Oct 16 23:52:15.630742 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374-rootfs.mount: Deactivated successfully. Oct 16 23:52:15.645640 kubelet[2571]: I1016 23:52:15.645596 2571 kubelet_node_status.go:427] "Fast updating node status as it just became ready" Oct 16 23:52:15.744259 containerd[2105]: time="2026-10-16T23:52:15.743994668Z" level=info msg="shim disconnected" id=46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374 namespace=k8s.io Oct 16 23:52:15.744259 containerd[2105]: time="2026-10-16T23:52:15.744082244Z" level=warning msg="cleaning up after shim disconnected" id=46019ba28a5fa000fc401d8d1e46ccb3768356ea1ea6707991182cb618a41374 namespace=k8s.io Oct 16 23:52:15.744259 containerd[2105]: time="2026-10-16T23:52:15.744097402Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 16 23:52:15.963897 kubelet[2571]: E1016 23:52:15.963837 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:16.154718 containerd[2105]: time="2026-10-16T23:52:16.154566112Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 16 23:52:16.179934 containerd[2105]: time="2026-10-16T23:52:16.179877353Z" level=info msg="CreateContainer within sandbox \"5904647a62ebf51b90a388c26ad02c46ebe886b868d2e666f3a54227026f40e2\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"5a30308bba63b3c4c727e20b2b767375a93829e54bafda51b9e91f57825169e1\"" Oct 16 23:52:16.180749 containerd[2105]: time="2026-10-16T23:52:16.180712479Z" level=info msg="StartContainer for \"5a30308bba63b3c4c727e20b2b767375a93829e54bafda51b9e91f57825169e1\"" Oct 16 23:52:16.236559 containerd[2105]: time="2026-10-16T23:52:16.236253604Z" level=info msg="StartContainer for \"5a30308bba63b3c4c727e20b2b767375a93829e54bafda51b9e91f57825169e1\" returns successfully" Oct 16 23:52:16.964293 kubelet[2571]: E1016 23:52:16.964237 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:17.166060 kubelet[2571]: I1016 23:52:17.166000 2571 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-bvbbh" podStartSLOduration=3.620608843 podStartE2EDuration="11.165985186s" podCreationTimestamp="2026-10-16 23:52:06 +0000 UTC" firstStartedPulling="2026-10-16 23:52:08.602799949 +0000 UTC m=+2.948820377" lastFinishedPulling="2026-10-16 23:52:16.148176269 +0000 UTC m=+10.494196720" observedRunningTime="2026-10-16 23:52:17.165933029 +0000 UTC m=+11.511953480" watchObservedRunningTime="2026-10-16 23:52:17.165985186 +0000 UTC m=+11.512005636" Oct 16 23:52:17.288899 (udev-worker)[3073]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:52:17.300861 systemd-networkd[1664]: flannel.1: Link UP Oct 16 23:52:17.300875 systemd-networkd[1664]: flannel.1: Gained carrier Oct 16 23:52:17.964480 kubelet[2571]: E1016 23:52:17.964433 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:18.822316 systemd-networkd[1664]: flannel.1: Gained IPv6LL Oct 16 23:52:18.965275 kubelet[2571]: E1016 23:52:18.965214 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:19.965925 kubelet[2571]: E1016 23:52:19.965874 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:20.342316 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 16 23:52:20.966538 kubelet[2571]: E1016 23:52:20.966482 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:21.745663 ntpd[2069]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 16 23:52:21.746193 ntpd[2069]: 16 Oct 23:52:21 ntpd[2069]: Listen normally on 6 flannel.1 192.168.1.0:123 Oct 16 23:52:21.746193 ntpd[2069]: 16 Oct 23:52:21 ntpd[2069]: Listen normally on 7 flannel.1 [fe80::d8b6:b0ff:fe54:883e%3]:123 Oct 16 23:52:21.745758 ntpd[2069]: Listen normally on 7 flannel.1 [fe80::d8b6:b0ff:fe54:883e%3]:123 Oct 16 23:52:21.966931 kubelet[2571]: E1016 23:52:21.966877 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:22.967983 kubelet[2571]: E1016 23:52:22.967924 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:23.718841 kubelet[2571]: I1016 23:52:23.718708 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tt24h\" (UniqueName: \"kubernetes.io/projected/138494cc-1871-4c75-8eb7-c41947ebf401-kube-api-access-tt24h\") pod \"nginx-deployment-6cc69d4fc7-9vj4n\" (UID: \"138494cc-1871-4c75-8eb7-c41947ebf401\") " pod="default/nginx-deployment-6cc69d4fc7-9vj4n" Oct 16 23:52:23.943575 containerd[2105]: time="2026-10-16T23:52:23.943051329Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-9vj4n,Uid:138494cc-1871-4c75-8eb7-c41947ebf401,Namespace:default,Attempt:0,}" Oct 16 23:52:23.969163 kubelet[2571]: E1016 23:52:23.968789 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:23.989913 systemd-networkd[1664]: cni0: Link UP Oct 16 23:52:23.989924 systemd-networkd[1664]: cni0: Gained carrier Oct 16 23:52:23.992439 systemd-networkd[1664]: cni0: Lost carrier Oct 16 23:52:23.992561 (udev-worker)[3168]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:52:24.000879 systemd-networkd[1664]: veth1795976b: Link UP Oct 16 23:52:24.003235 kernel: cni0: port 1(veth1795976b) entered blocking state Oct 16 23:52:24.003346 kernel: cni0: port 1(veth1795976b) entered disabled state Oct 16 23:52:24.006422 kernel: veth1795976b: entered allmulticast mode Oct 16 23:52:24.006511 kernel: veth1795976b: entered promiscuous mode Oct 16 23:52:24.008218 kernel: cni0: port 1(veth1795976b) entered blocking state Oct 16 23:52:24.008307 kernel: cni0: port 1(veth1795976b) entered forwarding state Oct 16 23:52:24.009135 kernel: cni0: port 1(veth1795976b) entered disabled state Oct 16 23:52:24.012443 (udev-worker)[3173]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:52:24.021402 kernel: cni0: port 1(veth1795976b) entered blocking state Oct 16 23:52:24.021681 kernel: cni0: port 1(veth1795976b) entered forwarding state Oct 16 23:52:24.024874 systemd-networkd[1664]: veth1795976b: Gained carrier Oct 16 23:52:24.030565 systemd-networkd[1664]: cni0: Gained carrier Oct 16 23:52:24.033230 containerd[2105]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0001047f0), "name":"cbr0", "type":"bridge"} Oct 16 23:52:24.033230 containerd[2105]: delegateAdd: netconf sent to delegate plugin: Oct 16 23:52:24.059373 containerd[2105]: {"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="2026-10-16T23:52:24.059273718Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 16 23:52:24.059373 containerd[2105]: time="2026-10-16T23:52:24.059332038Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 16 23:52:24.059613 containerd[2105]: time="2026-10-16T23:52:24.059346654Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:24.059613 containerd[2105]: time="2026-10-16T23:52:24.059453524Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:24.128395 containerd[2105]: time="2026-10-16T23:52:24.128350247Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6cc69d4fc7-9vj4n,Uid:138494cc-1871-4c75-8eb7-c41947ebf401,Namespace:default,Attempt:0,} returns sandbox id \"f7213b709132dbfa63207f73aebced796c2ece63a76567c922e58faa2f12e150\"" Oct 16 23:52:24.129823 containerd[2105]: time="2026-10-16T23:52:24.129771874Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 16 23:52:24.969624 kubelet[2571]: E1016 23:52:24.969566 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:25.606603 systemd-networkd[1664]: cni0: Gained IPv6LL Oct 16 23:52:25.670466 systemd-networkd[1664]: veth1795976b: Gained IPv6LL Oct 16 23:52:25.958625 kubelet[2571]: E1016 23:52:25.958449 2571 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:25.970426 kubelet[2571]: E1016 23:52:25.970206 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:26.698955 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount147447721.mount: Deactivated successfully. Oct 16 23:52:26.971246 kubelet[2571]: E1016 23:52:26.970804 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:27.745660 ntpd[2069]: Listen normally on 8 cni0 192.168.1.1:123 Oct 16 23:52:27.745756 ntpd[2069]: Listen normally on 9 cni0 [fe80::98a8:7dff:fe61:8a54%4]:123 Oct 16 23:52:27.746684 ntpd[2069]: 16 Oct 23:52:27 ntpd[2069]: Listen normally on 8 cni0 192.168.1.1:123 Oct 16 23:52:27.746684 ntpd[2069]: 16 Oct 23:52:27 ntpd[2069]: Listen normally on 9 cni0 [fe80::98a8:7dff:fe61:8a54%4]:123 Oct 16 23:52:27.746684 ntpd[2069]: 16 Oct 23:52:27 ntpd[2069]: Listen normally on 10 veth1795976b [fe80::d0b1:1eff:fe16:ddae%5]:123 Oct 16 23:52:27.745816 ntpd[2069]: Listen normally on 10 veth1795976b [fe80::d0b1:1eff:fe16:ddae%5]:123 Oct 16 23:52:27.834933 containerd[2105]: time="2026-10-16T23:52:27.834870262Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:27.837100 containerd[2105]: time="2026-10-16T23:52:27.836856063Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=64640498" Oct 16 23:52:27.839522 containerd[2105]: time="2026-10-16T23:52:27.839453104Z" level=info msg="ImageCreate event name:\"sha256:3a8edbda21b6357f9d8e8d441c00c44673e89af4c157e76f0f117f2429570e60\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:27.844290 containerd[2105]: time="2026-10-16T23:52:27.843627897Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:a183384b53e7717e9c1e3faf17393ce4dfa3d0800d250cceb829a2bde27f6bc2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:27.845081 containerd[2105]: time="2026-10-16T23:52:27.845019312Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3a8edbda21b6357f9d8e8d441c00c44673e89af4c157e76f0f117f2429570e60\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a183384b53e7717e9c1e3faf17393ce4dfa3d0800d250cceb829a2bde27f6bc2\", size \"64640376\" in 3.715201083s" Oct 16 23:52:27.845192 containerd[2105]: time="2026-10-16T23:52:27.845088532Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3a8edbda21b6357f9d8e8d441c00c44673e89af4c157e76f0f117f2429570e60\"" Oct 16 23:52:27.852638 containerd[2105]: time="2026-10-16T23:52:27.852595796Z" level=info msg="CreateContainer within sandbox \"f7213b709132dbfa63207f73aebced796c2ece63a76567c922e58faa2f12e150\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 16 23:52:27.876929 containerd[2105]: time="2026-10-16T23:52:27.876878952Z" level=info msg="CreateContainer within sandbox \"f7213b709132dbfa63207f73aebced796c2ece63a76567c922e58faa2f12e150\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"5384c7adbf7592b1e9dac54f0905feaaaf142edb8f13a00bf12e4786fe00c57f\"" Oct 16 23:52:27.877819 containerd[2105]: time="2026-10-16T23:52:27.877770306Z" level=info msg="StartContainer for \"5384c7adbf7592b1e9dac54f0905feaaaf142edb8f13a00bf12e4786fe00c57f\"" Oct 16 23:52:27.940217 containerd[2105]: time="2026-10-16T23:52:27.940057038Z" level=info msg="StartContainer for \"5384c7adbf7592b1e9dac54f0905feaaaf142edb8f13a00bf12e4786fe00c57f\" returns successfully" Oct 16 23:52:27.971889 kubelet[2571]: E1016 23:52:27.971837 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:28.191073 kubelet[2571]: I1016 23:52:28.190914 2571 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nginx-deployment-6cc69d4fc7-9vj4n" podStartSLOduration=1.473663518 podStartE2EDuration="5.19088831s" podCreationTimestamp="2026-10-16 23:52:23 +0000 UTC" firstStartedPulling="2026-10-16 23:52:24.129396751 +0000 UTC m=+18.475417178" lastFinishedPulling="2026-10-16 23:52:27.846621542 +0000 UTC m=+22.192641970" observedRunningTime="2026-10-16 23:52:28.190888047 +0000 UTC m=+22.536908498" watchObservedRunningTime="2026-10-16 23:52:28.19088831 +0000 UTC m=+22.536908760" Oct 16 23:52:28.972922 kubelet[2571]: E1016 23:52:28.972874 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:29.973563 kubelet[2571]: E1016 23:52:29.973505 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:30.974240 kubelet[2571]: E1016 23:52:30.974099 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:31.974583 kubelet[2571]: E1016 23:52:31.974533 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:32.975549 kubelet[2571]: E1016 23:52:32.975489 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:33.975911 kubelet[2571]: E1016 23:52:33.975859 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:34.714828 update_engine[2086]: I20261016 23:52:34.714723 2086 update_attempter.cc:509] Updating boot flags... Oct 16 23:52:34.765178 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 29 scanned by (udev-worker) (3369) Oct 16 23:52:34.932184 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 29 scanned by (udev-worker) (3368) Oct 16 23:52:34.978357 kubelet[2571]: E1016 23:52:34.978184 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:35.085104 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 29 scanned by (udev-worker) (3368) Oct 16 23:52:35.979447 kubelet[2571]: E1016 23:52:35.979402 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:36.979635 kubelet[2571]: E1016 23:52:36.979594 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:37.103321 kubelet[2571]: I1016 23:52:37.103261 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hlzzt\" (UniqueName: \"kubernetes.io/projected/7513f93d-c07d-4a53-b56b-d9963637fc7f-kube-api-access-hlzzt\") pod \"nfs-server-provisioner-0\" (UID: \"7513f93d-c07d-4a53-b56b-d9963637fc7f\") " pod="default/nfs-server-provisioner-0" Oct 16 23:52:37.103321 kubelet[2571]: I1016 23:52:37.103322 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/7513f93d-c07d-4a53-b56b-d9963637fc7f-data\") pod \"nfs-server-provisioner-0\" (UID: \"7513f93d-c07d-4a53-b56b-d9963637fc7f\") " pod="default/nfs-server-provisioner-0" Oct 16 23:52:37.290125 containerd[2105]: time="2026-10-16T23:52:37.289982008Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7513f93d-c07d-4a53-b56b-d9963637fc7f,Namespace:default,Attempt:0,}" Oct 16 23:52:37.325412 (udev-worker)[3369]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:52:37.329284 kernel: cni0: port 2(vethd33d8a66) entered blocking state Oct 16 23:52:37.329376 kernel: cni0: port 2(vethd33d8a66) entered disabled state Oct 16 23:52:37.329435 systemd-networkd[1664]: vethd33d8a66: Link UP Oct 16 23:52:37.332744 kernel: vethd33d8a66: entered allmulticast mode Oct 16 23:52:37.332849 kernel: vethd33d8a66: entered promiscuous mode Oct 16 23:52:37.342597 kernel: cni0: port 2(vethd33d8a66) entered blocking state Oct 16 23:52:37.343426 kernel: cni0: port 2(vethd33d8a66) entered forwarding state Oct 16 23:52:37.342393 systemd-networkd[1664]: vethd33d8a66: Gained carrier Oct 16 23:52:37.345534 containerd[2105]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Oct 16 23:52:37.345534 containerd[2105]: delegateAdd: netconf sent to delegate plugin: Oct 16 23:52:37.372634 containerd[2105]: {"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="2026-10-16T23:52:37.372337970Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 16 23:52:37.372634 containerd[2105]: time="2026-10-16T23:52:37.372397868Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 16 23:52:37.372634 containerd[2105]: time="2026-10-16T23:52:37.372427819Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:37.372634 containerd[2105]: time="2026-10-16T23:52:37.372560253Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:37.438235 containerd[2105]: time="2026-10-16T23:52:37.438196286Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7513f93d-c07d-4a53-b56b-d9963637fc7f,Namespace:default,Attempt:0,} returns sandbox id \"dc3b38d883084e3a94d0ecd61babb579d86df71c350ddfe7876b8b453b890941\"" Oct 16 23:52:37.441769 containerd[2105]: time="2026-10-16T23:52:37.441263416Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 16 23:52:37.980166 kubelet[2571]: E1016 23:52:37.980094 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:38.470415 systemd-networkd[1664]: vethd33d8a66: Gained IPv6LL Oct 16 23:52:38.981082 kubelet[2571]: E1016 23:52:38.980386 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:39.981004 kubelet[2571]: E1016 23:52:39.980965 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:40.269571 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2074703455.mount: Deactivated successfully. Oct 16 23:52:40.982566 kubelet[2571]: E1016 23:52:40.982529 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:41.745895 ntpd[2069]: Listen normally on 11 vethd33d8a66 [fe80::1010:bff:febe:2afb%6]:123 Oct 16 23:52:41.747280 ntpd[2069]: 16 Oct 23:52:41 ntpd[2069]: Listen normally on 11 vethd33d8a66 [fe80::1010:bff:febe:2afb%6]:123 Oct 16 23:52:41.983555 kubelet[2571]: E1016 23:52:41.983491 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:42.398006 containerd[2105]: time="2026-10-16T23:52:42.397949724Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:42.400036 containerd[2105]: time="2026-10-16T23:52:42.399960535Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039850" Oct 16 23:52:42.402652 containerd[2105]: time="2026-10-16T23:52:42.402607495Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:42.407043 containerd[2105]: time="2026-10-16T23:52:42.406998754Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:42.408853 containerd[2105]: time="2026-10-16T23:52:42.408805051Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.967500048s" Oct 16 23:52:42.408991 containerd[2105]: time="2026-10-16T23:52:42.408856189Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Oct 16 23:52:42.415845 containerd[2105]: time="2026-10-16T23:52:42.415804638Z" level=info msg="CreateContainer within sandbox \"dc3b38d883084e3a94d0ecd61babb579d86df71c350ddfe7876b8b453b890941\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 16 23:52:42.442777 containerd[2105]: time="2026-10-16T23:52:42.442625617Z" level=info msg="CreateContainer within sandbox \"dc3b38d883084e3a94d0ecd61babb579d86df71c350ddfe7876b8b453b890941\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"b46ee64552afb5a35d647188accc4fa1c7342e4414ff4022bc411adf251819bb\"" Oct 16 23:52:42.443672 containerd[2105]: time="2026-10-16T23:52:42.443629110Z" level=info msg="StartContainer for \"b46ee64552afb5a35d647188accc4fa1c7342e4414ff4022bc411adf251819bb\"" Oct 16 23:52:42.526952 systemd[1]: run-containerd-runc-k8s.io-b46ee64552afb5a35d647188accc4fa1c7342e4414ff4022bc411adf251819bb-runc.XLLe3E.mount: Deactivated successfully. Oct 16 23:52:42.561439 containerd[2105]: time="2026-10-16T23:52:42.561395056Z" level=info msg="StartContainer for \"b46ee64552afb5a35d647188accc4fa1c7342e4414ff4022bc411adf251819bb\" returns successfully" Oct 16 23:52:42.983959 kubelet[2571]: E1016 23:52:42.983898 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:43.234416 kubelet[2571]: I1016 23:52:43.234242 2571 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.265086637 podStartE2EDuration="7.234228622s" podCreationTimestamp="2026-10-16 23:52:36 +0000 UTC" firstStartedPulling="2026-10-16 23:52:37.440766811 +0000 UTC m=+31.786787241" lastFinishedPulling="2026-10-16 23:52:42.409908783 +0000 UTC m=+36.755929226" observedRunningTime="2026-10-16 23:52:43.233990353 +0000 UTC m=+37.580010803" watchObservedRunningTime="2026-10-16 23:52:43.234228622 +0000 UTC m=+37.580249072" Oct 16 23:52:43.984658 kubelet[2571]: E1016 23:52:43.984586 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:44.984980 kubelet[2571]: E1016 23:52:44.984920 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:45.958477 kubelet[2571]: E1016 23:52:45.958418 2571 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:45.985560 kubelet[2571]: E1016 23:52:45.985516 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:46.986640 kubelet[2571]: E1016 23:52:46.986585 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:47.978793 kubelet[2571]: I1016 23:52:47.978548 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-0831755c-6545-4d6c-acbd-e4a0d5dc291a\" (UniqueName: \"kubernetes.io/nfs/feb90d75-8788-464a-af0d-a7ccf0d03057-pvc-0831755c-6545-4d6c-acbd-e4a0d5dc291a\") pod \"test-pod-1\" (UID: \"feb90d75-8788-464a-af0d-a7ccf0d03057\") " pod="default/test-pod-1" Oct 16 23:52:47.978793 kubelet[2571]: I1016 23:52:47.978608 2571 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fp6qf\" (UniqueName: \"kubernetes.io/projected/feb90d75-8788-464a-af0d-a7ccf0d03057-kube-api-access-fp6qf\") pod \"test-pod-1\" (UID: \"feb90d75-8788-464a-af0d-a7ccf0d03057\") " pod="default/test-pod-1" Oct 16 23:52:47.987322 kubelet[2571]: E1016 23:52:47.987272 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:48.129149 kernel: FS-Cache: Loaded Oct 16 23:52:48.205266 kernel: RPC: Registered named UNIX socket transport module. Oct 16 23:52:48.205401 kernel: RPC: Registered udp transport module. Oct 16 23:52:48.205434 kernel: RPC: Registered tcp transport module. Oct 16 23:52:48.206229 kernel: RPC: Registered tcp-with-tls transport module. Oct 16 23:52:48.208092 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 16 23:52:48.524181 kernel: NFS: Registering the id_resolver key type Oct 16 23:52:48.524325 kernel: Key type id_resolver registered Oct 16 23:52:48.524361 kernel: Key type id_legacy registered Oct 16 23:52:48.561665 nfsidmap[3889]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Oct 16 23:52:48.566226 nfsidmap[3890]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Oct 16 23:52:48.761474 containerd[2105]: time="2026-10-16T23:52:48.761354455Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:feb90d75-8788-464a-af0d-a7ccf0d03057,Namespace:default,Attempt:0,}" Oct 16 23:52:48.824860 (udev-worker)[3886]: Network interface NamePolicy= disabled on kernel command line. Oct 16 23:52:48.826459 systemd-networkd[1664]: vethdcc44638: Link UP Oct 16 23:52:48.829413 kernel: cni0: port 3(vethdcc44638) entered blocking state Oct 16 23:52:48.829510 kernel: cni0: port 3(vethdcc44638) entered disabled state Oct 16 23:52:48.829539 kernel: vethdcc44638: entered allmulticast mode Oct 16 23:52:48.831531 kernel: vethdcc44638: entered promiscuous mode Oct 16 23:52:48.838137 kernel: cni0: port 3(vethdcc44638) entered blocking state Oct 16 23:52:48.838228 kernel: cni0: port 3(vethdcc44638) entered forwarding state Oct 16 23:52:48.838939 systemd-networkd[1664]: vethdcc44638: Gained carrier Oct 16 23:52:48.842171 containerd[2105]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00008c950), "name":"cbr0", "type":"bridge"} Oct 16 23:52:48.842171 containerd[2105]: delegateAdd: netconf sent to delegate plugin: Oct 16 23:52:48.872099 containerd[2105]: {"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="2026-10-16T23:52:48.871841822Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 16 23:52:48.872099 containerd[2105]: time="2026-10-16T23:52:48.871915739Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 16 23:52:48.872099 containerd[2105]: time="2026-10-16T23:52:48.871940013Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:48.872382 containerd[2105]: time="2026-10-16T23:52:48.872167150Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 16 23:52:48.933415 containerd[2105]: time="2026-10-16T23:52:48.933371339Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:feb90d75-8788-464a-af0d-a7ccf0d03057,Namespace:default,Attempt:0,} returns sandbox id \"6d7d4bca5202096d2f53388e23fb724f0f06693fe7aa5e4d95413bf0c4246a0f\"" Oct 16 23:52:48.934833 containerd[2105]: time="2026-10-16T23:52:48.934773035Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 16 23:52:48.988053 kubelet[2571]: E1016 23:52:48.987928 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:49.274531 containerd[2105]: time="2026-10-16T23:52:49.274468325Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 16 23:52:49.277601 containerd[2105]: time="2026-10-16T23:52:49.276329778Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 16 23:52:49.279363 containerd[2105]: time="2026-10-16T23:52:49.279315051Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3a8edbda21b6357f9d8e8d441c00c44673e89af4c157e76f0f117f2429570e60\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a183384b53e7717e9c1e3faf17393ce4dfa3d0800d250cceb829a2bde27f6bc2\", size \"64640376\" in 344.496162ms" Oct 16 23:52:49.279363 containerd[2105]: time="2026-10-16T23:52:49.279355459Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3a8edbda21b6357f9d8e8d441c00c44673e89af4c157e76f0f117f2429570e60\"" Oct 16 23:52:49.285832 containerd[2105]: time="2026-10-16T23:52:49.285794032Z" level=info msg="CreateContainer within sandbox \"6d7d4bca5202096d2f53388e23fb724f0f06693fe7aa5e4d95413bf0c4246a0f\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 16 23:52:49.311953 containerd[2105]: time="2026-10-16T23:52:49.311883260Z" level=info msg="CreateContainer within sandbox \"6d7d4bca5202096d2f53388e23fb724f0f06693fe7aa5e4d95413bf0c4246a0f\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"05405af5cdefbbb9938fb9292542270aa03e3bb81fd21e5f0e39db9b3858c386\"" Oct 16 23:52:49.312757 containerd[2105]: time="2026-10-16T23:52:49.312700706Z" level=info msg="StartContainer for \"05405af5cdefbbb9938fb9292542270aa03e3bb81fd21e5f0e39db9b3858c386\"" Oct 16 23:52:49.399345 containerd[2105]: time="2026-10-16T23:52:49.399201029Z" level=info msg="StartContainer for \"05405af5cdefbbb9938fb9292542270aa03e3bb81fd21e5f0e39db9b3858c386\" returns successfully" Oct 16 23:52:49.989122 kubelet[2571]: E1016 23:52:49.989055 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:50.054292 systemd-networkd[1664]: vethdcc44638: Gained IPv6LL Oct 16 23:52:50.250171 kubelet[2571]: I1016 23:52:50.249986 2571 pod_startup_latency_tracker.go:108] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=11.904357155 podStartE2EDuration="12.249969847s" podCreationTimestamp="2026-10-16 23:52:38 +0000 UTC" firstStartedPulling="2026-10-16 23:52:48.934477948 +0000 UTC m=+43.280498376" lastFinishedPulling="2026-10-16 23:52:49.280090622 +0000 UTC m=+43.626111068" observedRunningTime="2026-10-16 23:52:50.249769929 +0000 UTC m=+44.595790380" watchObservedRunningTime="2026-10-16 23:52:50.249969847 +0000 UTC m=+44.595990298" Oct 16 23:52:50.989729 kubelet[2571]: E1016 23:52:50.989671 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:51.989911 kubelet[2571]: E1016 23:52:51.989851 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:52.990959 kubelet[2571]: E1016 23:52:52.990905 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:53.991594 kubelet[2571]: E1016 23:52:53.991546 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:54.745746 ntpd[2069]: Listen normally on 12 vethdcc44638 [fe80::1062:7eff:fe0e:9c8a%7]:123 Oct 16 23:52:54.746263 ntpd[2069]: 16 Oct 23:52:54 ntpd[2069]: Listen normally on 12 vethdcc44638 [fe80::1062:7eff:fe0e:9c8a%7]:123 Oct 16 23:52:54.992720 kubelet[2571]: E1016 23:52:54.992665 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:55.993159 kubelet[2571]: E1016 23:52:55.993117 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:56.993954 kubelet[2571]: E1016 23:52:56.993896 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:57.994844 kubelet[2571]: E1016 23:52:57.994764 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:58.995823 kubelet[2571]: E1016 23:52:58.995764 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:52:59.996603 kubelet[2571]: E1016 23:52:59.996548 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:00.997044 kubelet[2571]: E1016 23:53:00.996980 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:02.000533 kubelet[2571]: E1016 23:53:02.000478 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:03.002915 kubelet[2571]: E1016 23:53:03.002663 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:04.003843 kubelet[2571]: E1016 23:53:04.003761 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:05.004486 kubelet[2571]: E1016 23:53:05.004429 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:05.958592 kubelet[2571]: E1016 23:53:05.958532 2571 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:06.004630 kubelet[2571]: E1016 23:53:06.004565 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:07.004767 kubelet[2571]: E1016 23:53:07.004717 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:08.005223 kubelet[2571]: E1016 23:53:08.005160 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:09.006216 kubelet[2571]: E1016 23:53:09.006161 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:10.006888 kubelet[2571]: E1016 23:53:10.006833 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:11.007771 kubelet[2571]: E1016 23:53:11.007724 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:12.008393 kubelet[2571]: E1016 23:53:12.008337 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:13.009554 kubelet[2571]: E1016 23:53:13.009489 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:14.010486 kubelet[2571]: E1016 23:53:14.010429 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:15.011570 kubelet[2571]: E1016 23:53:15.011427 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:16.012312 kubelet[2571]: E1016 23:53:16.012257 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:16.954903 kubelet[2571]: E1016 23:53:16.954842 2571 controller.go:251] "Failed to update lease" err="Put \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Oct 16 23:53:17.012986 kubelet[2571]: E1016 23:53:17.012931 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:17.417545 kubelet[2571]: E1016 23:53:17.417372 2571 kubelet_node_status.go:474] "Error updating node status, will retry" err="failed to patch status \"{\\\"status\\\":{\\\"$setElementOrder/conditions\\\":[{\\\"type\\\":\\\"NetworkUnavailable\\\"},{\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"type\\\":\\\"DiskPressure\\\"},{\\\"type\\\":\\\"PIDPressure\\\"},{\\\"type\\\":\\\"Ready\\\"}],\\\"conditions\\\":[{\\\"lastHeartbeatTime\\\":\\\"2026-10-16T23:53:07Z\\\",\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-16T23:53:07Z\\\",\\\"type\\\":\\\"DiskPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-16T23:53:07Z\\\",\\\"type\\\":\\\"PIDPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2026-10-16T23:53:07Z\\\",\\\"type\\\":\\\"Ready\\\"}],\\\"images\\\":[{\\\"names\\\":[\\\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\\\",\\\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\\\"],\\\"sizeBytes\\\":91036984},{\\\"names\\\":[\\\"ghcr.io/flatcar/nginx@sha256:a183384b53e7717e9c1e3faf17393ce4dfa3d0800d250cceb829a2bde27f6bc2\\\",\\\"ghcr.io/flatcar/nginx:latest\\\"],\\\"sizeBytes\\\":64640376},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\\\",\\\"ghcr.io/flannel-io/flannel:v0.26.7\\\"],\\\"sizeBytes\\\":32996046},{\\\"names\\\":[\\\"registry.k8s.io/kube-proxy@sha256:d08adb0c8c2c68075f1e6fa6c4c82880c5af54c1686ac20d22af612082c32a31\\\",\\\"registry.k8s.io/kube-proxy:v1.35.9\\\"],\\\"sizeBytes\\\":27179348},{\\\"names\\\":[\\\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\\\",\\\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\\\"],\\\"sizeBytes\\\":4856838},{\\\"names\\\":[\\\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\\\",\\\"registry.k8s.io/pause:3.8\\\"],\\\"sizeBytes\\\":311286}]}}\" for node \"172.31.28.79\": Patch \"https://172.31.23.242:6443/api/v1/nodes/172.31.28.79/status?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 16 23:53:18.013870 kubelet[2571]: E1016 23:53:18.013807 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:19.014824 kubelet[2571]: E1016 23:53:19.014755 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:20.015604 kubelet[2571]: E1016 23:53:20.015547 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:21.016363 kubelet[2571]: E1016 23:53:21.016281 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:22.017301 kubelet[2571]: E1016 23:53:22.017241 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:23.017413 kubelet[2571]: E1016 23:53:23.017346 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:24.018204 kubelet[2571]: E1016 23:53:24.018141 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:25.018397 kubelet[2571]: E1016 23:53:25.018337 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:25.958381 kubelet[2571]: E1016 23:53:25.958326 2571 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:26.019333 kubelet[2571]: E1016 23:53:26.019293 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:26.955851 kubelet[2571]: E1016 23:53:26.955786 2571 controller.go:251] "Failed to update lease" err="Put \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": context deadline exceeded" Oct 16 23:53:27.019791 kubelet[2571]: E1016 23:53:27.019731 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:27.417966 kubelet[2571]: E1016 23:53:27.417838 2571 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.28.79\": Get \"https://172.31.23.242:6443/api/v1/nodes/172.31.28.79?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 16 23:53:28.020594 kubelet[2571]: E1016 23:53:28.020505 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:29.021762 kubelet[2571]: E1016 23:53:29.021707 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:30.022133 kubelet[2571]: E1016 23:53:30.021972 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:31.022320 kubelet[2571]: E1016 23:53:31.022273 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:32.023476 kubelet[2571]: E1016 23:53:32.023418 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:33.023973 kubelet[2571]: E1016 23:53:33.023914 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:34.024946 kubelet[2571]: E1016 23:53:34.024830 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:35.025673 kubelet[2571]: E1016 23:53:35.025618 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:36.026717 kubelet[2571]: E1016 23:53:36.026664 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:36.956630 kubelet[2571]: E1016 23:53:36.956566 2571 controller.go:251] "Failed to update lease" err="Put \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Oct 16 23:53:36.978092 kubelet[2571]: E1016 23:53:36.976664 2571 controller.go:251] "Failed to update lease" err="Put \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": unexpected EOF" Oct 16 23:53:36.981616 kubelet[2571]: E1016 23:53:36.981560 2571 controller.go:251] "Failed to update lease" err="Put \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": read tcp 172.31.28.79:58644->172.31.23.242:6443: read: connection reset by peer" Oct 16 23:53:36.981616 kubelet[2571]: I1016 23:53:36.981607 2571 controller.go:171] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Oct 16 23:53:36.982540 kubelet[2571]: E1016 23:53:36.982505 2571 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": dial tcp 172.31.23.242:6443: connect: connection refused" interval="200ms" Oct 16 23:53:37.027132 kubelet[2571]: E1016 23:53:37.027060 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:37.184334 kubelet[2571]: E1016 23:53:37.184289 2571 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": dial tcp 172.31.23.242:6443: connect: connection refused" interval="400ms" Oct 16 23:53:37.418621 kubelet[2571]: E1016 23:53:37.418483 2571 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.28.79\": Get \"https://172.31.23.242:6443/api/v1/nodes/172.31.28.79?timeout=10s\": context deadline exceeded - error from a previous attempt: unexpected EOF" Oct 16 23:53:37.419355 kubelet[2571]: E1016 23:53:37.419220 2571 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.28.79\": Get \"https://172.31.23.242:6443/api/v1/nodes/172.31.28.79?timeout=10s\": dial tcp 172.31.23.242:6443: connect: connection refused" Oct 16 23:53:37.419749 kubelet[2571]: E1016 23:53:37.419719 2571 kubelet_node_status.go:474] "Error updating node status, will retry" err="error getting node \"172.31.28.79\": Get \"https://172.31.23.242:6443/api/v1/nodes/172.31.28.79?timeout=10s\": dial tcp 172.31.23.242:6443: connect: connection refused" Oct 16 23:53:37.419835 kubelet[2571]: E1016 23:53:37.419755 2571 kubelet_node_status.go:461] "Unable to update node status" err="update node status exceeds retry count" Oct 16 23:53:37.585390 kubelet[2571]: E1016 23:53:37.585298 2571 controller.go:201] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.23.242:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.28.79?timeout=10s\": dial tcp 172.31.23.242:6443: connect: connection refused" interval="800ms" Oct 16 23:53:38.028003 kubelet[2571]: E1016 23:53:38.027950 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:39.028793 kubelet[2571]: E1016 23:53:39.028733 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:40.029279 kubelet[2571]: E1016 23:53:40.029233 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:41.030132 kubelet[2571]: E1016 23:53:41.030081 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:42.030347 kubelet[2571]: E1016 23:53:42.030292 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:43.031474 kubelet[2571]: E1016 23:53:43.031387 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:44.031640 kubelet[2571]: E1016 23:53:44.031582 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 16 23:53:45.033354 kubelet[2571]: E1016 23:53:45.033016 2571 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"