Jan 13 20:36:01.269369 kernel: Linux version 6.6.71-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241116 p3) 14.2.1 20241116, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Jan 13 18:58:40 -00 2025 Jan 13 20:36:01.269412 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 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=8a11404d893165624d9716a125d997be53e2d6cdb0c50a945acda5b62a14eda5 Jan 13 20:36:01.269426 kernel: BIOS-provided physical RAM map: Jan 13 20:36:01.269437 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Jan 13 20:36:01.269447 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Jan 13 20:36:01.269458 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Jan 13 20:36:01.269474 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007d9e9fff] usable Jan 13 20:36:01.269485 kernel: BIOS-e820: [mem 0x000000007d9ea000-0x000000007fffffff] reserved Jan 13 20:36:01.269496 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000e03fffff] reserved Jan 13 20:36:01.269507 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Jan 13 20:36:01.269518 kernel: NX (Execute Disable) protection: active Jan 13 20:36:01.269530 kernel: APIC: Static calls initialized Jan 13 20:36:01.269540 kernel: SMBIOS 2.7 present. Jan 13 20:36:01.269551 kernel: DMI: Amazon EC2 t3.small/, BIOS 1.0 10/16/2017 Jan 13 20:36:01.269590 kernel: Hypervisor detected: KVM Jan 13 20:36:01.269603 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Jan 13 20:36:01.269615 kernel: kvm-clock: using sched offset of 8180567674 cycles Jan 13 20:36:01.269629 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jan 13 20:36:01.269642 kernel: tsc: Detected 2499.994 MHz processor Jan 13 20:36:01.269655 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 13 20:36:01.269669 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 13 20:36:01.269686 kernel: last_pfn = 0x7d9ea max_arch_pfn = 0x400000000 Jan 13 20:36:01.269699 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Jan 13 20:36:01.269712 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 13 20:36:01.269725 kernel: Using GB pages for direct mapping Jan 13 20:36:01.269738 kernel: ACPI: Early table checksum verification disabled Jan 13 20:36:01.269751 kernel: ACPI: RSDP 0x00000000000F8F40 000014 (v00 AMAZON) Jan 13 20:36:01.269764 kernel: ACPI: RSDT 0x000000007D9EE350 000044 (v01 AMAZON AMZNRSDT 00000001 AMZN 00000001) Jan 13 20:36:01.269777 kernel: ACPI: FACP 0x000000007D9EFF80 000074 (v01 AMAZON AMZNFACP 00000001 AMZN 00000001) Jan 13 20:36:01.269790 kernel: ACPI: DSDT 0x000000007D9EE3A0 0010E9 (v01 AMAZON AMZNDSDT 00000001 AMZN 00000001) Jan 13 20:36:01.269806 kernel: ACPI: FACS 0x000000007D9EFF40 000040 Jan 13 20:36:01.269820 kernel: ACPI: SSDT 0x000000007D9EF6C0 00087A (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001) Jan 13 20:36:01.269833 kernel: ACPI: APIC 0x000000007D9EF5D0 000076 (v01 AMAZON AMZNAPIC 00000001 AMZN 00000001) Jan 13 20:36:01.269846 kernel: ACPI: SRAT 0x000000007D9EF530 0000A0 (v01 AMAZON AMZNSRAT 00000001 AMZN 00000001) Jan 13 20:36:01.269859 kernel: ACPI: SLIT 0x000000007D9EF4C0 00006C (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Jan 13 20:36:01.269872 kernel: ACPI: WAET 0x000000007D9EF490 000028 (v01 AMAZON AMZNWAET 00000001 AMZN 00000001) Jan 13 20:36:01.269885 kernel: ACPI: HPET 0x00000000000C9000 000038 (v01 AMAZON AMZNHPET 00000001 AMZN 00000001) Jan 13 20:36:01.269898 kernel: ACPI: SSDT 0x00000000000C9040 00007B (v01 AMAZON AMZNSSDT 00000001 AMZN 00000001) Jan 13 20:36:01.269911 kernel: ACPI: Reserving FACP table memory at [mem 0x7d9eff80-0x7d9efff3] Jan 13 20:36:01.269927 kernel: ACPI: Reserving DSDT table memory at [mem 0x7d9ee3a0-0x7d9ef488] Jan 13 20:36:01.269946 kernel: ACPI: Reserving FACS table memory at [mem 0x7d9eff40-0x7d9eff7f] Jan 13 20:36:01.269960 kernel: ACPI: Reserving SSDT table memory at [mem 0x7d9ef6c0-0x7d9eff39] Jan 13 20:36:01.269974 kernel: ACPI: Reserving APIC table memory at [mem 0x7d9ef5d0-0x7d9ef645] Jan 13 20:36:01.269988 kernel: ACPI: Reserving SRAT table memory at [mem 0x7d9ef530-0x7d9ef5cf] Jan 13 20:36:01.270005 kernel: ACPI: Reserving SLIT table memory at [mem 0x7d9ef4c0-0x7d9ef52b] Jan 13 20:36:01.270104 kernel: ACPI: Reserving WAET table memory at [mem 0x7d9ef490-0x7d9ef4b7] Jan 13 20:36:01.270121 kernel: ACPI: Reserving HPET table memory at [mem 0xc9000-0xc9037] Jan 13 20:36:01.270135 kernel: ACPI: Reserving SSDT table memory at [mem 0xc9040-0xc90ba] Jan 13 20:36:01.270148 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Jan 13 20:36:01.270162 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Jan 13 20:36:01.270176 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x7fffffff] Jan 13 20:36:01.270190 kernel: NUMA: Initialized distance table, cnt=1 Jan 13 20:36:01.270203 kernel: NODE_DATA(0) allocated [mem 0x7d9e3000-0x7d9e8fff] Jan 13 20:36:01.270221 kernel: Zone ranges: Jan 13 20:36:01.270235 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 13 20:36:01.270249 kernel: DMA32 [mem 0x0000000001000000-0x000000007d9e9fff] Jan 13 20:36:01.270263 kernel: Normal empty Jan 13 20:36:01.270277 kernel: Movable zone start for each node Jan 13 20:36:01.270550 kernel: Early memory node ranges Jan 13 20:36:01.270603 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Jan 13 20:36:01.270616 kernel: node 0: [mem 0x0000000000100000-0x000000007d9e9fff] Jan 13 20:36:01.270628 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007d9e9fff] Jan 13 20:36:01.270641 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 13 20:36:01.270659 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Jan 13 20:36:01.270672 kernel: On node 0, zone DMA32: 9750 pages in unavailable ranges Jan 13 20:36:01.270686 kernel: ACPI: PM-Timer IO Port: 0xb008 Jan 13 20:36:01.270699 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Jan 13 20:36:01.270713 kernel: IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 Jan 13 20:36:01.270727 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Jan 13 20:36:01.270741 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 13 20:36:01.270755 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Jan 13 20:36:01.270769 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Jan 13 20:36:01.270787 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 13 20:36:01.270801 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Jan 13 20:36:01.270815 kernel: TSC deadline timer available Jan 13 20:36:01.270829 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Jan 13 20:36:01.270843 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Jan 13 20:36:01.270857 kernel: [mem 0x80000000-0xdfffffff] available for PCI devices Jan 13 20:36:01.270871 kernel: Booting paravirtualized kernel on KVM Jan 13 20:36:01.270886 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 13 20:36:01.270900 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Jan 13 20:36:01.270963 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u1048576 Jan 13 20:36:01.270977 kernel: pcpu-alloc: s197032 r8192 d32344 u1048576 alloc=1*2097152 Jan 13 20:36:01.270991 kernel: pcpu-alloc: [0] 0 1 Jan 13 20:36:01.271003 kernel: kvm-guest: PV spinlocks enabled Jan 13 20:36:01.271016 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jan 13 20:36:01.271033 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 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=8a11404d893165624d9716a125d997be53e2d6cdb0c50a945acda5b62a14eda5 Jan 13 20:36:01.271048 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jan 13 20:36:01.271069 kernel: random: crng init done Jan 13 20:36:01.271087 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 13 20:36:01.271101 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 13 20:36:01.271115 kernel: Fallback order for Node 0: 0 Jan 13 20:36:01.271128 kernel: Built 1 zonelists, mobility grouping on. Total pages: 506242 Jan 13 20:36:01.271143 kernel: Policy zone: DMA32 Jan 13 20:36:01.271157 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 13 20:36:01.271172 kernel: Memory: 1930296K/2057760K available (14336K kernel code, 2299K rwdata, 22800K rodata, 43320K init, 1756K bss, 127204K reserved, 0K cma-reserved) Jan 13 20:36:01.271186 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jan 13 20:36:01.271200 kernel: Kernel/User page tables isolation: enabled Jan 13 20:36:01.271218 kernel: ftrace: allocating 37890 entries in 149 pages Jan 13 20:36:01.271231 kernel: ftrace: allocated 149 pages with 4 groups Jan 13 20:36:01.271245 kernel: Dynamic Preempt: voluntary Jan 13 20:36:01.271259 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 13 20:36:01.271273 kernel: rcu: RCU event tracing is enabled. Jan 13 20:36:01.271287 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jan 13 20:36:01.271301 kernel: Trampoline variant of Tasks RCU enabled. Jan 13 20:36:01.271314 kernel: Rude variant of Tasks RCU enabled. Jan 13 20:36:01.271328 kernel: Tracing variant of Tasks RCU enabled. Jan 13 20:36:01.271344 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 13 20:36:01.271822 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jan 13 20:36:01.271836 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Jan 13 20:36:01.271849 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 13 20:36:01.271863 kernel: Console: colour VGA+ 80x25 Jan 13 20:36:01.271877 kernel: printk: console [ttyS0] enabled Jan 13 20:36:01.271891 kernel: ACPI: Core revision 20230628 Jan 13 20:36:01.271905 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 30580167144 ns Jan 13 20:36:01.271920 kernel: APIC: Switch to symmetric I/O mode setup Jan 13 20:36:01.271939 kernel: x2apic enabled Jan 13 20:36:01.271954 kernel: APIC: Switched APIC routing to: physical x2apic Jan 13 20:36:01.271980 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x240933eba6e, max_idle_ns: 440795246008 ns Jan 13 20:36:01.271998 kernel: Calibrating delay loop (skipped) preset value.. 4999.98 BogoMIPS (lpj=2499994) Jan 13 20:36:01.272013 kernel: Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 Jan 13 20:36:01.272028 kernel: Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4 Jan 13 20:36:01.272043 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 13 20:36:01.272058 kernel: Spectre V2 : Mitigation: Retpolines Jan 13 20:36:01.272072 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Jan 13 20:36:01.272087 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Jan 13 20:36:01.272101 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Jan 13 20:36:01.272116 kernel: RETBleed: Vulnerable Jan 13 20:36:01.272133 kernel: Speculative Store Bypass: Vulnerable Jan 13 20:36:01.272149 kernel: MDS: Vulnerable: Clear CPU buffers attempted, no microcode Jan 13 20:36:01.272163 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 13 20:36:01.272180 kernel: GDS: Unknown: Dependent on hypervisor status Jan 13 20:36:01.272195 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 13 20:36:01.272209 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 13 20:36:01.272224 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 13 20:36:01.272243 kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' Jan 13 20:36:01.272258 kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' Jan 13 20:36:01.272274 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Jan 13 20:36:01.272291 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Jan 13 20:36:01.272306 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Jan 13 20:36:01.272322 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' Jan 13 20:36:01.272339 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 13 20:36:01.272356 kernel: x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 Jan 13 20:36:01.272372 kernel: x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 Jan 13 20:36:01.272389 kernel: x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 Jan 13 20:36:01.272406 kernel: x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 Jan 13 20:36:01.272428 kernel: x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 Jan 13 20:36:01.272445 kernel: x86/fpu: xstate_offset[9]: 2560, xstate_sizes[9]: 8 Jan 13 20:36:01.272459 kernel: x86/fpu: Enabled xstate features 0x2ff, context size is 2568 bytes, using 'compacted' format. Jan 13 20:36:01.272475 kernel: Freeing SMP alternatives memory: 32K Jan 13 20:36:01.272492 kernel: pid_max: default: 32768 minimum: 301 Jan 13 20:36:01.272508 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Jan 13 20:36:01.272523 kernel: landlock: Up and running. Jan 13 20:36:01.272541 kernel: SELinux: Initializing. Jan 13 20:36:01.272558 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jan 13 20:36:01.272598 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Jan 13 20:36:01.272611 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8259CL CPU @ 2.50GHz (family: 0x6, model: 0x55, stepping: 0x7) Jan 13 20:36:01.272628 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 13 20:36:01.272641 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 13 20:36:01.272655 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 13 20:36:01.272667 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Jan 13 20:36:01.272680 kernel: signal: max sigframe size: 3632 Jan 13 20:36:01.272693 kernel: rcu: Hierarchical SRCU implementation. Jan 13 20:36:01.272709 kernel: rcu: Max phase no-delay instances is 400. Jan 13 20:36:01.272724 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 13 20:36:01.272738 kernel: smp: Bringing up secondary CPUs ... Jan 13 20:36:01.272758 kernel: smpboot: x86: Booting SMP configuration: Jan 13 20:36:01.272772 kernel: .... node #0, CPUs: #1 Jan 13 20:36:01.272786 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. Jan 13 20:36:01.272801 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. Jan 13 20:36:01.272814 kernel: smp: Brought up 1 node, 2 CPUs Jan 13 20:36:01.272828 kernel: smpboot: Max logical packages: 1 Jan 13 20:36:01.272842 kernel: smpboot: Total of 2 processors activated (9999.97 BogoMIPS) Jan 13 20:36:01.272856 kernel: devtmpfs: initialized Jan 13 20:36:01.272869 kernel: x86/mm: Memory block size: 128MB Jan 13 20:36:01.272887 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 13 20:36:01.272901 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jan 13 20:36:01.272915 kernel: pinctrl core: initialized pinctrl subsystem Jan 13 20:36:01.272929 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 13 20:36:01.272942 kernel: audit: initializing netlink subsys (disabled) Jan 13 20:36:01.272955 kernel: audit: type=2000 audit(1736800559.616:1): state=initialized audit_enabled=0 res=1 Jan 13 20:36:01.272969 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 13 20:36:01.272983 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 13 20:36:01.272996 kernel: cpuidle: using governor menu Jan 13 20:36:01.273014 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 13 20:36:01.273027 kernel: dca service started, version 1.12.1 Jan 13 20:36:01.273041 kernel: PCI: Using configuration type 1 for base access Jan 13 20:36:01.273055 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 13 20:36:01.273069 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 13 20:36:01.273135 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 13 20:36:01.273149 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 13 20:36:01.273163 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 13 20:36:01.273177 kernel: ACPI: Added _OSI(Module Device) Jan 13 20:36:01.273193 kernel: ACPI: Added _OSI(Processor Device) Jan 13 20:36:01.273207 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jan 13 20:36:01.273221 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 13 20:36:01.273238 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded Jan 13 20:36:01.273254 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Jan 13 20:36:01.273268 kernel: ACPI: Interpreter enabled Jan 13 20:36:01.273282 kernel: ACPI: PM: (supports S0 S5) Jan 13 20:36:01.273296 kernel: ACPI: Using IOAPIC for interrupt routing Jan 13 20:36:01.273311 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 13 20:36:01.273329 kernel: PCI: Using E820 reservations for host bridge windows Jan 13 20:36:01.273342 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F Jan 13 20:36:01.273357 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jan 13 20:36:01.273677 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Jan 13 20:36:01.273840 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Jan 13 20:36:01.273975 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Jan 13 20:36:01.273995 kernel: acpiphp: Slot [3] registered Jan 13 20:36:01.274016 kernel: acpiphp: Slot [4] registered Jan 13 20:36:01.274032 kernel: acpiphp: Slot [5] registered Jan 13 20:36:01.274048 kernel: acpiphp: Slot [6] registered Jan 13 20:36:01.274063 kernel: acpiphp: Slot [7] registered Jan 13 20:36:01.274079 kernel: acpiphp: Slot [8] registered Jan 13 20:36:01.274094 kernel: acpiphp: Slot [9] registered Jan 13 20:36:01.274109 kernel: acpiphp: Slot [10] registered Jan 13 20:36:01.274125 kernel: acpiphp: Slot [11] registered Jan 13 20:36:01.274140 kernel: acpiphp: Slot [12] registered Jan 13 20:36:01.274159 kernel: acpiphp: Slot [13] registered Jan 13 20:36:01.274175 kernel: acpiphp: Slot [14] registered Jan 13 20:36:01.274190 kernel: acpiphp: Slot [15] registered Jan 13 20:36:01.274206 kernel: acpiphp: Slot [16] registered Jan 13 20:36:01.274222 kernel: acpiphp: Slot [17] registered Jan 13 20:36:01.274237 kernel: acpiphp: Slot [18] registered Jan 13 20:36:01.274253 kernel: acpiphp: Slot [19] registered Jan 13 20:36:01.274268 kernel: acpiphp: Slot [20] registered Jan 13 20:36:01.274283 kernel: acpiphp: Slot [21] registered Jan 13 20:36:01.274298 kernel: acpiphp: Slot [22] registered Jan 13 20:36:01.274317 kernel: acpiphp: Slot [23] registered Jan 13 20:36:01.274332 kernel: acpiphp: Slot [24] registered Jan 13 20:36:01.274348 kernel: acpiphp: Slot [25] registered Jan 13 20:36:01.274363 kernel: acpiphp: Slot [26] registered Jan 13 20:36:01.274379 kernel: acpiphp: Slot [27] registered Jan 13 20:36:01.274394 kernel: acpiphp: Slot [28] registered Jan 13 20:36:01.274410 kernel: acpiphp: Slot [29] registered Jan 13 20:36:01.274425 kernel: acpiphp: Slot [30] registered Jan 13 20:36:01.274440 kernel: acpiphp: Slot [31] registered Jan 13 20:36:01.274459 kernel: PCI host bridge to bus 0000:00 Jan 13 20:36:01.274634 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Jan 13 20:36:01.274766 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Jan 13 20:36:01.274891 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Jan 13 20:36:01.275083 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Jan 13 20:36:01.275204 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 13 20:36:01.275361 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Jan 13 20:36:01.275506 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Jan 13 20:36:01.275657 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x000000 Jan 13 20:36:01.275855 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI Jan 13 20:36:01.275989 kernel: pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB Jan 13 20:36:01.276118 kernel: pci 0000:00:01.3: PIIX4 devres E PIO at fff0-ffff Jan 13 20:36:01.276244 kernel: pci 0000:00:01.3: PIIX4 devres F MMIO at ffc00000-ffffffff Jan 13 20:36:01.277872 kernel: pci 0000:00:01.3: PIIX4 devres G PIO at fff0-ffff Jan 13 20:36:01.278154 kernel: pci 0000:00:01.3: PIIX4 devres H MMIO at ffc00000-ffffffff Jan 13 20:36:01.278315 kernel: pci 0000:00:01.3: PIIX4 devres I PIO at fff0-ffff Jan 13 20:36:01.278456 kernel: pci 0000:00:01.3: PIIX4 devres J PIO at fff0-ffff Jan 13 20:36:01.278622 kernel: pci 0000:00:01.3: quirk_piix4_acpi+0x0/0x180 took 28320 usecs Jan 13 20:36:01.278764 kernel: pci 0000:00:03.0: [1d0f:1111] type 00 class 0x030000 Jan 13 20:36:01.278894 kernel: pci 0000:00:03.0: reg 0x10: [mem 0xfe400000-0xfe7fffff pref] Jan 13 20:36:01.279029 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Jan 13 20:36:01.279171 kernel: pci 0000:00:03.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Jan 13 20:36:01.279315 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Jan 13 20:36:01.279446 kernel: pci 0000:00:04.0: reg 0x10: [mem 0xfebf0000-0xfebf3fff] Jan 13 20:36:01.279606 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Jan 13 20:36:01.279737 kernel: pci 0000:00:05.0: reg 0x10: [mem 0xfebf4000-0xfebf7fff] Jan 13 20:36:01.279755 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Jan 13 20:36:01.279774 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Jan 13 20:36:01.279788 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Jan 13 20:36:01.279802 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Jan 13 20:36:01.279816 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Jan 13 20:36:01.279830 kernel: iommu: Default domain type: Translated Jan 13 20:36:01.279843 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 13 20:36:01.279857 kernel: PCI: Using ACPI for IRQ routing Jan 13 20:36:01.279871 kernel: PCI: pci_cache_line_size set to 64 bytes Jan 13 20:36:01.279885 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Jan 13 20:36:01.279897 kernel: e820: reserve RAM buffer [mem 0x7d9ea000-0x7fffffff] Jan 13 20:36:01.280030 kernel: pci 0000:00:03.0: vgaarb: setting as boot VGA device Jan 13 20:36:01.280164 kernel: pci 0000:00:03.0: vgaarb: bridge control possible Jan 13 20:36:01.280299 kernel: pci 0000:00:03.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Jan 13 20:36:01.280318 kernel: vgaarb: loaded Jan 13 20:36:01.280334 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 Jan 13 20:36:01.280349 kernel: hpet0: 8 comparators, 32-bit 62.500000 MHz counter Jan 13 20:36:01.280365 kernel: clocksource: Switched to clocksource kvm-clock Jan 13 20:36:01.280381 kernel: VFS: Disk quotas dquot_6.6.0 Jan 13 20:36:01.280401 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 13 20:36:01.280417 kernel: pnp: PnP ACPI init Jan 13 20:36:01.280433 kernel: pnp: PnP ACPI: found 5 devices Jan 13 20:36:01.280448 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 13 20:36:01.280477 kernel: NET: Registered PF_INET protocol family Jan 13 20:36:01.280492 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Jan 13 20:36:01.280508 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Jan 13 20:36:01.280524 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 13 20:36:01.280540 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 13 20:36:01.280559 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Jan 13 20:36:01.280608 kernel: TCP: Hash tables configured (established 16384 bind 16384) Jan 13 20:36:01.280622 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Jan 13 20:36:01.280635 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Jan 13 20:36:01.280648 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 13 20:36:01.280662 kernel: NET: Registered PF_XDP protocol family Jan 13 20:36:01.280811 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Jan 13 20:36:01.280941 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Jan 13 20:36:01.281064 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Jan 13 20:36:01.281247 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Jan 13 20:36:01.281383 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Jan 13 20:36:01.281404 kernel: PCI: CLS 0 bytes, default 64 Jan 13 20:36:01.281420 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 13 20:36:01.281436 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x240933eba6e, max_idle_ns: 440795246008 ns Jan 13 20:36:01.281451 kernel: clocksource: Switched to clocksource tsc Jan 13 20:36:01.281463 kernel: Initialise system trusted keyrings Jan 13 20:36:01.281483 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Jan 13 20:36:01.281497 kernel: Key type asymmetric registered Jan 13 20:36:01.281512 kernel: Asymmetric key parser 'x509' registered Jan 13 20:36:01.281526 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Jan 13 20:36:01.281540 kernel: io scheduler mq-deadline registered Jan 13 20:36:01.281557 kernel: io scheduler kyber registered Jan 13 20:36:01.281613 kernel: io scheduler bfq registered Jan 13 20:36:01.281626 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 13 20:36:01.281639 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 13 20:36:01.281652 kernel: 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 13 20:36:01.281669 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Jan 13 20:36:01.281683 kernel: i8042: Warning: Keylock active Jan 13 20:36:01.281697 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Jan 13 20:36:01.282550 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Jan 13 20:36:01.282762 kernel: rtc_cmos 00:00: RTC can wake from S4 Jan 13 20:36:01.282901 kernel: rtc_cmos 00:00: registered as rtc0 Jan 13 20:36:01.283040 kernel: rtc_cmos 00:00: setting system clock to 2025-01-13T20:36:00 UTC (1736800560) Jan 13 20:36:01.283197 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram Jan 13 20:36:01.283219 kernel: intel_pstate: CPU model not supported Jan 13 20:36:01.283235 kernel: NET: Registered PF_INET6 protocol family Jan 13 20:36:01.283252 kernel: Segment Routing with IPv6 Jan 13 20:36:01.283268 kernel: In-situ OAM (IOAM) with IPv6 Jan 13 20:36:01.283285 kernel: NET: Registered PF_PACKET protocol family Jan 13 20:36:01.283301 kernel: Key type dns_resolver registered Jan 13 20:36:01.283317 kernel: IPI shorthand broadcast: enabled Jan 13 20:36:01.283333 kernel: sched_clock: Marking stable (895003392, 240290359)->(1285275560, -149981809) Jan 13 20:36:01.283354 kernel: registered taskstats version 1 Jan 13 20:36:01.283370 kernel: Loading compiled-in X.509 certificates Jan 13 20:36:01.283386 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.71-flatcar: ede78b3e719729f95eaaf7cb6a5289b567f6ee3e' Jan 13 20:36:01.283403 kernel: Key type .fscrypt registered Jan 13 20:36:01.283419 kernel: Key type fscrypt-provisioning registered Jan 13 20:36:01.283435 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 13 20:36:01.283452 kernel: ima: Allocated hash algorithm: sha1 Jan 13 20:36:01.283468 kernel: ima: No architecture policies found Jan 13 20:36:01.283484 kernel: clk: Disabling unused clocks Jan 13 20:36:01.283505 kernel: Freeing unused kernel image (initmem) memory: 43320K Jan 13 20:36:01.283522 kernel: Write protecting the kernel read-only data: 38912k Jan 13 20:36:01.283538 kernel: Freeing unused kernel image (rodata/data gap) memory: 1776K Jan 13 20:36:01.283554 kernel: Run /init as init process Jan 13 20:36:01.283605 kernel: with arguments: Jan 13 20:36:01.283687 kernel: /init Jan 13 20:36:01.283703 kernel: with environment: Jan 13 20:36:01.283714 kernel: HOME=/ Jan 13 20:36:01.283727 kernel: TERM=linux Jan 13 20:36:01.283745 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jan 13 20:36:01.283784 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) Jan 13 20:36:01.283802 systemd[1]: Detected virtualization amazon. Jan 13 20:36:01.283816 systemd[1]: Detected architecture x86-64. Jan 13 20:36:01.283830 systemd[1]: Running in initrd. Jan 13 20:36:01.283847 systemd[1]: No hostname configured, using default hostname. Jan 13 20:36:01.283863 systemd[1]: Hostname set to . Jan 13 20:36:01.283886 systemd[1]: Initializing machine ID from VM UUID. Jan 13 20:36:01.283901 systemd[1]: Queued start job for default target initrd.target. Jan 13 20:36:01.283915 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 13 20:36:01.283930 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 13 20:36:01.283947 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 13 20:36:01.283963 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 13 20:36:01.283980 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 13 20:36:01.283997 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 13 20:36:01.284019 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jan 13 20:36:01.284036 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 13 20:36:01.284053 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 13 20:36:01.284070 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 13 20:36:01.284086 systemd[1]: Reached target paths.target - Path Units. Jan 13 20:36:01.284103 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Jan 13 20:36:01.284119 systemd[1]: Reached target slices.target - Slice Units. Jan 13 20:36:01.284138 systemd[1]: Reached target swap.target - Swaps. Jan 13 20:36:01.284155 systemd[1]: Reached target timers.target - Timer Units. Jan 13 20:36:01.284172 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 13 20:36:01.284188 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 13 20:36:01.284204 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 13 20:36:01.284221 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jan 13 20:36:01.284236 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 13 20:36:01.284253 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 13 20:36:01.284269 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 13 20:36:01.284289 systemd[1]: Reached target sockets.target - Socket Units. Jan 13 20:36:01.284305 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 13 20:36:01.284322 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 13 20:36:01.284336 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 13 20:36:01.284358 systemd[1]: Starting systemd-fsck-usr.service... Jan 13 20:36:01.284379 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 13 20:36:01.284396 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 13 20:36:01.284412 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 13 20:36:01.284429 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 13 20:36:01.284483 systemd-journald[179]: Collecting audit messages is disabled. Jan 13 20:36:01.284524 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 13 20:36:01.284539 systemd[1]: Finished systemd-fsck-usr.service. Jan 13 20:36:01.284557 systemd-journald[179]: Journal started Jan 13 20:36:01.284629 systemd-journald[179]: Runtime Journal (/run/log/journal/ec2d14d835b5643e1a40040ff5e59abd) is 4.8M, max 38.5M, 33.7M free. Jan 13 20:36:01.297227 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 13 20:36:01.299655 systemd[1]: Started systemd-journald.service - Journal Service. Jan 13 20:36:01.310664 systemd-modules-load[180]: Inserted module 'overlay' Jan 13 20:36:01.450483 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 13 20:36:01.450527 kernel: Bridge firewalling registered Jan 13 20:36:01.314027 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 13 20:36:01.355387 systemd-modules-load[180]: Inserted module 'br_netfilter' Jan 13 20:36:01.465879 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 13 20:36:01.470318 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 13 20:36:01.480280 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 13 20:36:01.495102 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 13 20:36:01.512943 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 13 20:36:01.515204 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 13 20:36:01.517627 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 13 20:36:01.537416 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 13 20:36:01.550797 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 13 20:36:01.554223 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 13 20:36:01.560237 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 13 20:36:01.574897 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 13 20:36:01.640175 dracut-cmdline[215]: dracut-dracut-053 Jan 13 20:36:01.647110 dracut-cmdline[215]: 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 flatcar.first_boot=detected flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=8a11404d893165624d9716a125d997be53e2d6cdb0c50a945acda5b62a14eda5 Jan 13 20:36:01.681842 systemd-resolved[209]: Positive Trust Anchors: Jan 13 20:36:01.683855 systemd-resolved[209]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 13 20:36:01.683921 systemd-resolved[209]: 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 Jan 13 20:36:01.690253 systemd-resolved[209]: Defaulting to hostname 'linux'. Jan 13 20:36:01.695906 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 13 20:36:01.700385 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 13 20:36:02.001599 kernel: SCSI subsystem initialized Jan 13 20:36:02.016604 kernel: Loading iSCSI transport class v2.0-870. Jan 13 20:36:02.037597 kernel: iscsi: registered transport (tcp) Jan 13 20:36:02.086928 kernel: iscsi: registered transport (qla4xxx) Jan 13 20:36:02.087039 kernel: QLogic iSCSI HBA Driver Jan 13 20:36:02.433194 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 13 20:36:02.442919 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 13 20:36:02.484622 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 13 20:36:02.485044 kernel: device-mapper: uevent: version 1.0.3 Jan 13 20:36:02.486591 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jan 13 20:36:02.536629 kernel: raid6: avx512x4 gen() 12506 MB/s Jan 13 20:36:02.553626 kernel: raid6: avx512x2 gen() 13281 MB/s Jan 13 20:36:02.570627 kernel: raid6: avx512x1 gen() 6229 MB/s Jan 13 20:36:02.587631 kernel: raid6: avx2x4 gen() 4885 MB/s Jan 13 20:36:02.608946 kernel: raid6: avx2x2 gen() 2750 MB/s Jan 13 20:36:02.625626 kernel: raid6: avx2x1 gen() 4724 MB/s Jan 13 20:36:02.625712 kernel: raid6: using algorithm avx512x2 gen() 13281 MB/s Jan 13 20:36:02.649559 kernel: raid6: .... xor() 4535 MB/s, rmw enabled Jan 13 20:36:02.649681 kernel: raid6: using avx512x2 recovery algorithm Jan 13 20:36:02.694604 kernel: xor: automatically using best checksumming function avx Jan 13 20:36:02.945597 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 13 20:36:02.958423 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 13 20:36:02.967808 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 13 20:36:03.008254 systemd-udevd[399]: Using default interface naming scheme 'v255'. Jan 13 20:36:03.015719 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 13 20:36:03.026767 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 13 20:36:03.072635 dracut-pre-trigger[406]: rd.md=0: removing MD RAID activation Jan 13 20:36:03.121106 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 13 20:36:03.130784 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 13 20:36:03.216977 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 13 20:36:03.227493 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 13 20:36:03.271801 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 13 20:36:03.275895 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 13 20:36:03.279643 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 13 20:36:03.282467 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 13 20:36:03.292938 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 13 20:36:03.331142 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 13 20:36:03.350685 kernel: ena 0000:00:05.0: ENA device version: 0.10 Jan 13 20:36:03.362826 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Jan 13 20:36:03.363144 kernel: ena 0000:00:05.0: LLQ is not supported Fallback to host mode policy. Jan 13 20:36:03.363554 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem febf4000, mac addr 06:ea:14:3f:f4:53 Jan 13 20:36:03.403071 kernel: cryptd: max_cpu_qlen set to 1000 Jan 13 20:36:03.408335 (udev-worker)[461]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:03.432159 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 13 20:36:03.433607 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 13 20:36:03.437012 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 13 20:36:03.440378 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 13 20:36:03.440707 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 13 20:36:03.445870 kernel: AVX2 version of gcm_enc/dec engaged. Jan 13 20:36:03.445989 kernel: AES CTR mode by8 optimization enabled Jan 13 20:36:03.446150 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 13 20:36:03.456260 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 13 20:36:03.489603 kernel: nvme nvme0: pci function 0000:00:04.0 Jan 13 20:36:03.489891 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Jan 13 20:36:03.504626 kernel: nvme nvme0: 2/0/0 default/read/poll queues Jan 13 20:36:03.515601 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jan 13 20:36:03.515665 kernel: GPT:9289727 != 16777215 Jan 13 20:36:03.515685 kernel: GPT:Alternate GPT header not at the end of the disk. Jan 13 20:36:03.515703 kernel: GPT:9289727 != 16777215 Jan 13 20:36:03.515730 kernel: GPT: Use GNU Parted to correct GPT errors. Jan 13 20:36:03.515749 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jan 13 20:36:03.620715 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 13 20:36:03.627777 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 13 20:36:03.655868 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (451) Jan 13 20:36:03.664593 kernel: BTRFS: device fsid 7f507843-6957-466b-8fb7-5bee228b170a devid 1 transid 44 /dev/nvme0n1p3 scanned by (udev-worker) (446) Jan 13 20:36:03.684877 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 13 20:36:03.716012 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Jan 13 20:36:03.733185 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Jan 13 20:36:03.750078 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Jan 13 20:36:03.756067 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Jan 13 20:36:03.758801 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Jan 13 20:36:03.768720 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 13 20:36:03.776650 disk-uuid[625]: Primary Header is updated. Jan 13 20:36:03.776650 disk-uuid[625]: Secondary Entries is updated. Jan 13 20:36:03.776650 disk-uuid[625]: Secondary Header is updated. Jan 13 20:36:03.783301 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jan 13 20:36:04.795560 disk-uuid[626]: The operation has completed successfully. Jan 13 20:36:04.797183 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Jan 13 20:36:04.960824 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 13 20:36:04.960963 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 13 20:36:04.985785 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 13 20:36:05.007644 sh[886]: Success Jan 13 20:36:05.027589 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Jan 13 20:36:05.163541 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 13 20:36:05.182746 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 13 20:36:05.185228 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 13 20:36:05.239952 kernel: BTRFS info (device dm-0): first mount of filesystem 7f507843-6957-466b-8fb7-5bee228b170a Jan 13 20:36:05.240025 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 13 20:36:05.240045 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jan 13 20:36:05.241922 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 13 20:36:05.241973 kernel: BTRFS info (device dm-0): using free space tree Jan 13 20:36:05.319595 kernel: BTRFS info (device dm-0): enabling ssd optimizations Jan 13 20:36:05.343825 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 13 20:36:05.344832 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 13 20:36:05.356798 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 13 20:36:05.359213 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 13 20:36:05.395394 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem de2056f8-fbde-4b85-b887-0a28f289d968 Jan 13 20:36:05.395473 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Jan 13 20:36:05.395496 kernel: BTRFS info (device nvme0n1p6): using free space tree Jan 13 20:36:05.402598 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jan 13 20:36:05.418324 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem de2056f8-fbde-4b85-b887-0a28f289d968 Jan 13 20:36:05.417666 systemd[1]: mnt-oem.mount: Deactivated successfully. Jan 13 20:36:05.428215 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 13 20:36:05.438987 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 13 20:36:05.512137 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 13 20:36:05.519947 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 13 20:36:05.567770 systemd-networkd[1078]: lo: Link UP Jan 13 20:36:05.567783 systemd-networkd[1078]: lo: Gained carrier Jan 13 20:36:05.570211 systemd-networkd[1078]: Enumeration completed Jan 13 20:36:05.570712 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 13 20:36:05.571374 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 13 20:36:05.571379 systemd-networkd[1078]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 13 20:36:05.574463 systemd[1]: Reached target network.target - Network. Jan 13 20:36:05.589095 systemd-networkd[1078]: eth0: Link UP Jan 13 20:36:05.589099 systemd-networkd[1078]: eth0: Gained carrier Jan 13 20:36:05.589113 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 13 20:36:05.605714 systemd-networkd[1078]: eth0: DHCPv4 address 172.31.27.123/20, gateway 172.31.16.1 acquired from 172.31.16.1 Jan 13 20:36:05.863695 ignition[1013]: Ignition 2.20.0 Jan 13 20:36:05.863711 ignition[1013]: Stage: fetch-offline Jan 13 20:36:05.863950 ignition[1013]: no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:05.863963 ignition[1013]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:05.865981 ignition[1013]: Ignition finished successfully Jan 13 20:36:05.871029 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 13 20:36:05.895937 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 13 20:36:05.915977 ignition[1088]: Ignition 2.20.0 Jan 13 20:36:05.915993 ignition[1088]: Stage: fetch Jan 13 20:36:05.916419 ignition[1088]: no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:05.916432 ignition[1088]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:05.916562 ignition[1088]: PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:05.940251 ignition[1088]: PUT result: OK Jan 13 20:36:05.944847 ignition[1088]: parsed url from cmdline: "" Jan 13 20:36:05.944859 ignition[1088]: no config URL provided Jan 13 20:36:05.944868 ignition[1088]: reading system config file "/usr/lib/ignition/user.ign" Jan 13 20:36:05.944883 ignition[1088]: no config at "/usr/lib/ignition/user.ign" Jan 13 20:36:05.944945 ignition[1088]: PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:05.946299 ignition[1088]: PUT result: OK Jan 13 20:36:05.946360 ignition[1088]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Jan 13 20:36:05.953339 ignition[1088]: GET result: OK Jan 13 20:36:05.953504 ignition[1088]: parsing config with SHA512: d3c64708c55bd4fd6c16662ecdd616b5bd7a8b8a1c68de27b3ef86e5ca539b2113868986ec3634dc18014ae5e02588bc4f7415a61237297f544b3dc8fc711754 Jan 13 20:36:05.957720 unknown[1088]: fetched base config from "system" Jan 13 20:36:05.957735 unknown[1088]: fetched base config from "system" Jan 13 20:36:05.958121 ignition[1088]: fetch: fetch complete Jan 13 20:36:05.957744 unknown[1088]: fetched user config from "aws" Jan 13 20:36:05.958131 ignition[1088]: fetch: fetch passed Jan 13 20:36:05.975554 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 13 20:36:05.958195 ignition[1088]: Ignition finished successfully Jan 13 20:36:05.981149 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 13 20:36:06.004201 ignition[1094]: Ignition 2.20.0 Jan 13 20:36:06.004217 ignition[1094]: Stage: kargs Jan 13 20:36:06.004689 ignition[1094]: no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:06.004702 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:06.004916 ignition[1094]: PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:06.007105 ignition[1094]: PUT result: OK Jan 13 20:36:06.013555 ignition[1094]: kargs: kargs passed Jan 13 20:36:06.013643 ignition[1094]: Ignition finished successfully Jan 13 20:36:06.015621 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 13 20:36:06.025793 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 13 20:36:06.043422 ignition[1100]: Ignition 2.20.0 Jan 13 20:36:06.043435 ignition[1100]: Stage: disks Jan 13 20:36:06.043782 ignition[1100]: no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:06.043791 ignition[1100]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:06.044155 ignition[1100]: PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:06.046245 ignition[1100]: PUT result: OK Jan 13 20:36:06.059548 ignition[1100]: disks: disks passed Jan 13 20:36:06.059640 ignition[1100]: Ignition finished successfully Jan 13 20:36:06.061671 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 13 20:36:06.065381 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 13 20:36:06.067021 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 13 20:36:06.071079 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 13 20:36:06.074034 systemd[1]: Reached target sysinit.target - System Initialization. Jan 13 20:36:06.076367 systemd[1]: Reached target basic.target - Basic System. Jan 13 20:36:06.082772 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 13 20:36:06.133636 systemd-fsck[1109]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jan 13 20:36:06.144801 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 13 20:36:06.160795 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 13 20:36:06.304591 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 59ba8ffc-e6b0-4bb4-a36e-13a47bd6ad99 r/w with ordered data mode. Quota mode: none. Jan 13 20:36:06.305536 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 13 20:36:06.306395 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 13 20:36:06.318820 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 13 20:36:06.329723 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 13 20:36:06.330365 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jan 13 20:36:06.330426 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 13 20:36:06.330458 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 13 20:36:06.341126 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 13 20:36:06.349781 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 13 20:36:06.360598 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1128) Jan 13 20:36:06.364170 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem de2056f8-fbde-4b85-b887-0a28f289d968 Jan 13 20:36:06.364232 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Jan 13 20:36:06.364253 kernel: BTRFS info (device nvme0n1p6): using free space tree Jan 13 20:36:06.379593 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jan 13 20:36:06.380947 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 13 20:36:06.662829 initrd-setup-root[1152]: cut: /sysroot/etc/passwd: No such file or directory Jan 13 20:36:06.685228 initrd-setup-root[1159]: cut: /sysroot/etc/group: No such file or directory Jan 13 20:36:06.701157 initrd-setup-root[1166]: cut: /sysroot/etc/shadow: No such file or directory Jan 13 20:36:06.711657 initrd-setup-root[1173]: cut: /sysroot/etc/gshadow: No such file or directory Jan 13 20:36:06.811010 systemd-networkd[1078]: eth0: Gained IPv6LL Jan 13 20:36:06.913184 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 13 20:36:06.924911 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 13 20:36:06.939807 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 13 20:36:06.958608 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem de2056f8-fbde-4b85-b887-0a28f289d968 Jan 13 20:36:06.958934 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 13 20:36:06.997249 ignition[1240]: INFO : Ignition 2.20.0 Jan 13 20:36:06.997249 ignition[1240]: INFO : Stage: mount Jan 13 20:36:06.997249 ignition[1240]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:06.997249 ignition[1240]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:06.997249 ignition[1240]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:07.004485 ignition[1240]: INFO : PUT result: OK Jan 13 20:36:07.007769 ignition[1240]: INFO : mount: mount passed Jan 13 20:36:07.007769 ignition[1240]: INFO : Ignition finished successfully Jan 13 20:36:07.009076 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 13 20:36:07.014390 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 13 20:36:07.025759 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 13 20:36:07.321906 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 13 20:36:07.350696 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1253) Jan 13 20:36:07.352774 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem de2056f8-fbde-4b85-b887-0a28f289d968 Jan 13 20:36:07.352838 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Jan 13 20:36:07.352858 kernel: BTRFS info (device nvme0n1p6): using free space tree Jan 13 20:36:07.364598 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Jan 13 20:36:07.369151 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 13 20:36:07.395565 ignition[1270]: INFO : Ignition 2.20.0 Jan 13 20:36:07.395565 ignition[1270]: INFO : Stage: files Jan 13 20:36:07.397841 ignition[1270]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:07.397841 ignition[1270]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:07.397841 ignition[1270]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:07.402982 ignition[1270]: INFO : PUT result: OK Jan 13 20:36:07.405544 ignition[1270]: DEBUG : files: compiled without relabeling support, skipping Jan 13 20:36:07.408394 ignition[1270]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 13 20:36:07.408394 ignition[1270]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 13 20:36:07.417132 ignition[1270]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 13 20:36:07.418824 ignition[1270]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 13 20:36:07.420750 unknown[1270]: wrote ssh authorized keys file for user: core Jan 13 20:36:07.422153 ignition[1270]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 13 20:36:07.427090 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jan 13 20:36:07.429376 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jan 13 20:36:07.433259 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 13 20:36:07.433259 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 13 20:36:07.433259 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 13 20:36:07.433259 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 13 20:36:07.433259 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 13 20:36:07.451205 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 Jan 13 20:36:07.939053 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jan 13 20:36:08.310907 ignition[1270]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" Jan 13 20:36:08.315138 ignition[1270]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 13 20:36:08.315138 ignition[1270]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 13 20:36:08.315138 ignition[1270]: INFO : files: files passed Jan 13 20:36:08.315138 ignition[1270]: INFO : Ignition finished successfully Jan 13 20:36:08.315586 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 13 20:36:08.326836 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 13 20:36:08.328128 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 13 20:36:08.338521 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 13 20:36:08.338668 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 13 20:36:08.349248 initrd-setup-root-after-ignition[1299]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 13 20:36:08.349248 initrd-setup-root-after-ignition[1299]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 13 20:36:08.354241 initrd-setup-root-after-ignition[1303]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 13 20:36:08.357402 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 13 20:36:08.357693 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 13 20:36:08.365891 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 13 20:36:08.411823 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 13 20:36:08.411984 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 13 20:36:08.415086 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 13 20:36:08.417432 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 13 20:36:08.419505 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 13 20:36:08.425747 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 13 20:36:08.443763 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 13 20:36:08.450928 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 13 20:36:08.515926 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 13 20:36:08.527461 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 13 20:36:08.543809 systemd[1]: Stopped target timers.target - Timer Units. Jan 13 20:36:08.548710 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 13 20:36:08.549976 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 13 20:36:08.554826 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 13 20:36:08.556018 systemd[1]: Stopped target basic.target - Basic System. Jan 13 20:36:08.562474 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 13 20:36:08.564490 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 13 20:36:08.566886 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 13 20:36:08.570533 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 13 20:36:08.574208 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 13 20:36:08.577110 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 13 20:36:08.579642 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 13 20:36:08.580974 systemd[1]: Stopped target swap.target - Swaps. Jan 13 20:36:08.584329 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 13 20:36:08.585821 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 13 20:36:08.589392 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 13 20:36:08.590645 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 13 20:36:08.594640 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 13 20:36:08.596674 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 13 20:36:08.599387 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 13 20:36:08.599973 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 13 20:36:08.605298 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 13 20:36:08.605434 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 13 20:36:08.608347 systemd[1]: ignition-files.service: Deactivated successfully. Jan 13 20:36:08.608462 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 13 20:36:08.627986 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 13 20:36:08.632769 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 13 20:36:08.633003 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 13 20:36:08.640650 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 13 20:36:08.646764 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 13 20:36:08.652771 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 13 20:36:08.655542 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 13 20:36:08.656902 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 13 20:36:08.664159 ignition[1323]: INFO : Ignition 2.20.0 Jan 13 20:36:08.664159 ignition[1323]: INFO : Stage: umount Jan 13 20:36:08.666189 ignition[1323]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 13 20:36:08.666189 ignition[1323]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Jan 13 20:36:08.666189 ignition[1323]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Jan 13 20:36:08.671104 ignition[1323]: INFO : PUT result: OK Jan 13 20:36:08.670258 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 13 20:36:08.670373 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 13 20:36:08.677973 ignition[1323]: INFO : umount: umount passed Jan 13 20:36:08.677973 ignition[1323]: INFO : Ignition finished successfully Jan 13 20:36:08.682362 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 13 20:36:08.683392 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 13 20:36:08.686556 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 13 20:36:08.686689 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 13 20:36:08.690359 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 13 20:36:08.690435 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 13 20:36:08.691521 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 13 20:36:08.691593 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 13 20:36:08.691782 systemd[1]: Stopped target network.target - Network. Jan 13 20:36:08.691828 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 13 20:36:08.691884 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 13 20:36:08.692054 systemd[1]: Stopped target paths.target - Path Units. Jan 13 20:36:08.692099 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 13 20:36:08.698737 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 13 20:36:08.700301 systemd[1]: Stopped target slices.target - Slice Units. Jan 13 20:36:08.701301 systemd[1]: Stopped target sockets.target - Socket Units. Jan 13 20:36:08.704546 systemd[1]: iscsid.socket: Deactivated successfully. Jan 13 20:36:08.704623 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 13 20:36:08.706781 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 13 20:36:08.708044 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 13 20:36:08.709121 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 13 20:36:08.709208 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 13 20:36:08.716477 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 13 20:36:08.716551 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 13 20:36:08.727966 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 13 20:36:08.728899 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 13 20:36:08.733331 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 13 20:36:08.734748 systemd-networkd[1078]: eth0: DHCPv6 lease lost Jan 13 20:36:08.741824 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 13 20:36:08.744315 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 13 20:36:08.747702 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 13 20:36:08.747823 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 13 20:36:08.754789 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 13 20:36:08.754844 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 13 20:36:08.765975 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 13 20:36:08.767373 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 13 20:36:08.767461 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 13 20:36:08.770432 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 13 20:36:08.771787 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 13 20:36:08.773694 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 13 20:36:08.773773 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 13 20:36:08.776173 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 13 20:36:08.776239 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 13 20:36:08.778932 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 13 20:36:08.802787 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 13 20:36:08.802930 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 13 20:36:08.811607 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 13 20:36:08.811826 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 13 20:36:08.813682 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 13 20:36:08.813738 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 13 20:36:08.815675 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 13 20:36:08.815711 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 13 20:36:08.819372 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 13 20:36:08.819428 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 13 20:36:08.825830 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 13 20:36:08.825906 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 13 20:36:08.830348 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 13 20:36:08.830414 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 13 20:36:08.840936 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 13 20:36:08.842363 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 13 20:36:08.843937 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 13 20:36:08.845531 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 13 20:36:08.845625 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 13 20:36:08.847579 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 13 20:36:08.847736 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 13 20:36:08.849387 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 13 20:36:08.849492 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 13 20:36:08.853381 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 13 20:36:08.853504 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 13 20:36:08.857976 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 13 20:36:08.869841 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 13 20:36:08.881118 systemd[1]: Switching root. Jan 13 20:36:08.920560 systemd-journald[179]: Journal stopped Jan 13 20:36:10.907706 systemd-journald[179]: Received SIGTERM from PID 1 (systemd). Jan 13 20:36:10.907800 kernel: SELinux: policy capability network_peer_controls=1 Jan 13 20:36:10.907834 kernel: SELinux: policy capability open_perms=1 Jan 13 20:36:10.907855 kernel: SELinux: policy capability extended_socket_class=1 Jan 13 20:36:10.907874 kernel: SELinux: policy capability always_check_network=0 Jan 13 20:36:10.907901 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 13 20:36:10.907928 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 13 20:36:10.907952 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 13 20:36:10.907975 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 13 20:36:10.907995 kernel: audit: type=1403 audit(1736800569.204:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 13 20:36:10.908018 systemd[1]: Successfully loaded SELinux policy in 46.093ms. Jan 13 20:36:10.908052 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 15.086ms. Jan 13 20:36:10.908073 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) Jan 13 20:36:10.908093 systemd[1]: Detected virtualization amazon. Jan 13 20:36:10.908112 systemd[1]: Detected architecture x86-64. Jan 13 20:36:10.908132 systemd[1]: Detected first boot. Jan 13 20:36:10.908154 systemd[1]: Initializing machine ID from VM UUID. Jan 13 20:36:10.908177 zram_generator::config[1367]: No configuration found. Jan 13 20:36:10.908198 systemd[1]: Populated /etc with preset unit settings. Jan 13 20:36:10.908220 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 13 20:36:10.908242 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 13 20:36:10.908263 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 13 20:36:10.908288 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 13 20:36:10.908311 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 13 20:36:10.908334 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 13 20:36:10.908356 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 13 20:36:10.908380 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 13 20:36:10.908402 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 13 20:36:10.908425 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 13 20:36:10.908448 systemd[1]: Created slice user.slice - User and Session Slice. Jan 13 20:36:10.908471 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 13 20:36:10.908497 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 13 20:36:10.908520 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 13 20:36:10.908542 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 13 20:36:10.908565 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 13 20:36:10.909648 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 13 20:36:10.909669 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 13 20:36:10.909689 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 13 20:36:10.909709 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 13 20:36:10.909735 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 13 20:36:10.909759 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 13 20:36:10.909779 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 13 20:36:10.909799 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 13 20:36:10.909819 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 13 20:36:10.909839 systemd[1]: Reached target slices.target - Slice Units. Jan 13 20:36:10.909858 systemd[1]: Reached target swap.target - Swaps. Jan 13 20:36:10.909878 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 13 20:36:10.909901 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 13 20:36:10.909921 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 13 20:36:10.909944 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 13 20:36:10.909964 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 13 20:36:10.909990 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 13 20:36:10.910009 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 13 20:36:10.910029 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 13 20:36:10.910049 systemd[1]: Mounting media.mount - External Media Directory... Jan 13 20:36:10.910069 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:10.910093 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 13 20:36:10.910112 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 13 20:36:10.910133 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 13 20:36:10.910186 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). Jan 13 20:36:10.910206 systemd[1]: Reached target machines.target - Containers. Jan 13 20:36:10.910226 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 13 20:36:10.910247 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 13 20:36:10.910267 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 13 20:36:10.910288 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 13 20:36:10.910312 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 13 20:36:10.910332 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 13 20:36:10.910353 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 13 20:36:10.910373 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 13 20:36:10.910393 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 13 20:36:10.910416 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 13 20:36:10.910436 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 13 20:36:10.910457 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 13 20:36:10.910480 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 13 20:36:10.910500 systemd[1]: Stopped systemd-fsck-usr.service. Jan 13 20:36:10.911616 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 13 20:36:10.911647 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 13 20:36:10.911668 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 13 20:36:10.911692 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 13 20:36:10.911717 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 13 20:36:10.911742 systemd[1]: verity-setup.service: Deactivated successfully. Jan 13 20:36:10.911766 systemd[1]: Stopped verity-setup.service. Jan 13 20:36:10.911796 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:10.911817 kernel: fuse: init (API version 7.39) Jan 13 20:36:10.911840 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 13 20:36:10.911865 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 13 20:36:10.911889 systemd[1]: Mounted media.mount - External Media Directory. Jan 13 20:36:10.911913 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 13 20:36:10.911938 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 13 20:36:10.911967 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 13 20:36:10.911991 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 13 20:36:10.912016 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 13 20:36:10.912040 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 13 20:36:10.912064 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 13 20:36:10.912089 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 13 20:36:10.912112 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 13 20:36:10.912140 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 13 20:36:10.912165 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 13 20:36:10.912191 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 13 20:36:10.912215 kernel: loop: module loaded Jan 13 20:36:10.912239 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 13 20:36:10.912268 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 13 20:36:10.912293 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 13 20:36:10.912318 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 13 20:36:10.912344 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 13 20:36:10.912367 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 13 20:36:10.912392 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 13 20:36:10.912417 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 13 20:36:10.912442 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 13 20:36:10.912467 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 13 20:36:10.912497 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 13 20:36:10.912522 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 13 20:36:10.912546 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 13 20:36:10.914610 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 13 20:36:10.914679 systemd-journald[1449]: Collecting audit messages is disabled. Jan 13 20:36:10.914717 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Jan 13 20:36:10.914737 kernel: ACPI: bus type drm_connector registered Jan 13 20:36:10.914761 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 13 20:36:10.914782 systemd-journald[1449]: Journal started Jan 13 20:36:10.914817 systemd-journald[1449]: Runtime Journal (/run/log/journal/ec2d14d835b5643e1a40040ff5e59abd) is 4.8M, max 38.5M, 33.7M free. Jan 13 20:36:10.279086 systemd[1]: Queued start job for default target multi-user.target. Jan 13 20:36:10.311597 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Jan 13 20:36:10.312040 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 13 20:36:10.928606 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 13 20:36:10.933615 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 13 20:36:10.947510 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 13 20:36:10.947609 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 13 20:36:10.962813 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 13 20:36:10.965692 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 13 20:36:10.973661 systemd[1]: Started systemd-journald.service - Journal Service. Jan 13 20:36:10.975593 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 13 20:36:10.978382 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 13 20:36:10.978630 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 13 20:36:10.980512 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 13 20:36:10.982374 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 13 20:36:11.055469 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 13 20:36:11.057587 kernel: loop0: detected capacity change from 0 to 141000 Jan 13 20:36:11.067782 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 13 20:36:11.069639 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 13 20:36:11.071661 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 13 20:36:11.077055 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 13 20:36:11.089858 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Jan 13 20:36:11.100326 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jan 13 20:36:11.161757 systemd-journald[1449]: Time spent on flushing to /var/log/journal/ec2d14d835b5643e1a40040ff5e59abd is 118.342ms for 949 entries. Jan 13 20:36:11.161757 systemd-journald[1449]: System Journal (/var/log/journal/ec2d14d835b5643e1a40040ff5e59abd) is 8.0M, max 195.6M, 187.6M free. Jan 13 20:36:11.311041 systemd-journald[1449]: Received client request to flush runtime journal. Jan 13 20:36:11.311176 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 13 20:36:11.311225 kernel: loop1: detected capacity change from 0 to 62848 Jan 13 20:36:11.176389 udevadm[1504]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Jan 13 20:36:11.245107 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 13 20:36:11.248707 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Jan 13 20:36:11.325060 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 13 20:36:11.329165 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 13 20:36:11.343619 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 13 20:36:11.407635 kernel: loop2: detected capacity change from 0 to 138184 Jan 13 20:36:11.409200 systemd-tmpfiles[1513]: ACLs are not supported, ignoring. Jan 13 20:36:11.409228 systemd-tmpfiles[1513]: ACLs are not supported, ignoring. Jan 13 20:36:11.428257 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 13 20:36:11.517605 kernel: loop3: detected capacity change from 0 to 205544 Jan 13 20:36:11.606763 kernel: loop4: detected capacity change from 0 to 141000 Jan 13 20:36:11.669599 kernel: loop5: detected capacity change from 0 to 62848 Jan 13 20:36:11.700616 kernel: loop6: detected capacity change from 0 to 138184 Jan 13 20:36:11.762246 kernel: loop7: detected capacity change from 0 to 205544 Jan 13 20:36:11.825980 (sd-merge)[1519]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Jan 13 20:36:11.830058 (sd-merge)[1519]: Merged extensions into '/usr'. Jan 13 20:36:11.845773 systemd[1]: Reloading requested from client PID 1477 ('systemd-sysext') (unit systemd-sysext.service)... Jan 13 20:36:11.845944 systemd[1]: Reloading... Jan 13 20:36:12.021152 zram_generator::config[1541]: No configuration found. Jan 13 20:36:12.330434 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. Jan 13 20:36:12.366094 ldconfig[1473]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 13 20:36:12.451514 systemd[1]: Reloading finished in 601 ms. Jan 13 20:36:12.484253 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 13 20:36:12.490762 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 13 20:36:12.502925 systemd[1]: Starting ensure-sysext.service... Jan 13 20:36:12.509033 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 13 20:36:12.527467 systemd[1]: Reloading requested from client PID 1594 ('systemctl') (unit ensure-sysext.service)... Jan 13 20:36:12.527488 systemd[1]: Reloading... Jan 13 20:36:12.566007 systemd-tmpfiles[1595]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 13 20:36:12.566617 systemd-tmpfiles[1595]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 13 20:36:12.568457 systemd-tmpfiles[1595]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 13 20:36:12.568938 systemd-tmpfiles[1595]: ACLs are not supported, ignoring. Jan 13 20:36:12.569025 systemd-tmpfiles[1595]: ACLs are not supported, ignoring. Jan 13 20:36:12.578442 systemd-tmpfiles[1595]: Detected autofs mount point /boot during canonicalization of boot. Jan 13 20:36:12.580608 systemd-tmpfiles[1595]: Skipping /boot Jan 13 20:36:12.627337 systemd-tmpfiles[1595]: Detected autofs mount point /boot during canonicalization of boot. Jan 13 20:36:12.628803 systemd-tmpfiles[1595]: Skipping /boot Jan 13 20:36:12.669612 zram_generator::config[1622]: No configuration found. Jan 13 20:36:12.805666 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. Jan 13 20:36:12.862538 systemd[1]: Reloading finished in 334 ms. Jan 13 20:36:12.880672 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 13 20:36:12.890113 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 13 20:36:12.906789 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 13 20:36:12.910767 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 13 20:36:12.915796 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 13 20:36:12.926238 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 13 20:36:12.937862 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 13 20:36:12.952049 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 13 20:36:12.963903 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 13 20:36:12.968402 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:12.968902 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 13 20:36:12.976996 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 13 20:36:12.985971 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 13 20:36:12.989412 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 13 20:36:12.991818 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 13 20:36:12.992019 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:13.007972 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:13.008346 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 13 20:36:13.008629 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 13 20:36:13.008777 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:13.014633 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 13 20:36:13.025391 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:13.026794 systemd-udevd[1681]: Using default interface naming scheme 'v255'. Jan 13 20:36:13.026799 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 13 20:36:13.032979 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 13 20:36:13.034539 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 13 20:36:13.034859 systemd[1]: Reached target time-set.target - System Time Set. Jan 13 20:36:13.036119 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 13 20:36:13.037301 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 13 20:36:13.037524 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 13 20:36:13.039379 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 13 20:36:13.039833 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 13 20:36:13.048366 systemd[1]: Finished ensure-sysext.service. Jan 13 20:36:13.059087 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 13 20:36:13.059472 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 13 20:36:13.067314 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 13 20:36:13.067856 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 13 20:36:13.072562 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 13 20:36:13.072868 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 13 20:36:13.091122 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 13 20:36:13.104841 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 13 20:36:13.124330 augenrules[1710]: No rules Jan 13 20:36:13.136466 systemd[1]: audit-rules.service: Deactivated successfully. Jan 13 20:36:13.136875 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 13 20:36:13.154787 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 13 20:36:13.172345 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 13 20:36:13.191172 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 13 20:36:13.202886 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 13 20:36:13.214441 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 13 20:36:13.216760 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). Jan 13 20:36:13.375972 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 13 20:36:13.427453 systemd-networkd[1727]: lo: Link UP Jan 13 20:36:13.427464 systemd-networkd[1727]: lo: Gained carrier Jan 13 20:36:13.429476 systemd-networkd[1727]: Enumeration completed Jan 13 20:36:13.430645 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 13 20:36:13.431714 (udev-worker)[1726]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:13.439891 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 13 20:36:13.443818 systemd-resolved[1679]: Positive Trust Anchors: Jan 13 20:36:13.443838 systemd-resolved[1679]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 13 20:36:13.443891 systemd-resolved[1679]: 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 Jan 13 20:36:13.457233 systemd-resolved[1679]: Defaulting to hostname 'linux'. Jan 13 20:36:13.464744 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 13 20:36:13.466215 systemd[1]: Reached target network.target - Network. Jan 13 20:36:13.467290 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 13 20:36:13.503594 kernel: input: ImPS/2 Generic Wheel Mouse as /devices/platform/i8042/serio1/input/input2 Jan 13 20:36:13.516059 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 255 Jan 13 20:36:13.519638 systemd-networkd[1727]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 13 20:36:13.519648 systemd-networkd[1727]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 13 20:36:13.525612 systemd-networkd[1727]: eth0: Link UP Jan 13 20:36:13.525973 systemd-networkd[1727]: eth0: Gained carrier Jan 13 20:36:13.526008 systemd-networkd[1727]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 13 20:36:13.533598 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Jan 13 20:36:13.536656 systemd-networkd[1727]: eth0: DHCPv4 address 172.31.27.123/20, gateway 172.31.16.1 acquired from 172.31.16.1 Jan 13 20:36:13.557785 kernel: ACPI: button: Power Button [PWRF] Jan 13 20:36:13.557861 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input4 Jan 13 20:36:13.569693 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 13 20:36:13.572595 kernel: ACPI: button: Sleep Button [SLPF] Jan 13 20:36:13.587595 kernel: mousedev: PS/2 mouse device common for all mice Jan 13 20:36:13.608592 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (1737) Jan 13 20:36:13.761008 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Jan 13 20:36:13.868022 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jan 13 20:36:13.873998 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 13 20:36:13.889865 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jan 13 20:36:13.893520 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 13 20:36:13.912529 lvm[1842]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jan 13 20:36:13.930475 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 13 20:36:13.952559 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jan 13 20:36:13.954252 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 13 20:36:13.955535 systemd[1]: Reached target sysinit.target - System Initialization. Jan 13 20:36:13.956926 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 13 20:36:13.958376 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 13 20:36:13.959931 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 13 20:36:13.961238 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 13 20:36:13.966177 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 13 20:36:13.970813 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 13 20:36:13.970866 systemd[1]: Reached target paths.target - Path Units. Jan 13 20:36:13.971987 systemd[1]: Reached target timers.target - Timer Units. Jan 13 20:36:13.974646 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 13 20:36:13.977400 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 13 20:36:13.983693 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 13 20:36:13.986468 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jan 13 20:36:13.988988 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 13 20:36:13.990466 systemd[1]: Reached target sockets.target - Socket Units. Jan 13 20:36:13.991738 systemd[1]: Reached target basic.target - Basic System. Jan 13 20:36:13.992925 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 13 20:36:13.992953 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 13 20:36:14.000834 systemd[1]: Starting containerd.service - containerd container runtime... Jan 13 20:36:14.008593 lvm[1849]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jan 13 20:36:14.009236 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 13 20:36:14.022448 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 13 20:36:14.027308 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 13 20:36:14.039974 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 13 20:36:14.042835 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). Jan 13 20:36:14.044938 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 13 20:36:14.060135 systemd[1]: Started ntpd.service - Network Time Service. Jan 13 20:36:14.078865 jq[1853]: false Jan 13 20:36:14.085287 systemd[1]: Starting setup-oem.service - Setup OEM... Jan 13 20:36:14.094789 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 13 20:36:14.107839 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 13 20:36:14.116849 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 13 20:36:14.119807 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 13 20:36:14.120999 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 13 20:36:14.130859 systemd[1]: Starting update-engine.service - Update Engine... Jan 13 20:36:14.136187 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 13 20:36:14.139553 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jan 13 20:36:14.152090 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 13 20:36:14.153388 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 13 20:36:14.216243 dbus-daemon[1852]: [system] SELinux support is enabled Jan 13 20:36:14.218717 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 13 20:36:14.232398 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 13 20:36:14.237273 jq[1867]: true Jan 13 20:36:14.232746 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 13 20:36:14.240282 dbus-daemon[1852]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=244 pid=1727 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Jan 13 20:36:14.255418 systemd[1]: motdgen.service: Deactivated successfully. Jan 13 20:36:14.255896 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 13 20:36:14.265363 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). Jan 13 20:36:14.265442 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 13 20:36:14.266966 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). Jan 13 20:36:14.266998 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 13 20:36:14.280789 jq[1880]: true Jan 13 20:36:14.278397 dbus-daemon[1852]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 13 20:36:14.285250 update_engine[1866]: I20250113 20:36:14.284318 1866 main.cc:92] Flatcar Update Engine starting Jan 13 20:36:14.294780 extend-filesystems[1854]: Found loop4 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found loop5 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found loop6 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found loop7 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p1 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p2 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p3 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found usr Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p4 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p6 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p7 Jan 13 20:36:14.294780 extend-filesystems[1854]: Found nvme0n1p9 Jan 13 20:36:14.294780 extend-filesystems[1854]: Checking size of /dev/nvme0n1p9 Jan 13 20:36:14.289315 ntpd[1856]: ntpd 4.2.8p17@1.4004-o Mon Jan 13 18:25:52 UTC 2025 (1): Starting Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: ntpd 4.2.8p17@1.4004-o Mon Jan 13 18:25:52 UTC 2025 (1): Starting Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: ---------------------------------------------------- Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: ntp-4 is maintained by Network Time Foundation, Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: corporation. Support and training for ntp-4 are Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: available at https://www.nwtime.org/support Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: ---------------------------------------------------- Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: proto: precision = 0.102 usec (-23) Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: basedate set to 2025-01-01 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: gps base set to 2025-01-05 (week 2348) Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listen and drop on 0 v6wildcard [::]:123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listen normally on 2 lo 127.0.0.1:123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listen normally on 3 eth0 172.31.27.123:123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listen normally on 4 lo [::1]:123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: bind(21) AF_INET6 fe80::4ea:14ff:fe3f:f453%2#123 flags 0x11 failed: Cannot assign requested address Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: unable to create socket on eth0 (5) for fe80::4ea:14ff:fe3f:f453%2#123 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: failed to init interface for address fe80::4ea:14ff:fe3f:f453%2 Jan 13 20:36:14.362974 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: Listening on routing socket on fd #21 for interface updates Jan 13 20:36:14.365847 update_engine[1866]: I20250113 20:36:14.325029 1866 update_check_scheduler.cc:74] Next update check in 4m58s Jan 13 20:36:14.296151 (ntainerd)[1885]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 13 20:36:14.289342 ntpd[1856]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Jan 13 20:36:14.313792 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jan 13 20:36:14.289354 ntpd[1856]: ---------------------------------------------------- Jan 13 20:36:14.324801 systemd[1]: Started update-engine.service - Update Engine. Jan 13 20:36:14.289364 ntpd[1856]: ntp-4 is maintained by Network Time Foundation, Jan 13 20:36:14.327613 systemd-logind[1864]: Watching system buttons on /dev/input/event2 (Power Button) Jan 13 20:36:14.289374 ntpd[1856]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Jan 13 20:36:14.327639 systemd-logind[1864]: Watching system buttons on /dev/input/event3 (Sleep Button) Jan 13 20:36:14.375982 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 20:36:14.375982 ntpd[1856]: 13 Jan 20:36:14 ntpd[1856]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 20:36:14.289384 ntpd[1856]: corporation. Support and training for ntp-4 are Jan 13 20:36:14.327663 systemd-logind[1864]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 13 20:36:14.289393 ntpd[1856]: available at https://www.nwtime.org/support Jan 13 20:36:14.330802 systemd-logind[1864]: New seat seat0. Jan 13 20:36:14.289403 ntpd[1856]: ---------------------------------------------------- Jan 13 20:36:14.335793 systemd[1]: Started systemd-logind.service - User Login Management. Jan 13 20:36:14.298707 ntpd[1856]: proto: precision = 0.102 usec (-23) Jan 13 20:36:14.358823 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 13 20:36:14.302346 ntpd[1856]: basedate set to 2025-01-01 Jan 13 20:36:14.372482 systemd[1]: Finished setup-oem.service - Setup OEM. Jan 13 20:36:14.302481 ntpd[1856]: gps base set to 2025-01-05 (week 2348) Jan 13 20:36:14.327180 ntpd[1856]: Listen and drop on 0 v6wildcard [::]:123 Jan 13 20:36:14.327243 ntpd[1856]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 13 20:36:14.333771 ntpd[1856]: Listen normally on 2 lo 127.0.0.1:123 Jan 13 20:36:14.333824 ntpd[1856]: Listen normally on 3 eth0 172.31.27.123:123 Jan 13 20:36:14.333873 ntpd[1856]: Listen normally on 4 lo [::1]:123 Jan 13 20:36:14.334091 ntpd[1856]: bind(21) AF_INET6 fe80::4ea:14ff:fe3f:f453%2#123 flags 0x11 failed: Cannot assign requested address Jan 13 20:36:14.334123 ntpd[1856]: unable to create socket on eth0 (5) for fe80::4ea:14ff:fe3f:f453%2#123 Jan 13 20:36:14.334139 ntpd[1856]: failed to init interface for address fe80::4ea:14ff:fe3f:f453%2 Jan 13 20:36:14.334181 ntpd[1856]: Listening on routing socket on fd #21 for interface updates Jan 13 20:36:14.374879 ntpd[1856]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 20:36:14.374919 ntpd[1856]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 13 20:36:14.398594 extend-filesystems[1854]: Resized partition /dev/nvme0n1p9 Jan 13 20:36:14.408230 extend-filesystems[1910]: resize2fs 1.47.1 (20-May-2024) Jan 13 20:36:14.420297 coreos-metadata[1851]: Jan 13 20:36:14.419 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Jan 13 20:36:14.426064 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.422 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.423 INFO Fetch successful Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.423 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.423 INFO Fetch successful Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.424 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.424 INFO Fetch successful Jan 13 20:36:14.426126 coreos-metadata[1851]: Jan 13 20:36:14.424 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Jan 13 20:36:14.433381 coreos-metadata[1851]: Jan 13 20:36:14.426 INFO Fetch successful Jan 13 20:36:14.433381 coreos-metadata[1851]: Jan 13 20:36:14.426 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Jan 13 20:36:14.441676 coreos-metadata[1851]: Jan 13 20:36:14.441 INFO Fetch failed with 404: resource not found Jan 13 20:36:14.441808 coreos-metadata[1851]: Jan 13 20:36:14.441 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Jan 13 20:36:14.443380 coreos-metadata[1851]: Jan 13 20:36:14.443 INFO Fetch successful Jan 13 20:36:14.443507 coreos-metadata[1851]: Jan 13 20:36:14.443 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Jan 13 20:36:14.444171 coreos-metadata[1851]: Jan 13 20:36:14.444 INFO Fetch successful Jan 13 20:36:14.444263 coreos-metadata[1851]: Jan 13 20:36:14.444 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Jan 13 20:36:14.446228 coreos-metadata[1851]: Jan 13 20:36:14.446 INFO Fetch successful Jan 13 20:36:14.446317 coreos-metadata[1851]: Jan 13 20:36:14.446 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Jan 13 20:36:14.447106 coreos-metadata[1851]: Jan 13 20:36:14.447 INFO Fetch successful Jan 13 20:36:14.447192 coreos-metadata[1851]: Jan 13 20:36:14.447 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Jan 13 20:36:14.448678 coreos-metadata[1851]: Jan 13 20:36:14.448 INFO Fetch successful Jan 13 20:36:14.574245 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Jan 13 20:36:14.582896 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 13 20:36:14.585458 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 13 20:36:14.599613 extend-filesystems[1910]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Jan 13 20:36:14.599613 extend-filesystems[1910]: old_desc_blocks = 1, new_desc_blocks = 1 Jan 13 20:36:14.599613 extend-filesystems[1910]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Jan 13 20:36:14.606050 extend-filesystems[1854]: Resized filesystem in /dev/nvme0n1p9 Jan 13 20:36:14.600826 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 13 20:36:14.601709 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 13 20:36:14.626319 bash[1918]: Updated "/home/core/.ssh/authorized_keys" Jan 13 20:36:14.623148 systemd-networkd[1727]: eth0: Gained IPv6LL Jan 13 20:36:14.626143 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 13 20:36:14.660071 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (1730) Jan 13 20:36:14.655298 systemd[1]: Starting sshkeys.service... Jan 13 20:36:14.660964 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 13 20:36:14.664280 systemd[1]: Reached target network-online.target - Network is Online. Jan 13 20:36:14.676146 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Jan 13 20:36:14.687119 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 13 20:36:14.696110 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 13 20:36:14.703458 dbus-daemon[1852]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 13 20:36:14.703686 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jan 13 20:36:14.714107 dbus-daemon[1852]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1893 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Jan 13 20:36:14.728932 systemd[1]: Starting polkit.service - Authorization Manager... Jan 13 20:36:14.795078 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Jan 13 20:36:14.809256 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Jan 13 20:36:14.871726 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 13 20:36:14.875383 polkitd[1959]: Started polkitd version 121 Jan 13 20:36:14.908796 locksmithd[1897]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 13 20:36:14.935533 polkitd[1959]: Loading rules from directory /etc/polkit-1/rules.d Jan 13 20:36:14.937959 polkitd[1959]: Loading rules from directory /usr/share/polkit-1/rules.d Jan 13 20:36:14.952130 sshd_keygen[1886]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 13 20:36:14.955415 polkitd[1959]: Finished loading, compiling and executing 2 rules Jan 13 20:36:14.969739 dbus-daemon[1852]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 13 20:36:14.970106 systemd[1]: Started polkit.service - Authorization Manager. Jan 13 20:36:14.976009 polkitd[1959]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Jan 13 20:36:15.082433 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 13 20:36:15.093103 amazon-ssm-agent[1937]: Initializing new seelog logger Jan 13 20:36:15.092991 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 13 20:36:15.097716 systemd-resolved[1679]: System hostname changed to 'ip-172-31-27-123'. Jan 13 20:36:15.098555 amazon-ssm-agent[1937]: New Seelog Logger Creation Complete Jan 13 20:36:15.098555 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.098555 amazon-ssm-agent[1937]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.098555 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 processing appconfig overrides Jan 13 20:36:15.098121 systemd-hostnamed[1893]: Hostname set to (transient) Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 processing appconfig overrides Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 processing appconfig overrides Jan 13 20:36:15.103595 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO Proxy environment variables: Jan 13 20:36:15.103953 coreos-metadata[1976]: Jan 13 20:36:15.102 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Jan 13 20:36:15.108833 coreos-metadata[1976]: Jan 13 20:36:15.106 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Jan 13 20:36:15.109726 coreos-metadata[1976]: Jan 13 20:36:15.109 INFO Fetch successful Jan 13 20:36:15.109869 coreos-metadata[1976]: Jan 13 20:36:15.109 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Jan 13 20:36:15.112090 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.112090 amazon-ssm-agent[1937]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Jan 13 20:36:15.112090 amazon-ssm-agent[1937]: 2025/01/13 20:36:15 processing appconfig overrides Jan 13 20:36:15.112592 coreos-metadata[1976]: Jan 13 20:36:15.112 INFO Fetch successful Jan 13 20:36:15.116924 unknown[1976]: wrote ssh authorized keys file for user: core Jan 13 20:36:15.167114 systemd[1]: issuegen.service: Deactivated successfully. Jan 13 20:36:15.167355 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 13 20:36:15.202750 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO https_proxy: Jan 13 20:36:15.204680 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 13 20:36:15.231595 update-ssh-keys[2058]: Updated "/home/core/.ssh/authorized_keys" Jan 13 20:36:15.239844 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Jan 13 20:36:15.253966 systemd[1]: Finished sshkeys.service. Jan 13 20:36:15.291283 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 13 20:36:15.302820 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO http_proxy: Jan 13 20:36:15.307019 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 13 20:36:15.317499 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 13 20:36:15.319112 systemd[1]: Reached target getty.target - Login Prompts. Jan 13 20:36:15.334987 containerd[1885]: time="2025-01-13T20:36:15.334900965Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Jan 13 20:36:15.389234 containerd[1885]: time="2025-01-13T20:36:15.388523722Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.390712 containerd[1885]: time="2025-01-13T20:36:15.390661662Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.71-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jan 13 20:36:15.390712 containerd[1885]: time="2025-01-13T20:36:15.390710247Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jan 13 20:36:15.390834 containerd[1885]: time="2025-01-13T20:36:15.390733357Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jan 13 20:36:15.390938 containerd[1885]: time="2025-01-13T20:36:15.390916351Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jan 13 20:36:15.390980 containerd[1885]: time="2025-01-13T20:36:15.390947942Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391053 containerd[1885]: time="2025-01-13T20:36:15.391032349Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391094 containerd[1885]: time="2025-01-13T20:36:15.391058010Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391303 containerd[1885]: time="2025-01-13T20:36:15.391279566Z" 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 Jan 13 20:36:15.391373 containerd[1885]: time="2025-01-13T20:36:15.391306293Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391373 containerd[1885]: time="2025-01-13T20:36:15.391325295Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391373 containerd[1885]: time="2025-01-13T20:36:15.391340137Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.391483 containerd[1885]: time="2025-01-13T20:36:15.391440096Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.392386 containerd[1885]: time="2025-01-13T20:36:15.391728030Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jan 13 20:36:15.392386 containerd[1885]: time="2025-01-13T20:36:15.391878709Z" 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 Jan 13 20:36:15.392386 containerd[1885]: time="2025-01-13T20:36:15.391898535Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jan 13 20:36:15.392386 containerd[1885]: time="2025-01-13T20:36:15.391988648Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jan 13 20:36:15.392386 containerd[1885]: time="2025-01-13T20:36:15.392042367Z" level=info msg="metadata content store policy set" policy=shared Jan 13 20:36:15.401058 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO no_proxy: Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404271920Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404347101Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404371213Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404395484Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404419097Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404646032Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.404987946Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405115575Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405136646Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405158657Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405178006Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405198294Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405217783Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.406595 containerd[1885]: time="2025-01-13T20:36:15.405238463Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405264007Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405285591Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405311924Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405329988Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405358127Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405378481Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405397320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405417292Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405435784Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405455197Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405472584Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405497718Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405517602Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407175 containerd[1885]: time="2025-01-13T20:36:15.405537984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405579586Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405597970Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405616015Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405636803Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405666047Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405686756Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405703674Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405771873Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405797612Z" 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 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405815954Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405834481Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405848822Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405872794Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jan 13 20:36:15.407670 containerd[1885]: time="2025-01-13T20:36:15.405885914Z" level=info msg="NRI interface is disabled by configuration." Jan 13 20:36:15.408154 containerd[1885]: time="2025-01-13T20:36:15.405902861Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jan 13 20:36:15.408194 containerd[1885]: time="2025-01-13T20:36:15.406323462Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Jan 13 20:36:15.408194 containerd[1885]: time="2025-01-13T20:36:15.406390097Z" level=info msg="Connect containerd service" Jan 13 20:36:15.408194 containerd[1885]: time="2025-01-13T20:36:15.406428305Z" level=info msg="using legacy CRI server" Jan 13 20:36:15.408194 containerd[1885]: time="2025-01-13T20:36:15.406437617Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 13 20:36:15.410986 containerd[1885]: time="2025-01-13T20:36:15.409477614Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jan 13 20:36:15.411394 containerd[1885]: time="2025-01-13T20:36:15.411363110Z" 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" Jan 13 20:36:15.411653 containerd[1885]: time="2025-01-13T20:36:15.411611015Z" level=info msg="Start subscribing containerd event" Jan 13 20:36:15.412455 containerd[1885]: time="2025-01-13T20:36:15.411679305Z" level=info msg="Start recovering state" Jan 13 20:36:15.412455 containerd[1885]: time="2025-01-13T20:36:15.411761523Z" level=info msg="Start event monitor" Jan 13 20:36:15.412455 containerd[1885]: time="2025-01-13T20:36:15.411781186Z" level=info msg="Start snapshots syncer" Jan 13 20:36:15.412455 containerd[1885]: time="2025-01-13T20:36:15.411795349Z" level=info msg="Start cni network conf syncer for default" Jan 13 20:36:15.412455 containerd[1885]: time="2025-01-13T20:36:15.411805612Z" level=info msg="Start streaming server" Jan 13 20:36:15.415127 containerd[1885]: time="2025-01-13T20:36:15.412879884Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 13 20:36:15.415127 containerd[1885]: time="2025-01-13T20:36:15.412936450Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 13 20:36:15.415127 containerd[1885]: time="2025-01-13T20:36:15.413246430Z" level=info msg="containerd successfully booted in 0.079914s" Jan 13 20:36:15.413101 systemd[1]: Started containerd.service - containerd container runtime. Jan 13 20:36:15.499132 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO Checking if agent identity type OnPrem can be assumed Jan 13 20:36:15.597321 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO Checking if agent identity type EC2 can be assumed Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO Agent will take identity from EC2 Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] using named pipe channel for IPC Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] using named pipe channel for IPC Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] using named pipe channel for IPC Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] OS: linux, Arch: amd64 Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] Starting Core Agent Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [amazon-ssm-agent] registrar detected. Attempting registration Jan 13 20:36:15.670186 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [Registrar] Starting registrar module Jan 13 20:36:15.670559 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Jan 13 20:36:15.670559 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [EC2Identity] EC2 registration was successful. Jan 13 20:36:15.670559 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [CredentialRefresher] credentialRefresher has started Jan 13 20:36:15.670559 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [CredentialRefresher] Starting credentials refresher loop Jan 13 20:36:15.670559 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO EC2RoleProvider Successfully connected with instance profile role credentials Jan 13 20:36:15.695691 amazon-ssm-agent[1937]: 2025-01-13 20:36:15 INFO [CredentialRefresher] Next credential rotation will be in 32.04165759883333 minutes Jan 13 20:36:16.537273 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jan 13 20:36:16.539961 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 13 20:36:16.541832 systemd[1]: Startup finished in 1.058s (kernel) + 8.459s (initrd) + 7.381s (userspace) = 16.900s. Jan 13 20:36:16.543943 (kubelet)[2093]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jan 13 20:36:16.559269 agetty[2083]: failed to open credentials directory Jan 13 20:36:16.686068 agetty[2081]: failed to open credentials directory Jan 13 20:36:16.699539 amazon-ssm-agent[1937]: 2025-01-13 20:36:16 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Jan 13 20:36:16.800225 amazon-ssm-agent[1937]: 2025-01-13 20:36:16 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2099) started Jan 13 20:36:16.901001 amazon-ssm-agent[1937]: 2025-01-13 20:36:16 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Jan 13 20:36:17.289829 ntpd[1856]: Listen normally on 6 eth0 [fe80::4ea:14ff:fe3f:f453%2]:123 Jan 13 20:36:17.290219 ntpd[1856]: 13 Jan 20:36:17 ntpd[1856]: Listen normally on 6 eth0 [fe80::4ea:14ff:fe3f:f453%2]:123 Jan 13 20:36:17.492738 kubelet[2093]: E0113 20:36:17.492684 2093 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" Jan 13 20:36:17.495226 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jan 13 20:36:17.495419 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jan 13 20:36:18.554407 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 13 20:36:18.564731 systemd[1]: Started sshd@0-172.31.27.123:22-139.178.89.65:37076.service - OpenSSH per-connection server daemon (139.178.89.65:37076). Jan 13 20:36:18.743801 sshd[2116]: Accepted publickey for core from 139.178.89.65 port 37076 ssh2: RSA SHA256:EuSc9fTRQXLwCQZEkDl5fiJPvgrOIGSulDG6+Z++tMY Jan 13 20:36:18.746745 sshd-session[2116]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 13 20:36:18.758836 systemd-logind[1864]: New session 1 of user core. Jan 13 20:36:18.761013 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 13 20:36:18.768709 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 13 20:36:18.795405 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 13 20:36:18.803109 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 13 20:36:18.811758 (systemd)[2120]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 13 20:36:18.930880 systemd[2120]: Queued start job for default target default.target. Jan 13 20:36:18.940815 systemd[2120]: Created slice app.slice - User Application Slice. Jan 13 20:36:18.940860 systemd[2120]: Reached target paths.target - Paths. Jan 13 20:36:18.940882 systemd[2120]: Reached target timers.target - Timers. Jan 13 20:36:18.942264 systemd[2120]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 13 20:36:18.957127 systemd[2120]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 13 20:36:18.957221 systemd[2120]: Reached target sockets.target - Sockets. Jan 13 20:36:18.957242 systemd[2120]: Reached target basic.target - Basic System. Jan 13 20:36:18.957298 systemd[2120]: Reached target default.target - Main User Target. Jan 13 20:36:18.957335 systemd[2120]: Startup finished in 138ms. Jan 13 20:36:18.957681 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 13 20:36:18.966783 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 13 20:36:19.113724 systemd[1]: Started sshd@1-172.31.27.123:22-139.178.89.65:37090.service - OpenSSH per-connection server daemon (139.178.89.65:37090). Jan 13 20:36:19.276603 sshd[2131]: Accepted publickey for core from 139.178.89.65 port 37090 ssh2: RSA SHA256:EuSc9fTRQXLwCQZEkDl5fiJPvgrOIGSulDG6+Z++tMY Jan 13 20:36:19.278534 sshd-session[2131]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 13 20:36:19.284852 systemd-logind[1864]: New session 2 of user core. Jan 13 20:36:19.293768 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 13 20:36:19.413797 sshd[2133]: Connection closed by 139.178.89.65 port 37090 Jan 13 20:36:19.414462 sshd-session[2131]: pam_unix(sshd:session): session closed for user core Jan 13 20:36:19.417983 systemd[1]: sshd@1-172.31.27.123:22-139.178.89.65:37090.service: Deactivated successfully. Jan 13 20:36:19.420022 systemd[1]: session-2.scope: Deactivated successfully. Jan 13 20:36:19.421453 systemd-logind[1864]: Session 2 logged out. Waiting for processes to exit. Jan 13 20:36:19.422902 systemd-logind[1864]: Removed session 2. Jan 13 20:36:19.450908 systemd[1]: Started sshd@2-172.31.27.123:22-139.178.89.65:37098.service - OpenSSH per-connection server daemon (139.178.89.65:37098). Jan 13 20:36:19.619773 sshd[2138]: Accepted publickey for core from 139.178.89.65 port 37098 ssh2: RSA SHA256:EuSc9fTRQXLwCQZEkDl5fiJPvgrOIGSulDG6+Z++tMY Jan 13 20:36:19.621108 sshd-session[2138]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 13 20:36:19.625759 systemd-logind[1864]: New session 3 of user core. Jan 13 20:36:19.641904 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 13 20:36:19.758073 sshd[2140]: Connection closed by 139.178.89.65 port 37098 Jan 13 20:36:19.758749 sshd-session[2138]: pam_unix(sshd:session): session closed for user core Jan 13 20:36:19.768483 systemd[1]: sshd@2-172.31.27.123:22-139.178.89.65:37098.service: Deactivated successfully. Jan 13 20:36:19.770552 systemd[1]: session-3.scope: Deactivated successfully. Jan 13 20:36:19.773669 systemd-logind[1864]: Session 3 logged out. Waiting for processes to exit. Jan 13 20:36:19.780268 systemd-logind[1864]: Removed session 3. Jan 13 20:36:19.806953 systemd[1]: Started sshd@3-172.31.27.123:22-139.178.89.65:37108.service - OpenSSH per-connection server daemon (139.178.89.65:37108). Jan 13 20:36:19.998090 sshd[2146]: Accepted publickey for core from 139.178.89.65 port 37108 ssh2: RSA SHA256:EuSc9fTRQXLwCQZEkDl5fiJPvgrOIGSulDG6+Z++tMY Jan 13 20:36:19.999483 sshd-session[2146]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 13 20:36:20.005478 systemd-logind[1864]: New session 4 of user core. Jan 13 20:36:20.016820 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 13 20:36:20.135635 sshd[2148]: Connection closed by 139.178.89.65 port 37108 Jan 13 20:36:20.136271 sshd-session[2146]: pam_unix(sshd:session): session closed for user core Jan 13 20:36:20.139408 systemd[1]: sshd@3-172.31.27.123:22-139.178.89.65:37108.service: Deactivated successfully. Jan 13 20:36:20.141650 systemd[1]: session-4.scope: Deactivated successfully. Jan 13 20:36:20.143075 systemd-logind[1864]: Session 4 logged out. Waiting for processes to exit. Jan 13 20:36:20.144683 systemd-logind[1864]: Removed session 4. Jan 13 20:36:20.172137 systemd[1]: Started sshd@4-172.31.27.123:22-139.178.89.65:37120.service - OpenSSH per-connection server daemon (139.178.89.65:37120). Jan 13 20:36:20.335013 sshd[2153]: Accepted publickey for core from 139.178.89.65 port 37120 ssh2: RSA SHA256:EuSc9fTRQXLwCQZEkDl5fiJPvgrOIGSulDG6+Z++tMY Jan 13 20:36:20.336599 sshd-session[2153]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 13 20:36:20.344980 systemd-logind[1864]: New session 5 of user core. Jan 13 20:36:20.361839 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 13 20:36:20.481951 sudo[2156]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jan 13 20:36:20.482359 sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 13 20:36:21.600796 systemd-resolved[1679]: Clock change detected. Flushing caches. Jan 13 20:36:21.824286 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jan 13 20:36:21.839340 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 13 20:36:21.877700 systemd[1]: Reloading requested from client PID 2189 ('systemctl') (unit session-5.scope)... Jan 13 20:36:21.877914 systemd[1]: Reloading... Jan 13 20:36:22.106951 zram_generator::config[2232]: No configuration found. Jan 13 20:36:22.278864 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. Jan 13 20:36:22.392922 systemd[1]: Reloading finished in 514 ms. Jan 13 20:36:22.489371 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jan 13 20:36:22.489809 systemd[1]: kubelet.service: Failed with result 'signal'. Jan 13 20:36:22.490757 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jan 13 20:36:22.497061 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jan 13 20:36:22.771711 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jan 13 20:36:22.781106 (kubelet)[2289]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jan 13 20:36:22.874770 kubelet[2289]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Jan 13 20:36:22.874770 kubelet[2289]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Jan 13 20:36:22.874770 kubelet[2289]: 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. Jan 13 20:36:22.877194 kubelet[2289]: I0113 20:36:22.877130 2289 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jan 13 20:36:23.224522 kubelet[2289]: I0113 20:36:23.224424 2289 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Jan 13 20:36:23.224522 kubelet[2289]: I0113 20:36:23.224456 2289 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jan 13 20:36:23.224840 kubelet[2289]: I0113 20:36:23.224817 2289 server.go:929] "Client rotation is on, will bootstrap in background" Jan 13 20:36:23.292354 kubelet[2289]: I0113 20:36:23.292255 2289 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jan 13 20:36:23.344690 kubelet[2289]: E0113 20:36:23.344633 2289 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Jan 13 20:36:23.344690 kubelet[2289]: I0113 20:36:23.344674 2289 server.go:1403] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Jan 13 20:36:23.350865 kubelet[2289]: I0113 20:36:23.350835 2289 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jan 13 20:36:23.351052 kubelet[2289]: I0113 20:36:23.350978 2289 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Jan 13 20:36:23.351189 kubelet[2289]: I0113 20:36:23.351154 2289 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jan 13 20:36:23.351464 kubelet[2289]: I0113 20:36:23.351189 2289 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.27.123","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jan 13 20:36:23.351618 kubelet[2289]: I0113 20:36:23.351479 2289 topology_manager.go:138] "Creating topology manager with none policy" Jan 13 20:36:23.351618 kubelet[2289]: I0113 20:36:23.351493 2289 container_manager_linux.go:300] "Creating device plugin manager" Jan 13 20:36:23.351699 kubelet[2289]: I0113 20:36:23.351649 2289 state_mem.go:36] "Initialized new in-memory state store" Jan 13 20:36:23.354006 kubelet[2289]: I0113 20:36:23.353972 2289 kubelet.go:408] "Attempting to sync node with API server" Jan 13 20:36:23.354006 kubelet[2289]: I0113 20:36:23.354002 2289 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Jan 13 20:36:23.354153 kubelet[2289]: I0113 20:36:23.354043 2289 kubelet.go:314] "Adding apiserver pod source" Jan 13 20:36:23.354153 kubelet[2289]: I0113 20:36:23.354060 2289 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jan 13 20:36:23.354803 kubelet[2289]: E0113 20:36:23.354765 2289 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:23.354877 kubelet[2289]: E0113 20:36:23.354823 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:23.363202 kubelet[2289]: I0113 20:36:23.363173 2289 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Jan 13 20:36:23.369302 kubelet[2289]: I0113 20:36:23.369264 2289 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jan 13 20:36:23.370607 kubelet[2289]: W0113 20:36:23.370574 2289 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jan 13 20:36:23.371665 kubelet[2289]: I0113 20:36:23.371435 2289 server.go:1269] "Started kubelet" Jan 13 20:36:23.373193 kubelet[2289]: I0113 20:36:23.373175 2289 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jan 13 20:36:23.373346 kubelet[2289]: I0113 20:36:23.373313 2289 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Jan 13 20:36:23.376101 kubelet[2289]: I0113 20:36:23.374621 2289 server.go:460] "Adding debug handlers to kubelet server" Jan 13 20:36:23.376515 kubelet[2289]: I0113 20:36:23.376444 2289 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jan 13 20:36:23.376937 kubelet[2289]: I0113 20:36:23.376910 2289 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jan 13 20:36:23.386463 kubelet[2289]: I0113 20:36:23.385842 2289 volume_manager.go:289] "Starting Kubelet Volume Manager" Jan 13 20:36:23.386751 kubelet[2289]: E0113 20:36:23.386727 2289 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.27.123\" not found" Jan 13 20:36:23.387163 kubelet[2289]: I0113 20:36:23.387065 2289 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Jan 13 20:36:23.388522 kubelet[2289]: I0113 20:36:23.387646 2289 reconciler.go:26] "Reconciler: start to sync state" Jan 13 20:36:23.388959 kubelet[2289]: I0113 20:36:23.388936 2289 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jan 13 20:36:23.390200 kubelet[2289]: I0113 20:36:23.390176 2289 factory.go:221] Registration of the systemd container factory successfully Jan 13 20:36:23.390385 kubelet[2289]: I0113 20:36:23.390362 2289 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jan 13 20:36:23.395480 kubelet[2289]: I0113 20:36:23.395451 2289 factory.go:221] Registration of the containerd container factory successfully Jan 13 20:36:23.404519 kubelet[2289]: E0113 20:36:23.402359 2289 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jan 13 20:36:23.405009 kubelet[2289]: E0113 20:36:23.402685 2289 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.27.123\" not found" node="172.31.27.123" Jan 13 20:36:23.418254 kubelet[2289]: I0113 20:36:23.418224 2289 cpu_manager.go:214] "Starting CPU manager" policy="none" Jan 13 20:36:23.418254 kubelet[2289]: I0113 20:36:23.418245 2289 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Jan 13 20:36:23.418254 kubelet[2289]: I0113 20:36:23.418266 2289 state_mem.go:36] "Initialized new in-memory state store" Jan 13 20:36:23.426455 kubelet[2289]: I0113 20:36:23.426424 2289 policy_none.go:49] "None policy: Start" Jan 13 20:36:23.427638 kubelet[2289]: I0113 20:36:23.427517 2289 memory_manager.go:170] "Starting memorymanager" policy="None" Jan 13 20:36:23.427638 kubelet[2289]: I0113 20:36:23.427546 2289 state_mem.go:35] "Initializing new in-memory state store" Jan 13 20:36:23.458697 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jan 13 20:36:23.488440 kubelet[2289]: E0113 20:36:23.488324 2289 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.27.123\" not found" Jan 13 20:36:23.506623 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jan 13 20:36:23.522307 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jan 13 20:36:23.533880 kubelet[2289]: I0113 20:36:23.533855 2289 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jan 13 20:36:23.535730 kubelet[2289]: I0113 20:36:23.535415 2289 eviction_manager.go:189] "Eviction manager: starting control loop" Jan 13 20:36:23.539486 kubelet[2289]: I0113 20:36:23.535434 2289 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jan 13 20:36:23.541727 kubelet[2289]: I0113 20:36:23.541648 2289 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jan 13 20:36:23.545643 kubelet[2289]: E0113 20:36:23.545461 2289 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.27.123\" not found" Jan 13 20:36:23.556584 kubelet[2289]: I0113 20:36:23.556276 2289 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jan 13 20:36:23.559645 kubelet[2289]: I0113 20:36:23.559612 2289 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jan 13 20:36:23.559773 kubelet[2289]: I0113 20:36:23.559655 2289 status_manager.go:217] "Starting to sync pod status with apiserver" Jan 13 20:36:23.559773 kubelet[2289]: I0113 20:36:23.559678 2289 kubelet.go:2321] "Starting kubelet main sync loop" Jan 13 20:36:23.559773 kubelet[2289]: E0113 20:36:23.559728 2289 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Jan 13 20:36:23.641909 kubelet[2289]: I0113 20:36:23.641872 2289 kubelet_node_status.go:72] "Attempting to register node" node="172.31.27.123" Jan 13 20:36:23.649869 kubelet[2289]: I0113 20:36:23.649543 2289 kubelet_node_status.go:75] "Successfully registered node" node="172.31.27.123" Jan 13 20:36:23.668632 kubelet[2289]: I0113 20:36:23.668573 2289 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jan 13 20:36:23.669267 containerd[1885]: time="2025-01-13T20:36:23.669190533Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jan 13 20:36:23.671343 kubelet[2289]: I0113 20:36:23.669743 2289 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jan 13 20:36:23.822984 sudo[2156]: pam_unix(sudo:session): session closed for user root Jan 13 20:36:23.845315 sshd[2155]: Connection closed by 139.178.89.65 port 37120 Jan 13 20:36:23.846625 sshd-session[2153]: pam_unix(sshd:session): session closed for user core Jan 13 20:36:23.855409 systemd[1]: sshd@4-172.31.27.123:22-139.178.89.65:37120.service: Deactivated successfully. Jan 13 20:36:23.857974 systemd[1]: session-5.scope: Deactivated successfully. Jan 13 20:36:23.859520 systemd-logind[1864]: Session 5 logged out. Waiting for processes to exit. Jan 13 20:36:23.861203 systemd-logind[1864]: Removed session 5. Jan 13 20:36:24.226854 kubelet[2289]: I0113 20:36:24.226687 2289 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jan 13 20:36:24.227292 kubelet[2289]: W0113 20:36:24.226896 2289 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jan 13 20:36:24.227292 kubelet[2289]: W0113 20:36:24.227243 2289 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jan 13 20:36:24.227292 kubelet[2289]: W0113 20:36:24.227277 2289 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jan 13 20:36:24.355162 kubelet[2289]: E0113 20:36:24.355106 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:24.355162 kubelet[2289]: I0113 20:36:24.355117 2289 apiserver.go:52] "Watching apiserver" Jan 13 20:36:24.379640 systemd[1]: Created slice kubepods-besteffort-podb03def6c_8a94_4b16_a78f_260a66386dbc.slice - libcontainer container kubepods-besteffort-podb03def6c_8a94_4b16_a78f_260a66386dbc.slice. Jan 13 20:36:24.384916 systemd[1]: Created slice kubepods-burstable-podf2a014ac_c1c0_40a0_9599_74a5aadfdf21.slice - libcontainer container kubepods-burstable-podf2a014ac_c1c0_40a0_9599_74a5aadfdf21.slice. Jan 13 20:36:24.388689 kubelet[2289]: I0113 20:36:24.388048 2289 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Jan 13 20:36:24.393011 kubelet[2289]: I0113 20:36:24.392772 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b03def6c-8a94-4b16-a78f-260a66386dbc-xtables-lock\") pod \"kube-proxy-dh2v7\" (UID: \"b03def6c-8a94-4b16-a78f-260a66386dbc\") " pod="kube-system/kube-proxy-dh2v7" Jan 13 20:36:24.393159 kubelet[2289]: I0113 20:36:24.393085 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9v4gq\" (UniqueName: \"kubernetes.io/projected/b03def6c-8a94-4b16-a78f-260a66386dbc-kube-api-access-9v4gq\") pod \"kube-proxy-dh2v7\" (UID: \"b03def6c-8a94-4b16-a78f-260a66386dbc\") " pod="kube-system/kube-proxy-dh2v7" Jan 13 20:36:24.393159 kubelet[2289]: I0113 20:36:24.393124 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-run\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.393159 kubelet[2289]: I0113 20:36:24.393145 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-flannel-cfg\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.393306 kubelet[2289]: I0113 20:36:24.393170 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-xtables-lock\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.393306 kubelet[2289]: I0113 20:36:24.393197 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b03def6c-8a94-4b16-a78f-260a66386dbc-kube-proxy\") pod \"kube-proxy-dh2v7\" (UID: \"b03def6c-8a94-4b16-a78f-260a66386dbc\") " pod="kube-system/kube-proxy-dh2v7" Jan 13 20:36:24.393306 kubelet[2289]: I0113 20:36:24.393220 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b03def6c-8a94-4b16-a78f-260a66386dbc-lib-modules\") pod \"kube-proxy-dh2v7\" (UID: \"b03def6c-8a94-4b16-a78f-260a66386dbc\") " pod="kube-system/kube-proxy-dh2v7" Jan 13 20:36:24.393306 kubelet[2289]: I0113 20:36:24.393243 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-cni-plugin\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.393306 kubelet[2289]: I0113 20:36:24.393266 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-cni\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.393585 kubelet[2289]: I0113 20:36:24.393290 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2zrmn\" (UniqueName: \"kubernetes.io/projected/f2a014ac-c1c0-40a0-9599-74a5aadfdf21-kube-api-access-2zrmn\") pod \"kube-flannel-ds-jtbt4\" (UID: \"f2a014ac-c1c0-40a0-9599-74a5aadfdf21\") " pod="kube-flannel/kube-flannel-ds-jtbt4" Jan 13 20:36:24.694356 containerd[1885]: time="2025-01-13T20:36:24.694309990Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dh2v7,Uid:b03def6c-8a94-4b16-a78f-260a66386dbc,Namespace:kube-system,Attempt:0,}" Jan 13 20:36:24.701763 containerd[1885]: time="2025-01-13T20:36:24.701724957Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-jtbt4,Uid:f2a014ac-c1c0-40a0-9599-74a5aadfdf21,Namespace:kube-flannel,Attempt:0,}" Jan 13 20:36:25.277444 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1974159554.mount: Deactivated successfully. Jan 13 20:36:25.294170 containerd[1885]: time="2025-01-13T20:36:25.294034156Z" 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\"}" Jan 13 20:36:25.296175 containerd[1885]: time="2025-01-13T20:36:25.296093743Z" 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\"}" Jan 13 20:36:25.299108 containerd[1885]: time="2025-01-13T20:36:25.299016565Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Jan 13 20:36:25.301111 containerd[1885]: time="2025-01-13T20:36:25.301027387Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Jan 13 20:36:25.304522 containerd[1885]: time="2025-01-13T20:36:25.302200247Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jan 13 20:36:25.306616 containerd[1885]: time="2025-01-13T20:36:25.305864721Z" 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\"}" Jan 13 20:36:25.309871 containerd[1885]: time="2025-01-13T20:36:25.309834915Z" 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 608.007687ms" Jan 13 20:36:25.311464 containerd[1885]: time="2025-01-13T20:36:25.311427250Z" 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 616.985688ms" Jan 13 20:36:25.355710 kubelet[2289]: E0113 20:36:25.355668 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:25.528318 containerd[1885]: time="2025-01-13T20:36:25.527398781Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 13 20:36:25.528318 containerd[1885]: time="2025-01-13T20:36:25.527568757Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 13 20:36:25.528318 containerd[1885]: time="2025-01-13T20:36:25.527636406Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:25.528318 containerd[1885]: time="2025-01-13T20:36:25.527920403Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:25.528679 containerd[1885]: time="2025-01-13T20:36:25.527978928Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 13 20:36:25.528679 containerd[1885]: time="2025-01-13T20:36:25.528056853Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 13 20:36:25.528679 containerd[1885]: time="2025-01-13T20:36:25.528106649Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:25.528679 containerd[1885]: time="2025-01-13T20:36:25.528199487Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:25.713338 systemd[1]: run-containerd-runc-k8s.io-af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf-runc.0lBYgd.mount: Deactivated successfully. Jan 13 20:36:25.728928 systemd[1]: Started cri-containerd-af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf.scope - libcontainer container af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf. Jan 13 20:36:25.730954 systemd[1]: Started cri-containerd-fbfbcefbab608f2a0d32440e7db67373dd2ab4f3fb395cc9f6c44d2cd21fdb2d.scope - libcontainer container fbfbcefbab608f2a0d32440e7db67373dd2ab4f3fb395cc9f6c44d2cd21fdb2d. Jan 13 20:36:25.785838 containerd[1885]: time="2025-01-13T20:36:25.785717360Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-dh2v7,Uid:b03def6c-8a94-4b16-a78f-260a66386dbc,Namespace:kube-system,Attempt:0,} returns sandbox id \"fbfbcefbab608f2a0d32440e7db67373dd2ab4f3fb395cc9f6c44d2cd21fdb2d\"" Jan 13 20:36:25.790806 containerd[1885]: time="2025-01-13T20:36:25.790298112Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.4\"" Jan 13 20:36:25.809150 containerd[1885]: time="2025-01-13T20:36:25.809110594Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-jtbt4,Uid:f2a014ac-c1c0-40a0-9599-74a5aadfdf21,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\"" Jan 13 20:36:26.356268 kubelet[2289]: E0113 20:36:26.356222 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:27.222321 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount403790848.mount: Deactivated successfully. Jan 13 20:36:27.356707 kubelet[2289]: E0113 20:36:27.356669 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:27.990798 containerd[1885]: time="2025-01-13T20:36:27.990744275Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:27.993402 containerd[1885]: time="2025-01-13T20:36:27.993350826Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.4: active requests=0, bytes read=30230243" Jan 13 20:36:27.994637 containerd[1885]: time="2025-01-13T20:36:27.994602253Z" level=info msg="ImageCreate event name:\"sha256:ebf80573666f86f115452db568feb34f6f771c3bdc7bfed14b9577f992cfa300\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:28.003793 containerd[1885]: time="2025-01-13T20:36:28.003705873Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:1739b3febca392035bf6edfe31efdfa55226be7b57389b2001ae357f7dcb99cf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:28.005251 containerd[1885]: time="2025-01-13T20:36:28.004905238Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.4\" with image id \"sha256:ebf80573666f86f115452db568feb34f6f771c3bdc7bfed14b9577f992cfa300\", repo tag \"registry.k8s.io/kube-proxy:v1.31.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:1739b3febca392035bf6edfe31efdfa55226be7b57389b2001ae357f7dcb99cf\", size \"30229262\" in 2.214497618s" Jan 13 20:36:28.005251 containerd[1885]: time="2025-01-13T20:36:28.005025324Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.4\" returns image reference \"sha256:ebf80573666f86f115452db568feb34f6f771c3bdc7bfed14b9577f992cfa300\"" Jan 13 20:36:28.007717 containerd[1885]: time="2025-01-13T20:36:28.007686272Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jan 13 20:36:28.009038 containerd[1885]: time="2025-01-13T20:36:28.009005702Z" level=info msg="CreateContainer within sandbox \"fbfbcefbab608f2a0d32440e7db67373dd2ab4f3fb395cc9f6c44d2cd21fdb2d\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jan 13 20:36:28.039855 containerd[1885]: time="2025-01-13T20:36:28.039814777Z" level=info msg="CreateContainer within sandbox \"fbfbcefbab608f2a0d32440e7db67373dd2ab4f3fb395cc9f6c44d2cd21fdb2d\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"23a1f93542b0729815863d6314b07363ea277427e503348b6fd1b7db7813e1c7\"" Jan 13 20:36:28.042340 containerd[1885]: time="2025-01-13T20:36:28.040682421Z" level=info msg="StartContainer for \"23a1f93542b0729815863d6314b07363ea277427e503348b6fd1b7db7813e1c7\"" Jan 13 20:36:28.116747 systemd[1]: Started cri-containerd-23a1f93542b0729815863d6314b07363ea277427e503348b6fd1b7db7813e1c7.scope - libcontainer container 23a1f93542b0729815863d6314b07363ea277427e503348b6fd1b7db7813e1c7. Jan 13 20:36:28.174355 containerd[1885]: time="2025-01-13T20:36:28.174309589Z" level=info msg="StartContainer for \"23a1f93542b0729815863d6314b07363ea277427e503348b6fd1b7db7813e1c7\" returns successfully" Jan 13 20:36:28.357629 kubelet[2289]: E0113 20:36:28.357578 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:29.358110 kubelet[2289]: E0113 20:36:29.358064 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:29.766112 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount912767246.mount: Deactivated successfully. Jan 13 20:36:29.853521 containerd[1885]: time="2025-01-13T20:36:29.853447009Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:29.855073 containerd[1885]: time="2025-01-13T20:36:29.854922682Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Jan 13 20:36:29.858600 containerd[1885]: time="2025-01-13T20:36:29.858332111Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:29.861821 containerd[1885]: time="2025-01-13T20:36:29.861766722Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:29.862690 containerd[1885]: time="2025-01-13T20:36:29.862655594Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 1.854929519s" Jan 13 20:36:29.862765 containerd[1885]: time="2025-01-13T20:36:29.862697044Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Jan 13 20:36:29.865359 containerd[1885]: time="2025-01-13T20:36:29.865331024Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jan 13 20:36:29.894151 containerd[1885]: time="2025-01-13T20:36:29.894108025Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23\"" Jan 13 20:36:29.894915 containerd[1885]: time="2025-01-13T20:36:29.894848388Z" level=info msg="StartContainer for \"872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23\"" Jan 13 20:36:29.946681 systemd[1]: Started cri-containerd-872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23.scope - libcontainer container 872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23. Jan 13 20:36:29.982520 systemd[1]: cri-containerd-872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23.scope: Deactivated successfully. Jan 13 20:36:29.983129 containerd[1885]: time="2025-01-13T20:36:29.983022084Z" level=info msg="StartContainer for \"872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23\" returns successfully" Jan 13 20:36:30.255634 containerd[1885]: time="2025-01-13T20:36:30.255570140Z" level=info msg="shim disconnected" id=872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23 namespace=k8s.io Jan 13 20:36:30.255634 containerd[1885]: time="2025-01-13T20:36:30.255630538Z" level=warning msg="cleaning up after shim disconnected" id=872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23 namespace=k8s.io Jan 13 20:36:30.256021 containerd[1885]: time="2025-01-13T20:36:30.255642953Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jan 13 20:36:30.358489 kubelet[2289]: E0113 20:36:30.358415 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:30.605944 containerd[1885]: time="2025-01-13T20:36:30.605904429Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jan 13 20:36:30.616466 kubelet[2289]: I0113 20:36:30.616383 2289 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-dh2v7" podStartSLOduration=5.398536153 podStartE2EDuration="7.616361156s" podCreationTimestamp="2025-01-13 20:36:23 +0000 UTC" firstStartedPulling="2025-01-13 20:36:25.788924594 +0000 UTC m=+2.996777029" lastFinishedPulling="2025-01-13 20:36:28.006749584 +0000 UTC m=+5.214602032" observedRunningTime="2025-01-13 20:36:28.604665653 +0000 UTC m=+5.812518105" watchObservedRunningTime="2025-01-13 20:36:30.616361156 +0000 UTC m=+7.824213601" Jan 13 20:36:30.640543 systemd[1]: run-containerd-runc-k8s.io-872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23-runc.q2RQC4.mount: Deactivated successfully. Jan 13 20:36:30.640762 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-872956c1c43a2d7e067729be9573f624f7cda47fcdb32e0a90e2e0ca919dce23-rootfs.mount: Deactivated successfully. Jan 13 20:36:31.358649 kubelet[2289]: E0113 20:36:31.358600 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:32.359574 kubelet[2289]: E0113 20:36:32.359523 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:32.644911 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2784248549.mount: Deactivated successfully. Jan 13 20:36:33.359890 kubelet[2289]: E0113 20:36:33.359832 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:33.537268 containerd[1885]: time="2025-01-13T20:36:33.537210709Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:33.539581 containerd[1885]: time="2025-01-13T20:36:33.539528290Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Jan 13 20:36:33.541790 containerd[1885]: time="2025-01-13T20:36:33.541261442Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:33.544880 containerd[1885]: time="2025-01-13T20:36:33.544845702Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:33.547183 containerd[1885]: time="2025-01-13T20:36:33.547143989Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 2.941196112s" Jan 13 20:36:33.548047 containerd[1885]: time="2025-01-13T20:36:33.547405916Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Jan 13 20:36:33.549958 containerd[1885]: time="2025-01-13T20:36:33.549924688Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jan 13 20:36:33.579405 containerd[1885]: time="2025-01-13T20:36:33.579361752Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1\"" Jan 13 20:36:33.580259 containerd[1885]: time="2025-01-13T20:36:33.580225316Z" level=info msg="StartContainer for \"571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1\"" Jan 13 20:36:33.617914 systemd[1]: Started cri-containerd-571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1.scope - libcontainer container 571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1. Jan 13 20:36:33.649527 systemd[1]: cri-containerd-571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1.scope: Deactivated successfully. Jan 13 20:36:33.654831 containerd[1885]: time="2025-01-13T20:36:33.654724129Z" level=info msg="StartContainer for \"571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1\" returns successfully" Jan 13 20:36:33.676882 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1-rootfs.mount: Deactivated successfully. Jan 13 20:36:33.680178 kubelet[2289]: I0113 20:36:33.678851 2289 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Jan 13 20:36:33.822851 containerd[1885]: time="2025-01-13T20:36:33.822790223Z" level=info msg="shim disconnected" id=571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1 namespace=k8s.io Jan 13 20:36:33.822851 containerd[1885]: time="2025-01-13T20:36:33.822839255Z" level=warning msg="cleaning up after shim disconnected" id=571b5dac924f1c2ae882c1924e795a1d6318dd85c784c1767622bdefa60661e1 namespace=k8s.io Jan 13 20:36:33.822851 containerd[1885]: time="2025-01-13T20:36:33.822851011Z" level=info msg="cleaning up dead shim" namespace=k8s.io Jan 13 20:36:34.360184 kubelet[2289]: E0113 20:36:34.360136 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:34.618389 containerd[1885]: time="2025-01-13T20:36:34.618257221Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jan 13 20:36:34.666629 containerd[1885]: time="2025-01-13T20:36:34.666588646Z" level=info msg="CreateContainer within sandbox \"af9b21ea619012bb28d3c56214b5eadec6f2453462db3872a06f4cfd8c093cbf\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2\"" Jan 13 20:36:34.672532 containerd[1885]: time="2025-01-13T20:36:34.668272518Z" level=info msg="StartContainer for \"7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2\"" Jan 13 20:36:34.744947 systemd[1]: run-containerd-runc-k8s.io-7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2-runc.Ns7Nyb.mount: Deactivated successfully. Jan 13 20:36:34.755795 systemd[1]: Started cri-containerd-7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2.scope - libcontainer container 7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2. Jan 13 20:36:34.787013 containerd[1885]: time="2025-01-13T20:36:34.786969428Z" level=info msg="StartContainer for \"7e47bc1e4b298dfa951be18c005b8e2df77a3f6799b3e7a460f15bcb8ca5e5d2\" returns successfully" Jan 13 20:36:35.361207 kubelet[2289]: E0113 20:36:35.361154 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:35.800951 kubelet[2289]: I0113 20:36:35.800880 2289 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-jtbt4" podStartSLOduration=5.0631658999999996 podStartE2EDuration="12.800768565s" podCreationTimestamp="2025-01-13 20:36:23 +0000 UTC" firstStartedPulling="2025-01-13 20:36:25.810765558 +0000 UTC m=+3.018618000" lastFinishedPulling="2025-01-13 20:36:33.548368226 +0000 UTC m=+10.756220665" observedRunningTime="2025-01-13 20:36:35.639079957 +0000 UTC m=+12.846932402" watchObservedRunningTime="2025-01-13 20:36:35.800768565 +0000 UTC m=+13.008621008" Jan 13 20:36:35.810169 systemd[1]: Created slice kubepods-besteffort-poda509e8bd_07cd_4fbc_bb99_e3001a524551.slice - libcontainer container kubepods-besteffort-poda509e8bd_07cd_4fbc_bb99_e3001a524551.slice. Jan 13 20:36:35.846204 (udev-worker)[2787]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:35.868373 systemd-networkd[1727]: flannel.1: Link UP Jan 13 20:36:35.868382 systemd-networkd[1727]: flannel.1: Gained carrier Jan 13 20:36:35.870488 kubelet[2289]: I0113 20:36:35.870318 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cbxm7\" (UniqueName: \"kubernetes.io/projected/a509e8bd-07cd-4fbc-bb99-e3001a524551-kube-api-access-cbxm7\") pod \"nginx-deployment-8587fbcb89-264kj\" (UID: \"a509e8bd-07cd-4fbc-bb99-e3001a524551\") " pod="default/nginx-deployment-8587fbcb89-264kj" Jan 13 20:36:36.114537 containerd[1885]: time="2025-01-13T20:36:36.114105007Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-264kj,Uid:a509e8bd-07cd-4fbc-bb99-e3001a524551,Namespace:default,Attempt:0,}" Jan 13 20:36:36.145332 (udev-worker)[2788]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:36.145807 systemd-networkd[1727]: cni0: Link UP Jan 13 20:36:36.160374 (udev-worker)[2883]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:36.164865 kernel: cni0: port 1(veth4d84fe6b) entered blocking state Jan 13 20:36:36.164970 kernel: cni0: port 1(veth4d84fe6b) entered disabled state Jan 13 20:36:36.166035 kernel: veth4d84fe6b: entered allmulticast mode Jan 13 20:36:36.166895 kernel: veth4d84fe6b: entered promiscuous mode Jan 13 20:36:36.167023 systemd-networkd[1727]: veth4d84fe6b: Link UP Jan 13 20:36:36.169852 kernel: cni0: port 1(veth4d84fe6b) entered blocking state Jan 13 20:36:36.169916 kernel: cni0: port 1(veth4d84fe6b) entered forwarding state Jan 13 20:36:36.169945 kernel: cni0: port 1(veth4d84fe6b) entered disabled state Jan 13 20:36:36.177323 systemd-networkd[1727]: veth4d84fe6b: Gained carrier Jan 13 20:36:36.177543 kernel: cni0: port 1(veth4d84fe6b) entered blocking state Jan 13 20:36:36.177607 kernel: cni0: port 1(veth4d84fe6b) entered forwarding state Jan 13 20:36:36.178806 systemd-networkd[1727]: cni0: Gained carrier Jan 13 20:36:36.187140 containerd[1885]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000106628), "name":"cbr0", "type":"bridge"} Jan 13 20:36:36.187140 containerd[1885]: delegateAdd: netconf sent to delegate plugin: Jan 13 20:36:36.227456 containerd[1885]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:36:36.226404325Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 13 20:36:36.227456 containerd[1885]: time="2025-01-13T20:36:36.226466710Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 13 20:36:36.227456 containerd[1885]: time="2025-01-13T20:36:36.226487865Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:36.227456 containerd[1885]: time="2025-01-13T20:36:36.226694797Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:36.256718 systemd[1]: Started cri-containerd-d971d6f17b9550933306e5613d886507b54c3964fb9713e8bb76a650d5b52b2c.scope - libcontainer container d971d6f17b9550933306e5613d886507b54c3964fb9713e8bb76a650d5b52b2c. Jan 13 20:36:36.305567 containerd[1885]: time="2025-01-13T20:36:36.305488793Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-264kj,Uid:a509e8bd-07cd-4fbc-bb99-e3001a524551,Namespace:default,Attempt:0,} returns sandbox id \"d971d6f17b9550933306e5613d886507b54c3964fb9713e8bb76a650d5b52b2c\"" Jan 13 20:36:36.307433 containerd[1885]: time="2025-01-13T20:36:36.307396275Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jan 13 20:36:36.361604 kubelet[2289]: E0113 20:36:36.361554 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:37.201734 systemd-networkd[1727]: flannel.1: Gained IPv6LL Jan 13 20:36:37.362627 kubelet[2289]: E0113 20:36:37.362535 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:38.097891 systemd-networkd[1727]: veth4d84fe6b: Gained IPv6LL Jan 13 20:36:38.229753 systemd-networkd[1727]: cni0: Gained IPv6LL Jan 13 20:36:38.363906 kubelet[2289]: E0113 20:36:38.363791 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:39.364365 kubelet[2289]: E0113 20:36:39.364323 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:39.557486 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2184155498.mount: Deactivated successfully. Jan 13 20:36:40.364475 kubelet[2289]: E0113 20:36:40.364440 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:40.601789 ntpd[1856]: Listen normally on 7 flannel.1 192.168.1.0:123 Jan 13 20:36:40.602874 ntpd[1856]: 13 Jan 20:36:40 ntpd[1856]: Listen normally on 7 flannel.1 192.168.1.0:123 Jan 13 20:36:40.602874 ntpd[1856]: 13 Jan 20:36:40 ntpd[1856]: Listen normally on 8 cni0 192.168.1.1:123 Jan 13 20:36:40.602874 ntpd[1856]: 13 Jan 20:36:40 ntpd[1856]: Listen normally on 9 flannel.1 [fe80::b800:2bff:fe68:ca13%3]:123 Jan 13 20:36:40.602874 ntpd[1856]: 13 Jan 20:36:40 ntpd[1856]: Listen normally on 10 cni0 [fe80::10eb:17ff:feb6:5b1b%4]:123 Jan 13 20:36:40.602874 ntpd[1856]: 13 Jan 20:36:40 ntpd[1856]: Listen normally on 11 veth4d84fe6b [fe80::845b:15ff:fe54:e85f%5]:123 Jan 13 20:36:40.601873 ntpd[1856]: Listen normally on 8 cni0 192.168.1.1:123 Jan 13 20:36:40.601936 ntpd[1856]: Listen normally on 9 flannel.1 [fe80::b800:2bff:fe68:ca13%3]:123 Jan 13 20:36:40.602211 ntpd[1856]: Listen normally on 10 cni0 [fe80::10eb:17ff:feb6:5b1b%4]:123 Jan 13 20:36:40.602275 ntpd[1856]: Listen normally on 11 veth4d84fe6b [fe80::845b:15ff:fe54:e85f%5]:123 Jan 13 20:36:41.361436 containerd[1885]: time="2025-01-13T20:36:41.361377661Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:41.362923 containerd[1885]: time="2025-01-13T20:36:41.362740697Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=71036018" Jan 13 20:36:41.364624 containerd[1885]: time="2025-01-13T20:36:41.364270311Z" level=info msg="ImageCreate event name:\"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:41.368823 kubelet[2289]: E0113 20:36:41.368783 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:41.372540 containerd[1885]: time="2025-01-13T20:36:41.372123387Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:41.373338 containerd[1885]: time="2025-01-13T20:36:41.373175150Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 5.065738533s" Jan 13 20:36:41.373338 containerd[1885]: time="2025-01-13T20:36:41.373215793Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\"" Jan 13 20:36:41.375704 containerd[1885]: time="2025-01-13T20:36:41.375668551Z" level=info msg="CreateContainer within sandbox \"d971d6f17b9550933306e5613d886507b54c3964fb9713e8bb76a650d5b52b2c\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jan 13 20:36:41.393656 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4065738731.mount: Deactivated successfully. Jan 13 20:36:41.394265 containerd[1885]: time="2025-01-13T20:36:41.394224397Z" level=info msg="CreateContainer within sandbox \"d971d6f17b9550933306e5613d886507b54c3964fb9713e8bb76a650d5b52b2c\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b434c6409e7e0f18cfc6ab0cd0eaa67c32be0126a22e6f7cf1c2f35088e46c66\"" Jan 13 20:36:41.396908 containerd[1885]: time="2025-01-13T20:36:41.395759057Z" level=info msg="StartContainer for \"b434c6409e7e0f18cfc6ab0cd0eaa67c32be0126a22e6f7cf1c2f35088e46c66\"" Jan 13 20:36:41.449734 systemd[1]: Started cri-containerd-b434c6409e7e0f18cfc6ab0cd0eaa67c32be0126a22e6f7cf1c2f35088e46c66.scope - libcontainer container b434c6409e7e0f18cfc6ab0cd0eaa67c32be0126a22e6f7cf1c2f35088e46c66. Jan 13 20:36:41.480478 containerd[1885]: time="2025-01-13T20:36:41.480436825Z" level=info msg="StartContainer for \"b434c6409e7e0f18cfc6ab0cd0eaa67c32be0126a22e6f7cf1c2f35088e46c66\" returns successfully" Jan 13 20:36:41.662328 kubelet[2289]: I0113 20:36:41.662176 2289 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-264kj" podStartSLOduration=1.594743812 podStartE2EDuration="6.662160413s" podCreationTimestamp="2025-01-13 20:36:35 +0000 UTC" firstStartedPulling="2025-01-13 20:36:36.30700787 +0000 UTC m=+13.514860303" lastFinishedPulling="2025-01-13 20:36:41.374424466 +0000 UTC m=+18.582276904" observedRunningTime="2025-01-13 20:36:41.662023061 +0000 UTC m=+18.869875510" watchObservedRunningTime="2025-01-13 20:36:41.662160413 +0000 UTC m=+18.870012860" Jan 13 20:36:42.370410 kubelet[2289]: E0113 20:36:42.370270 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:43.354540 kubelet[2289]: E0113 20:36:43.354474 2289 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:43.371178 kubelet[2289]: E0113 20:36:43.371124 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:44.371348 kubelet[2289]: E0113 20:36:44.371300 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:45.044444 systemd[1]: Created slice kubepods-besteffort-podfa20560d_431e_46ac_8855_8938c874d227.slice - libcontainer container kubepods-besteffort-podfa20560d_431e_46ac_8855_8938c874d227.slice. Jan 13 20:36:45.229113 kubelet[2289]: I0113 20:36:45.229062 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s2tnp\" (UniqueName: \"kubernetes.io/projected/fa20560d-431e-46ac-8855-8938c874d227-kube-api-access-s2tnp\") pod \"nfs-server-provisioner-0\" (UID: \"fa20560d-431e-46ac-8855-8938c874d227\") " pod="default/nfs-server-provisioner-0" Jan 13 20:36:45.229269 kubelet[2289]: I0113 20:36:45.229128 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/fa20560d-431e-46ac-8855-8938c874d227-data\") pod \"nfs-server-provisioner-0\" (UID: \"fa20560d-431e-46ac-8855-8938c874d227\") " pod="default/nfs-server-provisioner-0" Jan 13 20:36:45.348104 containerd[1885]: time="2025-01-13T20:36:45.347754210Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fa20560d-431e-46ac-8855-8938c874d227,Namespace:default,Attempt:0,}" Jan 13 20:36:45.371792 kubelet[2289]: E0113 20:36:45.371756 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:45.382234 (udev-worker)[3043]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:36:45.384536 systemd-networkd[1727]: vethd333d0ba: Link UP Jan 13 20:36:45.389345 kernel: cni0: port 2(vethd333d0ba) entered blocking state Jan 13 20:36:45.389436 kernel: cni0: port 2(vethd333d0ba) entered disabled state Jan 13 20:36:45.389465 kernel: vethd333d0ba: entered allmulticast mode Jan 13 20:36:45.389513 kernel: vethd333d0ba: entered promiscuous mode Jan 13 20:36:45.400455 kernel: cni0: port 2(vethd333d0ba) entered blocking state Jan 13 20:36:45.400564 kernel: cni0: port 2(vethd333d0ba) entered forwarding state Jan 13 20:36:45.400249 systemd-networkd[1727]: vethd333d0ba: Gained carrier Jan 13 20:36:45.404200 containerd[1885]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000020938), "name":"cbr0", "type":"bridge"} Jan 13 20:36:45.404200 containerd[1885]: delegateAdd: netconf sent to delegate plugin: Jan 13 20:36:45.425529 containerd[1885]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:36:45.425417526Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 13 20:36:45.425529 containerd[1885]: time="2025-01-13T20:36:45.425482559Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 13 20:36:45.425846 containerd[1885]: time="2025-01-13T20:36:45.425786165Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:45.426642 containerd[1885]: time="2025-01-13T20:36:45.426598660Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:36:45.450255 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 13 20:36:45.462753 systemd[1]: Started cri-containerd-57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b.scope - libcontainer container 57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b. Jan 13 20:36:45.506521 containerd[1885]: time="2025-01-13T20:36:45.506448386Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fa20560d-431e-46ac-8855-8938c874d227,Namespace:default,Attempt:0,} returns sandbox id \"57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b\"" Jan 13 20:36:45.508247 containerd[1885]: time="2025-01-13T20:36:45.508215806Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jan 13 20:36:46.341745 systemd[1]: run-containerd-runc-k8s.io-57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b-runc.m9LzEg.mount: Deactivated successfully. Jan 13 20:36:46.372270 kubelet[2289]: E0113 20:36:46.372226 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:47.313720 systemd-networkd[1727]: vethd333d0ba: Gained IPv6LL Jan 13 20:36:47.372837 kubelet[2289]: E0113 20:36:47.372770 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:48.373614 kubelet[2289]: E0113 20:36:48.373564 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:49.326117 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3923210347.mount: Deactivated successfully. Jan 13 20:36:49.374668 kubelet[2289]: E0113 20:36:49.374606 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:49.601327 ntpd[1856]: Listen normally on 12 vethd333d0ba [fe80::c0ec:56ff:fee6:3e2b%6]:123 Jan 13 20:36:49.601797 ntpd[1856]: 13 Jan 20:36:49 ntpd[1856]: Listen normally on 12 vethd333d0ba [fe80::c0ec:56ff:fee6:3e2b%6]:123 Jan 13 20:36:50.375523 kubelet[2289]: E0113 20:36:50.375459 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:51.376321 kubelet[2289]: E0113 20:36:51.376253 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:51.675071 containerd[1885]: time="2025-01-13T20:36:51.674832105Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:51.677229 containerd[1885]: time="2025-01-13T20:36:51.677187036Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Jan 13 20:36:51.681581 containerd[1885]: time="2025-01-13T20:36:51.680265286Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:51.686416 containerd[1885]: time="2025-01-13T20:36:51.686353678Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:36:51.701401 containerd[1885]: time="2025-01-13T20:36:51.688811223Z" 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 6.180557052s" Jan 13 20:36:51.701401 containerd[1885]: time="2025-01-13T20:36:51.688861853Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Jan 13 20:36:51.710280 containerd[1885]: time="2025-01-13T20:36:51.710239675Z" level=info msg="CreateContainer within sandbox \"57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jan 13 20:36:51.763630 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4172623322.mount: Deactivated successfully. Jan 13 20:36:51.766982 containerd[1885]: time="2025-01-13T20:36:51.766939281Z" level=info msg="CreateContainer within sandbox \"57bbdf3f536c613ad285d98103b51f1ec9cf70913b75201a37bf464b0663bb2b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756\"" Jan 13 20:36:51.767713 containerd[1885]: time="2025-01-13T20:36:51.767658698Z" level=info msg="StartContainer for \"215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756\"" Jan 13 20:36:51.911060 systemd[1]: run-containerd-runc-k8s.io-215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756-runc.GlcloM.mount: Deactivated successfully. Jan 13 20:36:51.920491 systemd[1]: Started cri-containerd-215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756.scope - libcontainer container 215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756. Jan 13 20:36:51.961920 containerd[1885]: time="2025-01-13T20:36:51.955080032Z" level=info msg="StartContainer for \"215b05ec00b320c3930c12c1552a27abf1f367d8411e76761c8d38ed8b387756\" returns successfully" Jan 13 20:36:52.376829 kubelet[2289]: E0113 20:36:52.376781 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:52.678468 kubelet[2289]: I0113 20:36:52.677887 2289 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.481347012 podStartE2EDuration="7.677869292s" podCreationTimestamp="2025-01-13 20:36:45 +0000 UTC" firstStartedPulling="2025-01-13 20:36:45.507764361 +0000 UTC m=+22.715616791" lastFinishedPulling="2025-01-13 20:36:51.704286634 +0000 UTC m=+28.912139071" observedRunningTime="2025-01-13 20:36:52.677724831 +0000 UTC m=+29.885577280" watchObservedRunningTime="2025-01-13 20:36:52.677869292 +0000 UTC m=+29.885721741" Jan 13 20:36:53.377651 kubelet[2289]: E0113 20:36:53.377592 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:54.378312 kubelet[2289]: E0113 20:36:54.378254 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:55.378987 kubelet[2289]: E0113 20:36:55.378931 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:56.379467 kubelet[2289]: E0113 20:36:56.379409 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:57.379605 kubelet[2289]: E0113 20:36:57.379562 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:58.380639 kubelet[2289]: E0113 20:36:58.380579 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:59.381452 kubelet[2289]: E0113 20:36:59.381392 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:36:59.961115 update_engine[1866]: I20250113 20:36:59.961019 1866 update_attempter.cc:509] Updating boot flags... Jan 13 20:37:00.075761 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (3301) Jan 13 20:37:00.284134 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (3301) Jan 13 20:37:00.383817 kubelet[2289]: E0113 20:37:00.383772 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:01.384256 kubelet[2289]: E0113 20:37:01.384194 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:01.591436 systemd[1]: Created slice kubepods-besteffort-pod8bf3da3a_3ba9_45c4_aadc_0d76e1bafe79.slice - libcontainer container kubepods-besteffort-pod8bf3da3a_3ba9_45c4_aadc_0d76e1bafe79.slice. Jan 13 20:37:01.734569 kubelet[2289]: I0113 20:37:01.734340 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4mxdp\" (UniqueName: \"kubernetes.io/projected/8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79-kube-api-access-4mxdp\") pod \"test-pod-1\" (UID: \"8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79\") " pod="default/test-pod-1" Jan 13 20:37:01.734569 kubelet[2289]: I0113 20:37:01.734443 2289 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-df621d4b-54a7-439b-a9f9-e89a0a70590c\" (UniqueName: \"kubernetes.io/nfs/8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79-pvc-df621d4b-54a7-439b-a9f9-e89a0a70590c\") pod \"test-pod-1\" (UID: \"8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79\") " pod="default/test-pod-1" Jan 13 20:37:01.914065 kernel: FS-Cache: Loaded Jan 13 20:37:02.011911 kernel: RPC: Registered named UNIX socket transport module. Jan 13 20:37:02.012049 kernel: RPC: Registered udp transport module. Jan 13 20:37:02.012081 kernel: RPC: Registered tcp transport module. Jan 13 20:37:02.012947 kernel: RPC: Registered tcp-with-tls transport module. Jan 13 20:37:02.013047 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 13 20:37:02.384822 kubelet[2289]: E0113 20:37:02.384762 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:02.532931 kernel: NFS: Registering the id_resolver key type Jan 13 20:37:02.533088 kernel: Key type id_resolver registered Jan 13 20:37:02.533127 kernel: Key type id_legacy registered Jan 13 20:37:02.567934 nfsidmap[3503]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Jan 13 20:37:02.572365 nfsidmap[3504]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Jan 13 20:37:02.800809 containerd[1885]: time="2025-01-13T20:37:02.800762095Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79,Namespace:default,Attempt:0,}" Jan 13 20:37:02.858348 (udev-worker)[3303]: Network interface NamePolicy= disabled on kernel command line. Jan 13 20:37:02.860993 kernel: cni0: port 3(vethe564437c) entered blocking state Jan 13 20:37:02.861083 kernel: cni0: port 3(vethe564437c) entered disabled state Jan 13 20:37:02.861111 kernel: vethe564437c: entered allmulticast mode Jan 13 20:37:02.860375 systemd-networkd[1727]: vethe564437c: Link UP Jan 13 20:37:02.862059 kernel: vethe564437c: entered promiscuous mode Jan 13 20:37:02.868292 kernel: cni0: port 3(vethe564437c) entered blocking state Jan 13 20:37:02.868400 kernel: cni0: port 3(vethe564437c) entered forwarding state Jan 13 20:37:02.868646 systemd-networkd[1727]: vethe564437c: Gained carrier Jan 13 20:37:02.871639 containerd[1885]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Jan 13 20:37:02.871639 containerd[1885]: delegateAdd: netconf sent to delegate plugin: Jan 13 20:37:02.899606 containerd[1885]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2025-01-13T20:37:02.899430571Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jan 13 20:37:02.901943 containerd[1885]: time="2025-01-13T20:37:02.900071709Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jan 13 20:37:02.901943 containerd[1885]: time="2025-01-13T20:37:02.900120366Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:37:02.901943 containerd[1885]: time="2025-01-13T20:37:02.900226832Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jan 13 20:37:02.928167 systemd[1]: run-containerd-runc-k8s.io-1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886-runc.Wd7qEw.mount: Deactivated successfully. Jan 13 20:37:02.936734 systemd[1]: Started cri-containerd-1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886.scope - libcontainer container 1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886. Jan 13 20:37:02.989889 containerd[1885]: time="2025-01-13T20:37:02.989857167Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8bf3da3a-3ba9-45c4-aadc-0d76e1bafe79,Namespace:default,Attempt:0,} returns sandbox id \"1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886\"" Jan 13 20:37:02.992452 containerd[1885]: time="2025-01-13T20:37:02.992416815Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jan 13 20:37:03.354347 kubelet[2289]: E0113 20:37:03.354307 2289 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:03.354762 containerd[1885]: time="2025-01-13T20:37:03.354714167Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jan 13 20:37:03.362180 containerd[1885]: time="2025-01-13T20:37:03.362113071Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jan 13 20:37:03.375030 containerd[1885]: time="2025-01-13T20:37:03.374984080Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:eca1d1ff18c7af45f86b7e0b572090f563a676ddca3da2ecff678390366335ad\", size \"71035896\" in 382.528472ms" Jan 13 20:37:03.375030 containerd[1885]: time="2025-01-13T20:37:03.375025089Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:29ef6eaebfc53650f3a4609edbf9d35e866f56b2c5e01d32d93439031b300f0b\"" Jan 13 20:37:03.377099 containerd[1885]: time="2025-01-13T20:37:03.377062587Z" level=info msg="CreateContainer within sandbox \"1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jan 13 20:37:03.385682 kubelet[2289]: E0113 20:37:03.385645 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:03.399394 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3405471213.mount: Deactivated successfully. Jan 13 20:37:03.407779 containerd[1885]: time="2025-01-13T20:37:03.407726811Z" level=info msg="CreateContainer within sandbox \"1ebd72a76660e7d693294a8b24b4408e91237086fb6d898f70171d5ce6fbb886\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"e758f8eff0a9e420d8c4aa177f92611b336d40ddec8f304e3924244739afc7ea\"" Jan 13 20:37:03.409330 containerd[1885]: time="2025-01-13T20:37:03.408317279Z" level=info msg="StartContainer for \"e758f8eff0a9e420d8c4aa177f92611b336d40ddec8f304e3924244739afc7ea\"" Jan 13 20:37:03.441752 systemd[1]: Started cri-containerd-e758f8eff0a9e420d8c4aa177f92611b336d40ddec8f304e3924244739afc7ea.scope - libcontainer container e758f8eff0a9e420d8c4aa177f92611b336d40ddec8f304e3924244739afc7ea. Jan 13 20:37:03.479175 containerd[1885]: time="2025-01-13T20:37:03.479131250Z" level=info msg="StartContainer for \"e758f8eff0a9e420d8c4aa177f92611b336d40ddec8f304e3924244739afc7ea\" returns successfully" Jan 13 20:37:03.698302 kubelet[2289]: I0113 20:37:03.698153 2289 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.314192021 podStartE2EDuration="18.698136202s" podCreationTimestamp="2025-01-13 20:36:45 +0000 UTC" firstStartedPulling="2025-01-13 20:37:02.991756338 +0000 UTC m=+40.199608775" lastFinishedPulling="2025-01-13 20:37:03.375700526 +0000 UTC m=+40.583552956" observedRunningTime="2025-01-13 20:37:03.697949753 +0000 UTC m=+40.905802201" watchObservedRunningTime="2025-01-13 20:37:03.698136202 +0000 UTC m=+40.905988650" Jan 13 20:37:04.386400 kubelet[2289]: E0113 20:37:04.386317 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:04.529910 systemd-networkd[1727]: vethe564437c: Gained IPv6LL Jan 13 20:37:05.387346 kubelet[2289]: E0113 20:37:05.387292 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:06.387813 kubelet[2289]: E0113 20:37:06.387756 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:06.600789 ntpd[1856]: Listen normally on 13 vethe564437c [fe80::8c7b:9bff:fea8:2585%7]:123 Jan 13 20:37:06.601261 ntpd[1856]: 13 Jan 20:37:06 ntpd[1856]: Listen normally on 13 vethe564437c [fe80::8c7b:9bff:fea8:2585%7]:123 Jan 13 20:37:07.388406 kubelet[2289]: E0113 20:37:07.388346 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:08.389227 kubelet[2289]: E0113 20:37:08.389180 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:09.390216 kubelet[2289]: E0113 20:37:09.390158 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:10.390849 kubelet[2289]: E0113 20:37:10.390803 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:11.391221 kubelet[2289]: E0113 20:37:11.391164 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:12.392271 kubelet[2289]: E0113 20:37:12.392211 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:13.393377 kubelet[2289]: E0113 20:37:13.393332 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:14.394176 kubelet[2289]: E0113 20:37:14.394119 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:15.394362 kubelet[2289]: E0113 20:37:15.394313 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:16.395031 kubelet[2289]: E0113 20:37:16.394979 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:17.395196 kubelet[2289]: E0113 20:37:17.395145 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:18.396015 kubelet[2289]: E0113 20:37:18.395962 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:19.396857 kubelet[2289]: E0113 20:37:19.396811 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:20.397237 kubelet[2289]: E0113 20:37:20.397180 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:21.397512 kubelet[2289]: E0113 20:37:21.397398 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:22.397859 kubelet[2289]: E0113 20:37:22.397802 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:23.354525 kubelet[2289]: E0113 20:37:23.354472 2289 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:23.398080 kubelet[2289]: E0113 20:37:23.398044 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:24.321476 kubelet[2289]: E0113 20:37:24.321399 2289 controller.go:195] "Failed to update lease" err="Put \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Jan 13 20:37:24.398854 kubelet[2289]: E0113 20:37:24.398811 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:25.399829 kubelet[2289]: E0113 20:37:25.399773 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:26.401699 kubelet[2289]: E0113 20:37:26.401026 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:27.402236 kubelet[2289]: E0113 20:37:27.402178 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:28.402867 kubelet[2289]: E0113 20:37:28.402814 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:29.403025 kubelet[2289]: E0113 20:37:29.402963 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:30.403515 kubelet[2289]: E0113 20:37:30.403451 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:31.404645 kubelet[2289]: E0113 20:37:31.404589 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:32.405409 kubelet[2289]: E0113 20:37:32.405355 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:33.406384 kubelet[2289]: E0113 20:37:33.406330 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:34.322444 kubelet[2289]: E0113 20:37:34.322377 2289 controller.go:195] "Failed to update lease" err="Put \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Jan 13 20:37:34.406618 kubelet[2289]: E0113 20:37:34.406574 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:35.406954 kubelet[2289]: E0113 20:37:35.406897 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:36.407670 kubelet[2289]: E0113 20:37:36.407612 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:37.408109 kubelet[2289]: E0113 20:37:37.408053 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:38.408724 kubelet[2289]: E0113 20:37:38.408667 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:39.408930 kubelet[2289]: E0113 20:37:39.408873 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:40.409738 kubelet[2289]: E0113 20:37:40.409693 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:41.410377 kubelet[2289]: E0113 20:37:41.410265 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:42.411270 kubelet[2289]: E0113 20:37:42.411219 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:43.355236 kubelet[2289]: E0113 20:37:43.355186 2289 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:43.411747 kubelet[2289]: E0113 20:37:43.411703 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:44.323020 kubelet[2289]: E0113 20:37:44.322953 2289 controller.go:195] "Failed to update lease" err="Put \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Jan 13 20:37:44.412197 kubelet[2289]: E0113 20:37:44.412142 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:45.413201 kubelet[2289]: E0113 20:37:45.413143 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:46.414364 kubelet[2289]: E0113 20:37:46.414225 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:47.414822 kubelet[2289]: E0113 20:37:47.414761 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:48.414980 kubelet[2289]: E0113 20:37:48.414917 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:49.415269 kubelet[2289]: E0113 20:37:49.415210 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:50.416370 kubelet[2289]: E0113 20:37:50.416316 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:51.417779 kubelet[2289]: E0113 20:37:51.417722 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:51.693638 kubelet[2289]: E0113 20:37:51.691845 2289 controller.go:195] "Failed to update lease" err="Put \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": unexpected EOF" Jan 13 20:37:51.700605 kubelet[2289]: E0113 20:37:51.700265 2289 controller.go:195] "Failed to update lease" err="Put \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": read tcp 172.31.27.123:35272->172.31.19.28:6443: read: connection reset by peer" Jan 13 20:37:51.700605 kubelet[2289]: I0113 20:37:51.700304 2289 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Jan 13 20:37:51.701017 kubelet[2289]: E0113 20:37:51.700979 2289 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": dial tcp 172.31.19.28:6443: connect: connection refused" interval="200ms" Jan 13 20:37:51.902678 kubelet[2289]: E0113 20:37:51.902594 2289 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": dial tcp 172.31.19.28:6443: connect: connection refused" interval="400ms" Jan 13 20:37:52.304049 kubelet[2289]: E0113 20:37:52.303990 2289 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": dial tcp 172.31.19.28:6443: connect: connection refused" interval="800ms" Jan 13 20:37:52.418638 kubelet[2289]: E0113 20:37:52.418578 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:53.418834 kubelet[2289]: E0113 20:37:53.418775 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:54.419198 kubelet[2289]: E0113 20:37:54.419141 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:55.419884 kubelet[2289]: E0113 20:37:55.419820 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:56.420476 kubelet[2289]: E0113 20:37:56.420420 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:57.421095 kubelet[2289]: E0113 20:37:57.421036 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:58.421625 kubelet[2289]: E0113 20:37:58.421570 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:37:59.422251 kubelet[2289]: E0113 20:37:59.422201 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:00.422519 kubelet[2289]: E0113 20:38:00.422453 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:01.422991 kubelet[2289]: E0113 20:38:01.422935 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:02.424128 kubelet[2289]: E0113 20:38:02.424071 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:03.106145 kubelet[2289]: E0113 20:38:03.106093 2289 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.19.28:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.27.123?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="1.6s" Jan 13 20:38:03.354515 kubelet[2289]: E0113 20:38:03.354460 2289 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:03.426381 kubelet[2289]: E0113 20:38:03.426238 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jan 13 20:38:04.427468 kubelet[2289]: E0113 20:38:04.427406 2289 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"