Nov 12 22:23:31.230255 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 12 22:23:31.230329 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Tue Nov 12 21:07:55 -00 2024 Nov 12 22:23:31.230363 kernel: KASLR disabled due to lack of seed Nov 12 22:23:31.230383 kernel: efi: EFI v2.7 by EDK II Nov 12 22:23:31.230402 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x78503d98 Nov 12 22:23:31.230422 kernel: secureboot: Secure boot disabled Nov 12 22:23:31.230442 kernel: ACPI: Early table checksum verification disabled Nov 12 22:23:31.230461 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 12 22:23:31.230522 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 12 22:23:31.230542 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 12 22:23:31.230573 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 12 22:23:31.230592 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 12 22:23:31.230608 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 12 22:23:31.230624 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 12 22:23:31.230642 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 12 22:23:31.230664 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 12 22:23:31.230681 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 12 22:23:31.230697 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 12 22:23:31.230713 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 12 22:23:31.230729 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 12 22:23:31.230745 kernel: printk: bootconsole [uart0] enabled Nov 12 22:23:31.230761 kernel: NUMA: Failed to initialise from firmware Nov 12 22:23:31.230778 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:23:31.230794 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 12 22:23:31.230810 kernel: Zone ranges: Nov 12 22:23:31.230827 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 12 22:23:31.230847 kernel: DMA32 empty Nov 12 22:23:31.230863 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 12 22:23:31.230880 kernel: Movable zone start for each node Nov 12 22:23:31.230896 kernel: Early memory node ranges Nov 12 22:23:31.233635 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 12 22:23:31.233657 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 12 22:23:31.233675 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 12 22:23:31.233692 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 12 22:23:31.233709 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 12 22:23:31.233726 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 12 22:23:31.233744 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 12 22:23:31.233761 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 12 22:23:31.233797 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 22:23:31.233817 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 12 22:23:31.233842 kernel: psci: probing for conduit method from ACPI. Nov 12 22:23:31.233862 kernel: psci: PSCIv1.0 detected in firmware. Nov 12 22:23:31.233881 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 22:23:31.233904 kernel: psci: Trusted OS migration not required Nov 12 22:23:31.233923 kernel: psci: SMC Calling Convention v1.1 Nov 12 22:23:31.233941 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 22:23:31.233959 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 22:23:31.233979 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 12 22:23:31.233996 kernel: Detected PIPT I-cache on CPU0 Nov 12 22:23:31.234014 kernel: CPU features: detected: GIC system register CPU interface Nov 12 22:23:31.234032 kernel: CPU features: detected: Spectre-v2 Nov 12 22:23:31.234049 kernel: CPU features: detected: Spectre-v3a Nov 12 22:23:31.234068 kernel: CPU features: detected: Spectre-BHB Nov 12 22:23:31.234086 kernel: CPU features: detected: ARM erratum 1742098 Nov 12 22:23:31.234104 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 12 22:23:31.234132 kernel: alternatives: applying boot alternatives Nov 12 22:23:31.234153 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:23:31.234174 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 22:23:31.234193 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 22:23:31.234212 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 22:23:31.234230 kernel: Fallback order for Node 0: 0 Nov 12 22:23:31.234249 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 12 22:23:31.234268 kernel: Policy zone: Normal Nov 12 22:23:31.234287 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 22:23:31.234306 kernel: software IO TLB: area num 2. Nov 12 22:23:31.234334 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 12 22:23:31.234355 kernel: Memory: 3819960K/4030464K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39680K init, 897K bss, 210504K reserved, 0K cma-reserved) Nov 12 22:23:31.234375 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 12 22:23:31.234395 kernel: trace event string verifier disabled Nov 12 22:23:31.234416 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 22:23:31.234435 kernel: rcu: RCU event tracing is enabled. Nov 12 22:23:31.234453 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 12 22:23:31.238590 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 22:23:31.238633 kernel: Tracing variant of Tasks RCU enabled. Nov 12 22:23:31.238651 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 22:23:31.238669 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 12 22:23:31.238699 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 22:23:31.238717 kernel: GICv3: 96 SPIs implemented Nov 12 22:23:31.238734 kernel: GICv3: 0 Extended SPIs implemented Nov 12 22:23:31.238751 kernel: Root IRQ handler: gic_handle_irq Nov 12 22:23:31.238768 kernel: GICv3: GICv3 features: 16 PPIs Nov 12 22:23:31.238785 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 12 22:23:31.238802 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 12 22:23:31.238819 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 22:23:31.238838 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 12 22:23:31.238855 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 12 22:23:31.238873 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 12 22:23:31.238891 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 12 22:23:31.238914 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 22:23:31.238932 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 12 22:23:31.238950 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 12 22:23:31.238968 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 12 22:23:31.238988 kernel: Console: colour dummy device 80x25 Nov 12 22:23:31.239007 kernel: printk: console [tty1] enabled Nov 12 22:23:31.239025 kernel: ACPI: Core revision 20230628 Nov 12 22:23:31.239044 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 12 22:23:31.239063 kernel: pid_max: default: 32768 minimum: 301 Nov 12 22:23:31.239081 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 22:23:31.239104 kernel: landlock: Up and running. Nov 12 22:23:31.239122 kernel: SELinux: Initializing. Nov 12 22:23:31.239141 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:23:31.239161 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 22:23:31.239180 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:23:31.239200 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 22:23:31.239219 kernel: rcu: Hierarchical SRCU implementation. Nov 12 22:23:31.239237 kernel: rcu: Max phase no-delay instances is 400. Nov 12 22:23:31.239256 kernel: Platform MSI: ITS@0x10080000 domain created Nov 12 22:23:31.239280 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 12 22:23:31.239298 kernel: Remapping and enabling EFI services. Nov 12 22:23:31.239317 kernel: smp: Bringing up secondary CPUs ... Nov 12 22:23:31.239335 kernel: Detected PIPT I-cache on CPU1 Nov 12 22:23:31.239354 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 12 22:23:31.239372 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 12 22:23:31.239391 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 12 22:23:31.239409 kernel: smp: Brought up 1 node, 2 CPUs Nov 12 22:23:31.239427 kernel: SMP: Total of 2 processors activated. Nov 12 22:23:31.239450 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 22:23:31.242549 kernel: CPU features: detected: 32-bit EL1 Support Nov 12 22:23:31.242613 kernel: CPU features: detected: CRC32 instructions Nov 12 22:23:31.242638 kernel: CPU: All CPU(s) started at EL1 Nov 12 22:23:31.242657 kernel: alternatives: applying system-wide alternatives Nov 12 22:23:31.242676 kernel: devtmpfs: initialized Nov 12 22:23:31.242695 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 22:23:31.242715 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 12 22:23:31.242734 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 22:23:31.242758 kernel: SMBIOS 3.0.0 present. Nov 12 22:23:31.242777 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 12 22:23:31.242795 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 22:23:31.242814 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 22:23:31.242833 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 22:23:31.242852 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 22:23:31.242870 kernel: audit: initializing netlink subsys (disabled) Nov 12 22:23:31.242895 kernel: audit: type=2000 audit(0.221:1): state=initialized audit_enabled=0 res=1 Nov 12 22:23:31.242914 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 22:23:31.242933 kernel: cpuidle: using governor menu Nov 12 22:23:31.242951 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 22:23:31.242970 kernel: ASID allocator initialised with 65536 entries Nov 12 22:23:31.242988 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 22:23:31.243007 kernel: Serial: AMBA PL011 UART driver Nov 12 22:23:31.243026 kernel: Modules: 17440 pages in range for non-PLT usage Nov 12 22:23:31.243044 kernel: Modules: 508960 pages in range for PLT usage Nov 12 22:23:31.243062 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 22:23:31.243085 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 22:23:31.243103 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 22:23:31.243122 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 22:23:31.243140 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 22:23:31.243158 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 22:23:31.243176 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 22:23:31.243194 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 22:23:31.243212 kernel: ACPI: Added _OSI(Module Device) Nov 12 22:23:31.243231 kernel: ACPI: Added _OSI(Processor Device) Nov 12 22:23:31.243253 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 22:23:31.243271 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 22:23:31.243289 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 22:23:31.243308 kernel: ACPI: Interpreter enabled Nov 12 22:23:31.243326 kernel: ACPI: Using GIC for interrupt routing Nov 12 22:23:31.243344 kernel: ACPI: MCFG table detected, 1 entries Nov 12 22:23:31.243363 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 12 22:23:31.245762 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 22:23:31.246055 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 22:23:31.246272 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 22:23:31.246516 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 12 22:23:31.246738 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 12 22:23:31.246767 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 12 22:23:31.246787 kernel: acpiphp: Slot [1] registered Nov 12 22:23:31.246806 kernel: acpiphp: Slot [2] registered Nov 12 22:23:31.246824 kernel: acpiphp: Slot [3] registered Nov 12 22:23:31.246853 kernel: acpiphp: Slot [4] registered Nov 12 22:23:31.246871 kernel: acpiphp: Slot [5] registered Nov 12 22:23:31.246889 kernel: acpiphp: Slot [6] registered Nov 12 22:23:31.246908 kernel: acpiphp: Slot [7] registered Nov 12 22:23:31.246926 kernel: acpiphp: Slot [8] registered Nov 12 22:23:31.246945 kernel: acpiphp: Slot [9] registered Nov 12 22:23:31.246963 kernel: acpiphp: Slot [10] registered Nov 12 22:23:31.246982 kernel: acpiphp: Slot [11] registered Nov 12 22:23:31.247001 kernel: acpiphp: Slot [12] registered Nov 12 22:23:31.247024 kernel: acpiphp: Slot [13] registered Nov 12 22:23:31.247043 kernel: acpiphp: Slot [14] registered Nov 12 22:23:31.247061 kernel: acpiphp: Slot [15] registered Nov 12 22:23:31.247079 kernel: acpiphp: Slot [16] registered Nov 12 22:23:31.247098 kernel: acpiphp: Slot [17] registered Nov 12 22:23:31.247116 kernel: acpiphp: Slot [18] registered Nov 12 22:23:31.247135 kernel: acpiphp: Slot [19] registered Nov 12 22:23:31.247153 kernel: acpiphp: Slot [20] registered Nov 12 22:23:31.247171 kernel: acpiphp: Slot [21] registered Nov 12 22:23:31.248844 kernel: acpiphp: Slot [22] registered Nov 12 22:23:31.248885 kernel: acpiphp: Slot [23] registered Nov 12 22:23:31.248904 kernel: acpiphp: Slot [24] registered Nov 12 22:23:31.248923 kernel: acpiphp: Slot [25] registered Nov 12 22:23:31.251600 kernel: acpiphp: Slot [26] registered Nov 12 22:23:31.251620 kernel: acpiphp: Slot [27] registered Nov 12 22:23:31.251640 kernel: acpiphp: Slot [28] registered Nov 12 22:23:31.251660 kernel: acpiphp: Slot [29] registered Nov 12 22:23:31.251679 kernel: acpiphp: Slot [30] registered Nov 12 22:23:31.251699 kernel: acpiphp: Slot [31] registered Nov 12 22:23:31.251728 kernel: PCI host bridge to bus 0000:00 Nov 12 22:23:31.252028 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 12 22:23:31.252251 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 22:23:31.253543 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 12 22:23:31.253844 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 12 22:23:31.254130 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 12 22:23:31.254386 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 12 22:23:31.256286 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 12 22:23:31.260598 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 12 22:23:31.260886 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 12 22:23:31.261128 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:23:31.261459 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 12 22:23:31.261806 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 12 22:23:31.262055 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 12 22:23:31.262335 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 12 22:23:31.262595 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 22:23:31.262817 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 12 22:23:31.263037 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 12 22:23:31.263284 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 12 22:23:31.264677 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 12 22:23:31.265022 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 12 22:23:31.265249 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 12 22:23:31.265524 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 22:23:31.265782 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 12 22:23:31.265818 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 22:23:31.265838 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 22:23:31.265858 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 22:23:31.265877 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 22:23:31.265910 kernel: iommu: Default domain type: Translated Nov 12 22:23:31.265930 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 22:23:31.265948 kernel: efivars: Registered efivars operations Nov 12 22:23:31.265968 kernel: vgaarb: loaded Nov 12 22:23:31.265986 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 22:23:31.266005 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 22:23:31.266025 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 22:23:31.266044 kernel: pnp: PnP ACPI init Nov 12 22:23:31.266341 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 12 22:23:31.266392 kernel: pnp: PnP ACPI: found 1 devices Nov 12 22:23:31.266412 kernel: NET: Registered PF_INET protocol family Nov 12 22:23:31.266432 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 22:23:31.266452 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 22:23:31.266755 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 22:23:31.266793 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 22:23:31.266813 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 22:23:31.269047 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 22:23:31.269088 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:23:31.269108 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 22:23:31.269128 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 22:23:31.269149 kernel: PCI: CLS 0 bytes, default 64 Nov 12 22:23:31.269169 kernel: kvm [1]: HYP mode not available Nov 12 22:23:31.269188 kernel: Initialise system trusted keyrings Nov 12 22:23:31.269209 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 22:23:31.269229 kernel: Key type asymmetric registered Nov 12 22:23:31.269247 kernel: Asymmetric key parser 'x509' registered Nov 12 22:23:31.269277 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 22:23:31.269298 kernel: io scheduler mq-deadline registered Nov 12 22:23:31.269317 kernel: io scheduler kyber registered Nov 12 22:23:31.269335 kernel: io scheduler bfq registered Nov 12 22:23:31.269680 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 12 22:23:31.269722 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 22:23:31.269742 kernel: ACPI: button: Power Button [PWRB] Nov 12 22:23:31.269762 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 12 22:23:31.269781 kernel: ACPI: button: Sleep Button [SLPB] Nov 12 22:23:31.269813 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 22:23:31.269833 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 12 22:23:31.270091 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 12 22:23:31.270130 kernel: printk: console [ttyS0] disabled Nov 12 22:23:31.270150 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 12 22:23:31.270169 kernel: printk: console [ttyS0] enabled Nov 12 22:23:31.270188 kernel: printk: bootconsole [uart0] disabled Nov 12 22:23:31.270209 kernel: thunder_xcv, ver 1.0 Nov 12 22:23:31.270228 kernel: thunder_bgx, ver 1.0 Nov 12 22:23:31.270255 kernel: nicpf, ver 1.0 Nov 12 22:23:31.270274 kernel: nicvf, ver 1.0 Nov 12 22:23:31.270597 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 22:23:31.270866 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T22:23:30 UTC (1731450210) Nov 12 22:23:31.270904 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 22:23:31.270926 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 12 22:23:31.270946 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 22:23:31.271027 kernel: watchdog: Hard watchdog permanently disabled Nov 12 22:23:31.271072 kernel: NET: Registered PF_INET6 protocol family Nov 12 22:23:31.271092 kernel: Segment Routing with IPv6 Nov 12 22:23:31.271112 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 22:23:31.271132 kernel: NET: Registered PF_PACKET protocol family Nov 12 22:23:31.271151 kernel: Key type dns_resolver registered Nov 12 22:23:31.271170 kernel: registered taskstats version 1 Nov 12 22:23:31.271190 kernel: Loading compiled-in X.509 certificates Nov 12 22:23:31.271209 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 27dd0d090d7a0971a24582c9198f7e80123ea69f' Nov 12 22:23:31.271227 kernel: Key type .fscrypt registered Nov 12 22:23:31.271253 kernel: Key type fscrypt-provisioning registered Nov 12 22:23:31.271271 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 22:23:31.271290 kernel: ima: Allocated hash algorithm: sha1 Nov 12 22:23:31.271308 kernel: ima: No architecture policies found Nov 12 22:23:31.271328 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 22:23:31.271347 kernel: clk: Disabling unused clocks Nov 12 22:23:31.271365 kernel: Freeing unused kernel memory: 39680K Nov 12 22:23:31.271384 kernel: Run /init as init process Nov 12 22:23:31.271403 kernel: with arguments: Nov 12 22:23:31.271427 kernel: /init Nov 12 22:23:31.271446 kernel: with environment: Nov 12 22:23:31.271500 kernel: HOME=/ Nov 12 22:23:31.271562 kernel: TERM=linux Nov 12 22:23:31.271581 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 22:23:31.271606 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:23:31.271630 systemd[1]: Detected virtualization amazon. Nov 12 22:23:31.271651 systemd[1]: Detected architecture arm64. Nov 12 22:23:31.271680 systemd[1]: Running in initrd. Nov 12 22:23:31.271701 systemd[1]: No hostname configured, using default hostname. Nov 12 22:23:31.271720 systemd[1]: Hostname set to . Nov 12 22:23:31.271742 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:23:31.271762 systemd[1]: Queued start job for default target initrd.target. Nov 12 22:23:31.271783 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:31.271803 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:31.271825 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 22:23:31.271851 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:23:31.271872 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 22:23:31.271892 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 22:23:31.271916 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 12 22:23:31.271938 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 22:23:31.271958 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:31.271983 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:31.272004 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:23:31.272024 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:23:31.272044 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:23:31.272064 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:23:31.272084 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:23:31.272105 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:23:31.272125 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 22:23:31.272145 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 22:23:31.272170 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:31.272191 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:31.272211 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:31.272232 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:23:31.272252 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 22:23:31.272272 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:23:31.272293 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 22:23:31.272313 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 22:23:31.272333 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:23:31.272360 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:23:31.272380 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:31.272400 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 22:23:31.272421 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:31.272441 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 22:23:31.274267 systemd-journald[251]: Collecting audit messages is disabled. Nov 12 22:23:31.274350 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:23:31.274374 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 12 22:23:31.274403 kernel: Bridge firewalling registered Nov 12 22:23:31.274426 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:31.274448 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:23:31.274506 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:31.274534 systemd-journald[251]: Journal started Nov 12 22:23:31.274576 systemd-journald[251]: Runtime Journal (/run/log/journal/ec2257c6fbb6d730f7654b1ef8afca93) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:23:31.218452 systemd-modules-load[252]: Inserted module 'overlay' Nov 12 22:23:31.245846 systemd-modules-load[252]: Inserted module 'br_netfilter' Nov 12 22:23:31.285862 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:31.292636 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:23:31.310949 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:23:31.316546 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:23:31.331262 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:23:31.334974 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:31.371446 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:31.375536 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:31.401962 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:23:31.411416 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:31.429123 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 22:23:31.471946 dracut-cmdline[290]: dracut-dracut-053 Nov 12 22:23:31.479307 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=054b3f497d0699ec5dd6f755e221ed9e2d4f35054d20dd4fb5abe997efb88cfb Nov 12 22:23:31.490237 systemd-resolved[288]: Positive Trust Anchors: Nov 12 22:23:31.490284 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:23:31.490347 systemd-resolved[288]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 12 22:23:31.645496 kernel: SCSI subsystem initialized Nov 12 22:23:31.651507 kernel: Loading iSCSI transport class v2.0-870. Nov 12 22:23:31.663517 kernel: iscsi: registered transport (tcp) Nov 12 22:23:31.686525 kernel: iscsi: registered transport (qla4xxx) Nov 12 22:23:31.686632 kernel: QLogic iSCSI HBA Driver Nov 12 22:23:31.744500 kernel: random: crng init done Nov 12 22:23:31.745169 systemd-resolved[288]: Defaulting to hostname 'linux'. Nov 12 22:23:31.748538 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:23:31.752619 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:31.779551 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 22:23:31.792893 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 22:23:31.828512 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 22:23:31.828588 kernel: device-mapper: uevent: version 1.0.3 Nov 12 22:23:31.831515 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 22:23:31.918516 kernel: raid6: neonx8 gen() 6522 MB/s Nov 12 22:23:31.924564 kernel: raid6: neonx4 gen() 6317 MB/s Nov 12 22:23:31.937535 kernel: raid6: neonx2 gen() 5305 MB/s Nov 12 22:23:31.954538 kernel: raid6: neonx1 gen() 3889 MB/s Nov 12 22:23:31.971567 kernel: raid6: int64x8 gen() 3733 MB/s Nov 12 22:23:31.988529 kernel: raid6: int64x4 gen() 3615 MB/s Nov 12 22:23:32.005540 kernel: raid6: int64x2 gen() 3505 MB/s Nov 12 22:23:32.023358 kernel: raid6: int64x1 gen() 2733 MB/s Nov 12 22:23:32.023440 kernel: raid6: using algorithm neonx8 gen() 6522 MB/s Nov 12 22:23:32.041360 kernel: raid6: .... xor() 4836 MB/s, rmw enabled Nov 12 22:23:32.041457 kernel: raid6: using neon recovery algorithm Nov 12 22:23:32.050999 kernel: xor: measuring software checksum speed Nov 12 22:23:32.051075 kernel: 8regs : 11025 MB/sec Nov 12 22:23:32.052103 kernel: 32regs : 11978 MB/sec Nov 12 22:23:32.053295 kernel: arm64_neon : 9479 MB/sec Nov 12 22:23:32.053333 kernel: xor: using function: 32regs (11978 MB/sec) Nov 12 22:23:32.138520 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 22:23:32.156992 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:23:32.168767 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:32.207256 systemd-udevd[472]: Using default interface naming scheme 'v255'. Nov 12 22:23:32.217051 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:32.228752 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 22:23:32.266517 dracut-pre-trigger[477]: rd.md=0: removing MD RAID activation Nov 12 22:23:32.332247 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:23:32.341814 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:23:32.483936 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:32.496716 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 22:23:32.551330 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 22:23:32.559387 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:23:32.565207 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:32.571155 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:23:32.582830 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 22:23:32.631399 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:23:32.699567 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 22:23:32.699633 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 12 22:23:32.724408 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 12 22:23:32.724721 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 12 22:23:32.724960 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:69:3c:bf:b2:97 Nov 12 22:23:32.710650 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:23:32.710925 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:32.714692 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:32.716895 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:23:32.717268 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:32.719758 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:32.734250 (udev-worker)[527]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:23:32.736141 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:32.781507 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 12 22:23:32.781603 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 12 22:23:32.791542 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 12 22:23:32.793496 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:32.802496 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 22:23:32.802573 kernel: GPT:9289727 != 16777215 Nov 12 22:23:32.802599 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 22:23:32.802623 kernel: GPT:9289727 != 16777215 Nov 12 22:23:32.804638 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 22:23:32.804753 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:32.805893 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 22:23:32.845749 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:33.102846 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 12 22:23:33.167514 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (530) Nov 12 22:23:33.175953 kernel: BTRFS: device fsid 337794e4-53df-462b-aefc-e93e6a958f34 devid 1 transid 41 /dev/nvme0n1p3 scanned by (udev-worker) (522) Nov 12 22:23:33.228099 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:23:33.246569 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 12 22:23:33.265596 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 12 22:23:33.299926 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 12 22:23:33.310894 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 22:23:33.326828 disk-uuid[660]: Primary Header is updated. Nov 12 22:23:33.326828 disk-uuid[660]: Secondary Entries is updated. Nov 12 22:23:33.326828 disk-uuid[660]: Secondary Header is updated. Nov 12 22:23:33.337503 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:34.352580 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 22:23:34.354083 disk-uuid[661]: The operation has completed successfully. Nov 12 22:23:34.580879 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 22:23:34.583670 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 22:23:34.619914 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 22:23:34.642710 sh[921]: Success Nov 12 22:23:34.660518 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 22:23:34.767828 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 22:23:34.784735 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 22:23:34.787200 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 22:23:34.818459 kernel: BTRFS info (device dm-0): first mount of filesystem 337794e4-53df-462b-aefc-e93e6a958f34 Nov 12 22:23:34.818579 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:34.818610 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 22:23:34.821481 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 22:23:34.821528 kernel: BTRFS info (device dm-0): using free space tree Nov 12 22:23:35.257522 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 12 22:23:35.414808 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 22:23:35.418517 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 22:23:35.437141 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 22:23:35.443791 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 22:23:35.476560 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:35.476639 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:35.478015 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:35.486546 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:35.505779 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:35.506240 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 22:23:35.516408 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 22:23:35.529824 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 22:23:35.620096 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:23:35.631819 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:23:35.692016 systemd-networkd[1114]: lo: Link UP Nov 12 22:23:35.693599 systemd-networkd[1114]: lo: Gained carrier Nov 12 22:23:35.697461 systemd-networkd[1114]: Enumeration completed Nov 12 22:23:35.699020 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:23:35.701489 systemd[1]: Reached target network.target - Network. Nov 12 22:23:35.703427 systemd-networkd[1114]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:35.703434 systemd-networkd[1114]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:23:35.716323 systemd-networkd[1114]: eth0: Link UP Nov 12 22:23:35.716338 systemd-networkd[1114]: eth0: Gained carrier Nov 12 22:23:35.716355 systemd-networkd[1114]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:35.731543 systemd-networkd[1114]: eth0: DHCPv4 address 172.31.18.195/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:23:35.950500 ignition[1035]: Ignition 2.20.0 Nov 12 22:23:35.950536 ignition[1035]: Stage: fetch-offline Nov 12 22:23:35.951041 ignition[1035]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:35.951068 ignition[1035]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:35.954453 ignition[1035]: Ignition finished successfully Nov 12 22:23:35.962299 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:23:35.972937 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 12 22:23:36.009649 ignition[1125]: Ignition 2.20.0 Nov 12 22:23:36.009670 ignition[1125]: Stage: fetch Nov 12 22:23:36.010309 ignition[1125]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:36.010335 ignition[1125]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:36.011100 ignition[1125]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:36.030548 ignition[1125]: PUT result: OK Nov 12 22:23:36.033613 ignition[1125]: parsed url from cmdline: "" Nov 12 22:23:36.033628 ignition[1125]: no config URL provided Nov 12 22:23:36.033646 ignition[1125]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 22:23:36.033671 ignition[1125]: no config at "/usr/lib/ignition/user.ign" Nov 12 22:23:36.033705 ignition[1125]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:36.035343 ignition[1125]: PUT result: OK Nov 12 22:23:36.035416 ignition[1125]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 12 22:23:36.039602 ignition[1125]: GET result: OK Nov 12 22:23:36.039698 ignition[1125]: parsing config with SHA512: 6ce00192cbd4fbeff346598dd91753d30dd49b664a32145fa39ba498dd592303e9f7abc946f2133886484beab7a961a7efec2cde3d53411b88a6014dfbb73367 Nov 12 22:23:36.051078 unknown[1125]: fetched base config from "system" Nov 12 22:23:36.051302 unknown[1125]: fetched base config from "system" Nov 12 22:23:36.052162 ignition[1125]: fetch: fetch complete Nov 12 22:23:36.051317 unknown[1125]: fetched user config from "aws" Nov 12 22:23:36.052174 ignition[1125]: fetch: fetch passed Nov 12 22:23:36.052437 ignition[1125]: Ignition finished successfully Nov 12 22:23:36.062815 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 12 22:23:36.073755 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 22:23:36.110584 ignition[1131]: Ignition 2.20.0 Nov 12 22:23:36.110615 ignition[1131]: Stage: kargs Nov 12 22:23:36.112171 ignition[1131]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:36.112201 ignition[1131]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:36.112367 ignition[1131]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:36.114609 ignition[1131]: PUT result: OK Nov 12 22:23:36.126212 ignition[1131]: kargs: kargs passed Nov 12 22:23:36.126619 ignition[1131]: Ignition finished successfully Nov 12 22:23:36.133569 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 22:23:36.143765 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 22:23:36.168561 ignition[1137]: Ignition 2.20.0 Nov 12 22:23:36.168591 ignition[1137]: Stage: disks Nov 12 22:23:36.170198 ignition[1137]: no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:36.170225 ignition[1137]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:36.171322 ignition[1137]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:36.175535 ignition[1137]: PUT result: OK Nov 12 22:23:36.181333 ignition[1137]: disks: disks passed Nov 12 22:23:36.181696 ignition[1137]: Ignition finished successfully Nov 12 22:23:36.186603 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 22:23:36.190863 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 22:23:36.193062 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 22:23:36.195405 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:23:36.197318 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:23:36.199266 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:23:36.218821 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 22:23:36.354734 systemd-fsck[1146]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 22:23:36.359680 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 22:23:36.382879 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 22:23:36.470562 kernel: EXT4-fs (nvme0n1p9): mounted filesystem be7e07bb-77fc-4aec-a4f6-d76dc4498784 r/w with ordered data mode. Quota mode: none. Nov 12 22:23:36.471999 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 22:23:36.475990 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 22:23:36.534776 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:23:36.540685 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 22:23:36.542949 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 22:23:36.543049 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 22:23:36.543099 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:23:36.573564 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1165) Nov 12 22:23:36.574891 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 22:23:36.581954 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:36.582026 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:36.583235 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:36.591755 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 22:23:36.647551 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:36.651014 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:23:37.293607 systemd-networkd[1114]: eth0: Gained IPv6LL Nov 12 22:23:37.475693 initrd-setup-root[1189]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 22:23:37.486027 initrd-setup-root[1196]: cut: /sysroot/etc/group: No such file or directory Nov 12 22:23:37.521035 initrd-setup-root[1203]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 22:23:37.530357 initrd-setup-root[1210]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 22:23:38.094525 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 22:23:38.103655 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 22:23:38.118075 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 22:23:38.133627 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 22:23:38.136179 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:38.175567 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 22:23:38.182673 ignition[1277]: INFO : Ignition 2.20.0 Nov 12 22:23:38.182673 ignition[1277]: INFO : Stage: mount Nov 12 22:23:38.186025 ignition[1277]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:38.186025 ignition[1277]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:38.186025 ignition[1277]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:38.193064 ignition[1277]: INFO : PUT result: OK Nov 12 22:23:38.197451 ignition[1277]: INFO : mount: mount passed Nov 12 22:23:38.199640 ignition[1277]: INFO : Ignition finished successfully Nov 12 22:23:38.203894 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 22:23:38.211705 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 22:23:38.245257 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 22:23:38.267507 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1289) Nov 12 22:23:38.271731 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem e7e17182-4510-4c0b-82ae-ebdf6a7625d9 Nov 12 22:23:38.271806 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 22:23:38.271836 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 22:23:38.279515 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 22:23:38.282159 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 22:23:38.329735 ignition[1306]: INFO : Ignition 2.20.0 Nov 12 22:23:38.329735 ignition[1306]: INFO : Stage: files Nov 12 22:23:38.333143 ignition[1306]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:38.333143 ignition[1306]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:38.337704 ignition[1306]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:38.340990 ignition[1306]: INFO : PUT result: OK Nov 12 22:23:38.345596 ignition[1306]: DEBUG : files: compiled without relabeling support, skipping Nov 12 22:23:38.349603 ignition[1306]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 22:23:38.349603 ignition[1306]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 22:23:38.425525 ignition[1306]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 22:23:38.428237 ignition[1306]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 22:23:38.431282 unknown[1306]: wrote ssh authorized keys file for user: core Nov 12 22:23:38.433780 ignition[1306]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 22:23:38.483778 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Nov 12 22:23:38.487545 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 Nov 12 22:23:38.964126 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 12 22:23:39.365915 ignition[1306]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" Nov 12 22:23:39.369994 ignition[1306]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:23:39.369994 ignition[1306]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 22:23:39.369994 ignition[1306]: INFO : files: files passed Nov 12 22:23:39.369994 ignition[1306]: INFO : Ignition finished successfully Nov 12 22:23:39.372232 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 22:23:39.397875 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 22:23:39.403289 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 22:23:39.414952 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 22:23:39.418925 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 22:23:39.432509 initrd-setup-root-after-ignition[1334]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:39.432509 initrd-setup-root-after-ignition[1334]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:39.439998 initrd-setup-root-after-ignition[1338]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 22:23:39.445771 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:23:39.451183 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 22:23:39.467830 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 22:23:39.525109 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 22:23:39.526794 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 22:23:39.530173 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 22:23:39.532224 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 22:23:39.534205 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 22:23:39.556512 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 22:23:39.604217 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:23:39.616851 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 22:23:39.656732 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:39.659150 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:39.660299 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 22:23:39.661078 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 22:23:39.661312 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 22:23:39.663185 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 22:23:39.664333 systemd[1]: Stopped target basic.target - Basic System. Nov 12 22:23:39.665105 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 22:23:39.665819 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 22:23:39.666690 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 22:23:39.667769 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 22:23:39.668614 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 22:23:39.669547 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 22:23:39.670486 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 22:23:39.671239 systemd[1]: Stopped target swap.target - Swaps. Nov 12 22:23:39.672121 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 22:23:39.672447 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 22:23:39.676134 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:39.678044 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:39.678832 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 22:23:39.688128 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:39.693396 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 22:23:39.694129 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 22:23:39.710564 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 22:23:39.710861 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 22:23:39.713627 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 22:23:39.713864 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 22:23:39.735224 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 22:23:39.748014 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 22:23:39.762432 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 22:23:39.763520 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:39.768012 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 22:23:39.768300 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 22:23:39.808796 ignition[1358]: INFO : Ignition 2.20.0 Nov 12 22:23:39.808796 ignition[1358]: INFO : Stage: umount Nov 12 22:23:39.815658 ignition[1358]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 22:23:39.815658 ignition[1358]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 22:23:39.815658 ignition[1358]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 22:23:39.815658 ignition[1358]: INFO : PUT result: OK Nov 12 22:23:39.841565 ignition[1358]: INFO : umount: umount passed Nov 12 22:23:39.841565 ignition[1358]: INFO : Ignition finished successfully Nov 12 22:23:39.818791 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 22:23:39.822079 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 22:23:39.846184 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 22:23:39.846411 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 22:23:39.849094 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 22:23:39.849213 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 22:23:39.854460 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 22:23:39.854772 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 22:23:39.857668 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 12 22:23:39.857774 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 12 22:23:39.862558 systemd[1]: Stopped target network.target - Network. Nov 12 22:23:39.867900 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 22:23:39.868033 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 22:23:39.868595 systemd[1]: Stopped target paths.target - Path Units. Nov 12 22:23:39.872033 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 22:23:39.880636 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:39.885770 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 22:23:39.889443 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 22:23:39.891944 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 22:23:39.892046 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 22:23:39.894289 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 22:23:39.894399 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 22:23:39.896712 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 22:23:39.896915 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 22:23:39.899087 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 22:23:39.899213 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 22:23:39.902040 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 22:23:39.904351 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 22:23:39.909935 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 22:23:39.914270 systemd-networkd[1114]: eth0: DHCPv6 lease lost Nov 12 22:23:39.930772 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 22:23:39.931069 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 22:23:39.945550 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 22:23:39.946928 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 22:23:39.953006 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 22:23:39.953141 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:40.006936 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 22:23:40.010888 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 22:23:40.011053 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 22:23:40.016748 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 22:23:40.016853 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:40.021756 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 22:23:40.021947 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:40.032356 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 22:23:40.032505 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:40.036722 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:40.064061 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 22:23:40.068778 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 22:23:40.078009 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 22:23:40.078201 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 22:23:40.089890 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 22:23:40.090237 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:40.101175 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 22:23:40.101576 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 22:23:40.108245 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 22:23:40.108360 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:40.113118 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 22:23:40.113242 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:40.114216 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 22:23:40.114329 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 22:23:40.117977 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 22:23:40.118081 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 22:23:40.120409 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 22:23:40.120541 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 22:23:40.142798 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 22:23:40.147775 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 22:23:40.147917 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:40.160392 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 12 22:23:40.160569 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:23:40.163373 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 22:23:40.163521 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:40.166750 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 22:23:40.166865 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:40.206045 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 22:23:40.206421 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 22:23:40.213583 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 22:23:40.240753 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 22:23:40.255174 systemd[1]: Switching root. Nov 12 22:23:40.297177 systemd-journald[251]: Journal stopped Nov 12 22:23:44.690380 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Nov 12 22:23:44.696612 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 22:23:44.696678 kernel: SELinux: policy capability open_perms=1 Nov 12 22:23:44.696710 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 22:23:44.696740 kernel: SELinux: policy capability always_check_network=0 Nov 12 22:23:44.696779 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 22:23:44.696816 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 22:23:44.696847 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 22:23:44.696886 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 22:23:44.696916 kernel: audit: type=1403 audit(1731450221.833:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 22:23:44.696948 systemd[1]: Successfully loaded SELinux policy in 205.272ms. Nov 12 22:23:44.696986 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.515ms. Nov 12 22:23:44.697022 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 12 22:23:44.697059 systemd[1]: Detected virtualization amazon. Nov 12 22:23:44.697093 systemd[1]: Detected architecture arm64. Nov 12 22:23:44.697136 systemd[1]: Detected first boot. Nov 12 22:23:44.697174 systemd[1]: Initializing machine ID from VM UUID. Nov 12 22:23:44.697217 zram_generator::config[1401]: No configuration found. Nov 12 22:23:44.697264 systemd[1]: Populated /etc with preset unit settings. Nov 12 22:23:44.697306 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 22:23:44.697379 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 22:23:44.697442 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 22:23:44.699818 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 22:23:44.699893 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 22:23:44.699943 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 22:23:44.699984 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 22:23:44.700031 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 22:23:44.700073 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 22:23:44.700113 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 22:23:44.700142 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 22:23:44.700175 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 22:23:44.700205 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 22:23:44.700234 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 22:23:44.700263 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 22:23:44.700295 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 22:23:44.700327 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 22:23:44.700360 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 12 22:23:44.700394 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 22:23:44.700423 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 22:23:44.700452 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 22:23:44.705857 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 22:23:44.705909 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 22:23:44.705964 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 22:23:44.705998 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 22:23:44.706032 systemd[1]: Reached target slices.target - Slice Units. Nov 12 22:23:44.706070 systemd[1]: Reached target swap.target - Swaps. Nov 12 22:23:44.706101 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 22:23:44.706132 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 22:23:44.706165 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 22:23:44.706197 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 22:23:44.706227 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 22:23:44.706256 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 22:23:44.706287 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 22:23:44.706318 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 22:23:44.706362 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 22:23:44.706392 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 22:23:44.706421 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 22:23:44.706452 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 22:23:44.706514 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 12 22:23:44.706546 systemd[1]: Reached target machines.target - Containers. Nov 12 22:23:44.706576 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 22:23:44.706608 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:44.706642 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 22:23:44.706680 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 22:23:44.706710 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:23:44.706739 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:23:44.706770 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:23:44.706802 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 22:23:44.706833 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:23:44.706868 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 22:23:44.706900 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 22:23:44.706938 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 22:23:44.706970 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 22:23:44.707005 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 22:23:44.707040 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 22:23:44.707075 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 22:23:44.707107 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 22:23:44.707143 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 22:23:44.707174 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 22:23:44.707207 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 22:23:44.707244 systemd[1]: Stopped verity-setup.service. Nov 12 22:23:44.707281 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 22:23:44.707314 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 22:23:44.707347 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 22:23:44.707384 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 22:23:44.707432 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 22:23:44.709584 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 22:23:44.709675 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 22:23:44.709716 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:23:44.709748 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:23:44.709777 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:23:44.709807 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:23:44.709836 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 22:23:44.709868 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 22:23:44.709908 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 22:23:44.709937 kernel: fuse: init (API version 7.39) Nov 12 22:23:44.709966 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 22:23:44.709995 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 22:23:44.710028 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 22:23:44.710067 kernel: loop: module loaded Nov 12 22:23:44.710096 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 22:23:44.710127 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 22:23:44.710208 systemd-journald[1476]: Collecting audit messages is disabled. Nov 12 22:23:44.710261 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 22:23:44.710294 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 22:23:44.710324 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 22:23:44.710357 kernel: ACPI: bus type drm_connector registered Nov 12 22:23:44.710393 systemd-journald[1476]: Journal started Nov 12 22:23:44.710443 systemd-journald[1476]: Runtime Journal (/run/log/journal/ec2257c6fbb6d730f7654b1ef8afca93) is 8.0M, max 75.3M, 67.3M free. Nov 12 22:23:44.715423 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 22:23:43.978845 systemd[1]: Queued start job for default target multi-user.target. Nov 12 22:23:44.726852 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:44.067217 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 12 22:23:44.068317 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 22:23:44.780815 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 22:23:44.780939 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:23:44.799434 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 22:23:44.815636 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 22:23:44.831913 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 22:23:44.841962 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 22:23:44.859761 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 22:23:44.859538 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 22:23:44.862614 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:23:44.863008 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:23:44.867404 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 22:23:44.869120 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 22:23:44.872591 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:23:44.873587 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:23:44.876065 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 22:23:44.880826 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 22:23:44.884060 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 22:23:44.934317 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 22:23:44.953733 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 22:23:44.970814 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 22:23:44.977825 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 22:23:44.981979 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:23:44.986069 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 22:23:44.990003 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 22:23:45.029235 kernel: loop0: detected capacity change from 0 to 113536 Nov 12 22:23:45.040267 systemd-journald[1476]: Time spent on flushing to /var/log/journal/ec2257c6fbb6d730f7654b1ef8afca93 is 53.614ms for 897 entries. Nov 12 22:23:45.040267 systemd-journald[1476]: System Journal (/var/log/journal/ec2257c6fbb6d730f7654b1ef8afca93) is 8.0M, max 195.6M, 187.6M free. Nov 12 22:23:45.119076 systemd-journald[1476]: Received client request to flush runtime journal. Nov 12 22:23:45.043492 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 22:23:45.055556 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 22:23:45.068161 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 22:23:45.082856 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 22:23:45.092785 systemd-tmpfiles[1512]: ACLs are not supported, ignoring. Nov 12 22:23:45.092810 systemd-tmpfiles[1512]: ACLs are not supported, ignoring. Nov 12 22:23:45.113546 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 22:23:45.124796 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 22:23:45.127596 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 22:23:45.142103 udevadm[1543]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 12 22:23:45.206758 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 22:23:45.216759 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 22:23:45.263639 systemd-tmpfiles[1550]: ACLs are not supported, ignoring. Nov 12 22:23:45.264122 systemd-tmpfiles[1550]: ACLs are not supported, ignoring. Nov 12 22:23:45.273268 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 22:23:45.401512 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 22:23:45.465987 kernel: loop1: detected capacity change from 0 to 116808 Nov 12 22:23:45.783517 kernel: loop2: detected capacity change from 0 to 189592 Nov 12 22:23:46.080103 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 22:23:46.093974 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 22:23:46.157101 systemd-udevd[1557]: Using default interface naming scheme 'v255'. Nov 12 22:23:46.160058 kernel: loop3: detected capacity change from 0 to 53784 Nov 12 22:23:46.360955 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 22:23:46.377172 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 22:23:46.408793 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 22:23:46.483636 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 12 22:23:46.527968 (udev-worker)[1564]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:23:46.531195 kernel: loop4: detected capacity change from 0 to 113536 Nov 12 22:23:46.531265 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1560) Nov 12 22:23:46.528745 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 22:23:46.557511 kernel: loop5: detected capacity change from 0 to 116808 Nov 12 22:23:46.580549 kernel: loop6: detected capacity change from 0 to 189592 Nov 12 22:23:46.604542 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1560) Nov 12 22:23:46.615531 kernel: loop7: detected capacity change from 0 to 53784 Nov 12 22:23:46.629352 (sd-merge)[1583]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Nov 12 22:23:46.631875 (sd-merge)[1583]: Merged extensions into '/usr'. Nov 12 22:23:46.662314 systemd[1]: Reloading requested from client PID 1511 ('systemd-sysext') (unit systemd-sysext.service)... Nov 12 22:23:46.662346 systemd[1]: Reloading... Nov 12 22:23:46.790890 systemd-networkd[1562]: lo: Link UP Nov 12 22:23:46.790918 systemd-networkd[1562]: lo: Gained carrier Nov 12 22:23:46.802154 systemd-networkd[1562]: Enumeration completed Nov 12 22:23:46.809794 systemd-networkd[1562]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:46.809818 systemd-networkd[1562]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 22:23:46.815126 systemd-networkd[1562]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:46.819672 systemd-networkd[1562]: eth0: Link UP Nov 12 22:23:46.822233 systemd-networkd[1562]: eth0: Gained carrier Nov 12 22:23:46.822272 systemd-networkd[1562]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 22:23:46.850598 systemd-networkd[1562]: eth0: DHCPv4 address 172.31.18.195/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 22:23:46.931504 zram_generator::config[1633]: No configuration found. Nov 12 22:23:47.062567 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1574) Nov 12 22:23:47.299544 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 12 22:23:47.444495 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 22:23:47.448881 systemd[1]: Reloading finished in 785 ms. Nov 12 22:23:47.498875 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 22:23:47.502847 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 22:23:47.523523 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 22:23:47.569762 systemd[1]: Starting ensure-sysext.service... Nov 12 22:23:47.575840 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 22:23:47.588917 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 22:23:47.596679 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 22:23:47.613860 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 22:23:47.622820 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 22:23:47.634663 systemd[1]: Reloading requested from client PID 1760 ('systemctl') (unit ensure-sysext.service)... Nov 12 22:23:47.634685 systemd[1]: Reloading... Nov 12 22:23:47.640762 lvm[1761]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:23:47.734287 systemd-tmpfiles[1764]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 22:23:47.735009 systemd-tmpfiles[1764]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 22:23:47.738883 systemd-tmpfiles[1764]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 22:23:47.739440 systemd-tmpfiles[1764]: ACLs are not supported, ignoring. Nov 12 22:23:47.739714 systemd-tmpfiles[1764]: ACLs are not supported, ignoring. Nov 12 22:23:47.746972 systemd-tmpfiles[1764]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:23:47.747002 systemd-tmpfiles[1764]: Skipping /boot Nov 12 22:23:47.771821 systemd-tmpfiles[1764]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 22:23:47.771858 systemd-tmpfiles[1764]: Skipping /boot Nov 12 22:23:47.854927 zram_generator::config[1802]: No configuration found. Nov 12 22:23:48.110846 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 12 22:23:48.262050 systemd[1]: Reloading finished in 626 ms. Nov 12 22:23:48.301692 systemd-networkd[1562]: eth0: Gained IPv6LL Nov 12 22:23:48.303213 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 22:23:48.307731 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 22:23:48.312364 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 22:23:48.316754 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 22:23:48.321679 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 22:23:48.342355 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 22:23:48.361004 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 12 22:23:48.367968 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 22:23:48.379008 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 22:23:48.388106 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 22:23:48.397020 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 22:23:48.405398 lvm[1862]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 22:23:48.413051 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 22:23:48.422114 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:48.431983 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:23:48.440178 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 22:23:48.448188 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 22:23:48.450327 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:48.458828 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:48.459252 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:48.461391 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:23:48.462733 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:23:48.481067 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 22:23:48.489975 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 22:23:48.499853 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 22:23:48.505158 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 22:23:48.508823 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 22:23:48.509258 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 22:23:48.526105 systemd[1]: Finished ensure-sysext.service. Nov 12 22:23:48.532615 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 22:23:48.544208 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 22:23:48.545128 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 22:23:48.553710 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 22:23:48.554074 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 22:23:48.558587 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 22:23:48.584055 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 22:23:48.600340 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 22:23:48.601871 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 22:23:48.606349 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 22:23:48.613442 ldconfig[1497]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 22:23:48.618387 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 22:23:48.619266 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 22:23:48.626613 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 22:23:48.644837 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 22:23:48.692617 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 22:23:48.729585 systemd-resolved[1864]: Positive Trust Anchors: Nov 12 22:23:48.730167 systemd-resolved[1864]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 22:23:48.730359 systemd-resolved[1864]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 12 22:23:48.734281 augenrules[1902]: No rules Nov 12 22:23:48.735128 systemd[1]: audit-rules.service: Deactivated successfully. Nov 12 22:23:48.735784 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 12 22:23:48.742109 systemd-resolved[1864]: Defaulting to hostname 'linux'. Nov 12 22:23:48.745547 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 22:23:48.747923 systemd[1]: Reached target network.target - Network. Nov 12 22:23:48.749744 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 22:23:48.751734 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 22:23:48.762327 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 22:23:48.765958 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 12 22:23:48.766028 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 22:23:48.768129 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 22:23:48.770476 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 12 22:23:48.773055 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 22:23:48.775273 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 22:23:48.777730 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 22:23:48.780306 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 22:23:48.780353 systemd[1]: Reached target paths.target - Path Units. Nov 12 22:23:48.783167 systemd[1]: Reached target timers.target - Timer Units. Nov 12 22:23:48.786609 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 22:23:48.791666 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 22:23:48.800913 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 22:23:48.804194 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 22:23:48.806554 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 22:23:48.808580 systemd[1]: Reached target basic.target - Basic System. Nov 12 22:23:48.810659 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:23:48.810714 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 22:23:48.818762 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 22:23:48.825732 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 12 22:23:48.842970 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 22:23:48.861723 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 22:23:48.867815 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 22:23:48.869758 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 12 22:23:48.879730 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:23:48.884212 jq[1914]: false Nov 12 22:23:48.886849 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 22:23:48.898800 systemd[1]: Started ntpd.service - Network Time Service. Nov 12 22:23:48.912901 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 22:23:48.918192 extend-filesystems[1915]: Found loop4 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found loop5 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found loop6 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found loop7 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p1 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p2 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p3 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found usr Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p4 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p6 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p7 Nov 12 22:23:48.939582 extend-filesystems[1915]: Found nvme0n1p9 Nov 12 22:23:48.939582 extend-filesystems[1915]: Checking size of /dev/nvme0n1p9 Nov 12 22:23:49.035702 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Nov 12 22:23:48.952547 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 12 22:23:49.035888 extend-filesystems[1915]: Resized partition /dev/nvme0n1p9 Nov 12 22:23:48.969341 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 22:23:49.069126 extend-filesystems[1931]: resize2fs 1.47.1 (20-May-2024) Nov 12 22:23:48.997907 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 22:23:49.033573 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 22:23:49.043800 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 22:23:49.044864 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 22:23:49.048817 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 22:23:49.055737 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 22:23:49.062384 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 22:23:49.066439 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 22:23:49.129900 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 22:23:49.130313 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 22:23:49.135347 dbus-daemon[1913]: [system] SELinux support is enabled Nov 12 22:23:49.138978 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 22:23:49.146220 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 12 22:23:49.146267 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 22:23:49.149721 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 12 22:23:49.149762 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 22:23:49.174681 dbus-daemon[1913]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1562 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 12 22:23:49.187058 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Nov 12 22:23:49.191676 extend-filesystems[1931]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 12 22:23:49.191676 extend-filesystems[1931]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 12 22:23:49.191676 extend-filesystems[1931]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Nov 12 22:23:49.206060 extend-filesystems[1915]: Resized filesystem in /dev/nvme0n1p9 Nov 12 22:23:49.208500 jq[1940]: true Nov 12 22:23:49.209300 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 12 22:23:49.212203 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 22:23:49.214141 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 22:23:49.226764 (ntainerd)[1952]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 22:23:49.235992 ntpd[1918]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 20:41:47 UTC 2024 (1): Starting Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: ---------------------------------------------------- Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: corporation. Support and training for ntp-4 are Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: available at https://www.nwtime.org/support Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: ---------------------------------------------------- Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: proto: precision = 0.096 usec (-23) Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: basedate set to 2024-10-31 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: gps base set to 2024-11-03 (week 2339) Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen normally on 3 eth0 172.31.18.195:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen normally on 4 lo [::1]:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listen normally on 5 eth0 [fe80::469:3cff:febf:b297%2]:123 Nov 12 22:23:49.247502 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: Listening on routing socket on fd #22 for interface updates Nov 12 22:23:49.238595 ntpd[1918]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 22:23:49.250829 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:49.250829 ntpd[1918]: 12 Nov 22:23:49 ntpd[1918]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:49.238617 ntpd[1918]: ---------------------------------------------------- Nov 12 22:23:49.238636 ntpd[1918]: ntp-4 is maintained by Network Time Foundation, Nov 12 22:23:49.238655 ntpd[1918]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 22:23:49.238674 ntpd[1918]: corporation. Support and training for ntp-4 are Nov 12 22:23:49.238697 ntpd[1918]: available at https://www.nwtime.org/support Nov 12 22:23:49.238715 ntpd[1918]: ---------------------------------------------------- Nov 12 22:23:49.267981 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 22:23:49.242052 ntpd[1918]: proto: precision = 0.096 usec (-23) Nov 12 22:23:49.270660 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 22:23:49.242512 ntpd[1918]: basedate set to 2024-10-31 Nov 12 22:23:49.242539 ntpd[1918]: gps base set to 2024-11-03 (week 2339) Nov 12 22:23:49.245013 ntpd[1918]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 22:23:49.245088 ntpd[1918]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 22:23:49.245358 ntpd[1918]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 22:23:49.245439 ntpd[1918]: Listen normally on 3 eth0 172.31.18.195:123 Nov 12 22:23:49.245533 ntpd[1918]: Listen normally on 4 lo [::1]:123 Nov 12 22:23:49.245604 ntpd[1918]: Listen normally on 5 eth0 [fe80::469:3cff:febf:b297%2]:123 Nov 12 22:23:49.245668 ntpd[1918]: Listening on routing socket on fd #22 for interface updates Nov 12 22:23:49.247963 ntpd[1918]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:49.248012 ntpd[1918]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 22:23:49.289994 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 12 22:23:49.318890 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 12 22:23:49.371311 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 22:23:49.377117 update_engine[1939]: I20241112 22:23:49.375308 1939 main.cc:92] Flatcar Update Engine starting Nov 12 22:23:49.378959 jq[1967]: true Nov 12 22:23:49.387989 update_engine[1939]: I20241112 22:23:49.385282 1939 update_check_scheduler.cc:74] Next update check in 2m18s Nov 12 22:23:49.388702 systemd[1]: Started update-engine.service - Update Engine. Nov 12 22:23:49.403248 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 22:23:49.502877 systemd-logind[1932]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 22:23:49.502920 systemd-logind[1932]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 12 22:23:49.511294 systemd-logind[1932]: New seat seat0. Nov 12 22:23:49.526609 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 22:23:49.535899 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1986) Nov 12 22:23:49.615506 coreos-metadata[1912]: Nov 12 22:23:49.611 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:23:49.622522 coreos-metadata[1912]: Nov 12 22:23:49.619 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 12 22:23:49.628032 coreos-metadata[1912]: Nov 12 22:23:49.626 INFO Fetch successful Nov 12 22:23:49.628032 coreos-metadata[1912]: Nov 12 22:23:49.626 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 12 22:23:49.628032 coreos-metadata[1912]: Nov 12 22:23:49.627 INFO Fetch successful Nov 12 22:23:49.628032 coreos-metadata[1912]: Nov 12 22:23:49.627 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 12 22:23:49.630218 coreos-metadata[1912]: Nov 12 22:23:49.629 INFO Fetch successful Nov 12 22:23:49.630218 coreos-metadata[1912]: Nov 12 22:23:49.629 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 12 22:23:49.643436 coreos-metadata[1912]: Nov 12 22:23:49.638 INFO Fetch successful Nov 12 22:23:49.643436 coreos-metadata[1912]: Nov 12 22:23:49.638 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 12 22:23:49.643436 coreos-metadata[1912]: Nov 12 22:23:49.641 INFO Fetch failed with 404: resource not found Nov 12 22:23:49.643436 coreos-metadata[1912]: Nov 12 22:23:49.641 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 12 22:23:49.648529 coreos-metadata[1912]: Nov 12 22:23:49.644 INFO Fetch successful Nov 12 22:23:49.648529 coreos-metadata[1912]: Nov 12 22:23:49.644 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 12 22:23:49.653088 coreos-metadata[1912]: Nov 12 22:23:49.650 INFO Fetch successful Nov 12 22:23:49.653088 coreos-metadata[1912]: Nov 12 22:23:49.650 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 12 22:23:49.662137 coreos-metadata[1912]: Nov 12 22:23:49.661 INFO Fetch successful Nov 12 22:23:49.662137 coreos-metadata[1912]: Nov 12 22:23:49.662 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 12 22:23:49.664709 coreos-metadata[1912]: Nov 12 22:23:49.664 INFO Fetch successful Nov 12 22:23:49.664709 coreos-metadata[1912]: Nov 12 22:23:49.664 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 12 22:23:49.670517 coreos-metadata[1912]: Nov 12 22:23:49.666 INFO Fetch successful Nov 12 22:23:49.691492 amazon-ssm-agent[1976]: Initializing new seelog logger Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: New Seelog Logger Creation Complete Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 processing appconfig overrides Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 processing appconfig overrides Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 processing appconfig overrides Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO Proxy environment variables: Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 22:23:49.785712 amazon-ssm-agent[1976]: 2024/11/12 22:23:49 processing appconfig overrides Nov 12 22:23:49.786256 bash[2035]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:23:49.786137 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 22:23:49.806505 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO no_proxy: Nov 12 22:23:49.838711 systemd[1]: Starting sshkeys.service... Nov 12 22:23:49.905762 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO https_proxy: Nov 12 22:23:49.924038 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 12 22:23:49.928620 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 22:23:49.937679 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 12 22:23:49.947195 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 12 22:23:49.988711 dbus-daemon[1913]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 12 22:23:49.989018 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 12 22:23:50.002389 dbus-daemon[1913]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1965 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 12 22:23:50.016523 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO http_proxy: Nov 12 22:23:50.016652 containerd[1952]: time="2024-11-12T22:23:50.014896032Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 12 22:23:50.034133 systemd[1]: Starting polkit.service - Authorization Manager... Nov 12 22:23:50.123551 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO Checking if agent identity type OnPrem can be assumed Nov 12 22:23:50.134430 polkitd[2105]: Started polkitd version 121 Nov 12 22:23:50.216241 polkitd[2105]: Loading rules from directory /etc/polkit-1/rules.d Nov 12 22:23:50.217769 polkitd[2105]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 12 22:23:50.223630 polkitd[2105]: Finished loading, compiling and executing 2 rules Nov 12 22:23:50.232528 amazon-ssm-agent[1976]: 2024-11-12 22:23:49 INFO Checking if agent identity type EC2 can be assumed Nov 12 22:23:50.234592 dbus-daemon[1913]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 12 22:23:50.236394 systemd[1]: Started polkit.service - Authorization Manager. Nov 12 22:23:50.241865 polkitd[2105]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 12 22:23:50.257292 coreos-metadata[2089]: Nov 12 22:23:50.257 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 22:23:50.265350 coreos-metadata[2089]: Nov 12 22:23:50.260 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 12 22:23:50.265350 coreos-metadata[2089]: Nov 12 22:23:50.262 INFO Fetch successful Nov 12 22:23:50.265350 coreos-metadata[2089]: Nov 12 22:23:50.263 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 12 22:23:50.266571 coreos-metadata[2089]: Nov 12 22:23:50.266 INFO Fetch successful Nov 12 22:23:50.271124 unknown[2089]: wrote ssh authorized keys file for user: core Nov 12 22:23:50.284521 containerd[1952]: time="2024-11-12T22:23:50.281712806Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.319532198Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.60-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.319596218Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.319630418Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.319952450Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.319986326Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320108294Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320135570Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320418410Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320447258Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320497898Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:50.326515 containerd[1952]: time="2024-11-12T22:23:50.320525666Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.327102 containerd[1952]: time="2024-11-12T22:23:50.320693630Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.327102 containerd[1952]: time="2024-11-12T22:23:50.321084878Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 22:23:50.327102 containerd[1952]: time="2024-11-12T22:23:50.321267146Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 22:23:50.327102 containerd[1952]: time="2024-11-12T22:23:50.321295898Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 22:23:50.333542 systemd-hostnamed[1965]: Hostname set to (transient) Nov 12 22:23:50.335977 systemd-resolved[1864]: System hostname changed to 'ip-172-31-18-195'. Nov 12 22:23:50.339269 containerd[1952]: time="2024-11-12T22:23:50.338645090Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 22:23:50.339269 containerd[1952]: time="2024-11-12T22:23:50.338813246Z" level=info msg="metadata content store policy set" policy=shared Nov 12 22:23:50.340513 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO Agent will take identity from EC2 Nov 12 22:23:50.339731 locksmithd[1982]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.350024522Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.351363734Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.351431642Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.351532382Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.351573290Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.351827390Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352254722Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352449434Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352515158Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352550378Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352582430Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352612934Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352645874Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.355623 containerd[1952]: time="2024-11-12T22:23:50.352681022Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352713806Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352767830Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352801562Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352832414Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352875038Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352906010Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352938854Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.352972718Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353002094Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353034794Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353072582Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353108198Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353141786Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.356455 containerd[1952]: time="2024-11-12T22:23:50.353176994Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353205494Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353235446Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353265698Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353298662Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353386130Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.357133 containerd[1952]: time="2024-11-12T22:23:50.353432066Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.367695 update-ssh-keys[2133]: Updated "/home/core/.ssh/authorized_keys" Nov 12 22:23:50.364576 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.353461418Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362722934Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362767058Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362793986Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362855462Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362882930Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362924450Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362949890Z" level=info msg="NRI interface is disabled by configuration." Nov 12 22:23:50.368399 containerd[1952]: time="2024-11-12T22:23:50.362975378Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 22:23:50.372621 containerd[1952]: time="2024-11-12T22:23:50.371588294Z" 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}" Nov 12 22:23:50.372621 containerd[1952]: time="2024-11-12T22:23:50.371698298Z" level=info msg="Connect containerd service" Nov 12 22:23:50.372621 containerd[1952]: time="2024-11-12T22:23:50.371778638Z" level=info msg="using legacy CRI server" Nov 12 22:23:50.372621 containerd[1952]: time="2024-11-12T22:23:50.371797238Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 22:23:50.372621 containerd[1952]: time="2024-11-12T22:23:50.372046070Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 22:23:50.378938 systemd[1]: Finished sshkeys.service. Nov 12 22:23:50.381191 containerd[1952]: time="2024-11-12T22:23:50.381076322Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381509930Z" level=info msg="Start subscribing containerd event" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381591086Z" level=info msg="Start recovering state" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381710786Z" level=info msg="Start event monitor" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381733874Z" level=info msg="Start snapshots syncer" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381755186Z" level=info msg="Start cni network conf syncer for default" Nov 12 22:23:50.381975 containerd[1952]: time="2024-11-12T22:23:50.381773378Z" level=info msg="Start streaming server" Nov 12 22:23:50.387752 containerd[1952]: time="2024-11-12T22:23:50.387690302Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 22:23:50.387895 containerd[1952]: time="2024-11-12T22:23:50.387807374Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 22:23:50.387949 containerd[1952]: time="2024-11-12T22:23:50.387909578Z" level=info msg="containerd successfully booted in 0.377235s" Nov 12 22:23:50.394709 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 22:23:50.438665 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:50.540556 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:50.638816 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 22:23:50.738239 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 12 22:23:50.840894 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 12 22:23:50.941941 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] Starting Core Agent Nov 12 22:23:51.010247 sshd_keygen[1966]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 22:23:51.042215 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 12 22:23:51.063577 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 22:23:51.080260 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 22:23:51.106054 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 22:23:51.106774 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 22:23:51.121053 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 22:23:51.144539 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [Registrar] Starting registrar module Nov 12 22:23:51.155037 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 22:23:51.168443 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 22:23:51.184593 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 12 22:23:51.187170 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 22:23:51.243659 amazon-ssm-agent[1976]: 2024-11-12 22:23:50 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 12 22:23:51.327286 amazon-ssm-agent[1976]: 2024-11-12 22:23:51 INFO [EC2Identity] EC2 registration was successful. Nov 12 22:23:51.327286 amazon-ssm-agent[1976]: 2024-11-12 22:23:51 INFO [CredentialRefresher] credentialRefresher has started Nov 12 22:23:51.327286 amazon-ssm-agent[1976]: 2024-11-12 22:23:51 INFO [CredentialRefresher] Starting credentials refresher loop Nov 12 22:23:51.327286 amazon-ssm-agent[1976]: 2024-11-12 22:23:51 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 12 22:23:51.344862 amazon-ssm-agent[1976]: 2024-11-12 22:23:51 INFO [CredentialRefresher] Next credential rotation will be in 32.1499899227 minutes Nov 12 22:23:52.354923 amazon-ssm-agent[1976]: 2024-11-12 22:23:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 12 22:23:52.456926 amazon-ssm-agent[1976]: 2024-11-12 22:23:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2165) started Nov 12 22:23:52.557366 amazon-ssm-agent[1976]: 2024-11-12 22:23:52 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 12 22:23:54.022081 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:23:54.025276 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 22:23:54.030967 systemd[1]: Startup finished in 1.118s (kernel) + 10.867s (initrd) + 12.399s (userspace) = 24.385s. Nov 12 22:23:54.038408 (kubelet)[2180]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 12 22:23:55.881389 kubelet[2180]: E1112 22:23:55.881267 2180 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" Nov 12 22:23:55.885084 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 12 22:23:55.885436 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 12 22:23:55.886040 systemd[1]: kubelet.service: Consumed 1.283s CPU time. Nov 12 22:23:56.637973 systemd-resolved[1864]: Clock change detected. Flushing caches. Nov 12 22:23:58.032639 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 22:23:58.043117 systemd[1]: Started sshd@0-172.31.18.195:22-147.75.109.163:54254.service - OpenSSH per-connection server daemon (147.75.109.163:54254). Nov 12 22:23:58.241884 sshd[2192]: Accepted publickey for core from 147.75.109.163 port 54254 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:23:58.245568 sshd-session[2192]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:23:58.264685 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 22:23:58.272403 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 22:23:58.278723 systemd-logind[1932]: New session 1 of user core. Nov 12 22:23:58.316509 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 22:23:58.327238 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 22:23:58.351055 (systemd)[2196]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 22:23:58.576123 systemd[2196]: Queued start job for default target default.target. Nov 12 22:23:58.584707 systemd[2196]: Created slice app.slice - User Application Slice. Nov 12 22:23:58.584807 systemd[2196]: Reached target paths.target - Paths. Nov 12 22:23:58.584852 systemd[2196]: Reached target timers.target - Timers. Nov 12 22:23:58.587843 systemd[2196]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 22:23:58.610852 systemd[2196]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 22:23:58.611132 systemd[2196]: Reached target sockets.target - Sockets. Nov 12 22:23:58.611169 systemd[2196]: Reached target basic.target - Basic System. Nov 12 22:23:58.611269 systemd[2196]: Reached target default.target - Main User Target. Nov 12 22:23:58.611339 systemd[2196]: Startup finished in 246ms. Nov 12 22:23:58.611551 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 22:23:58.623027 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 22:23:58.780891 systemd[1]: Started sshd@1-172.31.18.195:22-147.75.109.163:54532.service - OpenSSH per-connection server daemon (147.75.109.163:54532). Nov 12 22:23:58.974786 sshd[2207]: Accepted publickey for core from 147.75.109.163 port 54532 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:23:58.977576 sshd-session[2207]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:23:58.986228 systemd-logind[1932]: New session 2 of user core. Nov 12 22:23:59.000003 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 22:23:59.124964 sshd[2209]: Connection closed by 147.75.109.163 port 54532 Nov 12 22:23:59.125944 sshd-session[2207]: pam_unix(sshd:session): session closed for user core Nov 12 22:23:59.132212 systemd[1]: sshd@1-172.31.18.195:22-147.75.109.163:54532.service: Deactivated successfully. Nov 12 22:23:59.135552 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 22:23:59.140032 systemd-logind[1932]: Session 2 logged out. Waiting for processes to exit. Nov 12 22:23:59.142106 systemd-logind[1932]: Removed session 2. Nov 12 22:23:59.161170 systemd[1]: Started sshd@2-172.31.18.195:22-147.75.109.163:54540.service - OpenSSH per-connection server daemon (147.75.109.163:54540). Nov 12 22:23:59.356301 sshd[2214]: Accepted publickey for core from 147.75.109.163 port 54540 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:23:59.359017 sshd-session[2214]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:23:59.368700 systemd-logind[1932]: New session 3 of user core. Nov 12 22:23:59.378998 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 22:23:59.498367 sshd[2216]: Connection closed by 147.75.109.163 port 54540 Nov 12 22:23:59.499505 sshd-session[2214]: pam_unix(sshd:session): session closed for user core Nov 12 22:23:59.507742 systemd[1]: sshd@2-172.31.18.195:22-147.75.109.163:54540.service: Deactivated successfully. Nov 12 22:23:59.513120 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 22:23:59.514847 systemd-logind[1932]: Session 3 logged out. Waiting for processes to exit. Nov 12 22:23:59.516670 systemd-logind[1932]: Removed session 3. Nov 12 22:23:59.534147 systemd[1]: Started sshd@3-172.31.18.195:22-147.75.109.163:54554.service - OpenSSH per-connection server daemon (147.75.109.163:54554). Nov 12 22:23:59.731038 sshd[2221]: Accepted publickey for core from 147.75.109.163 port 54554 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:23:59.733757 sshd-session[2221]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:23:59.745281 systemd-logind[1932]: New session 4 of user core. Nov 12 22:23:59.755995 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 22:23:59.881417 sshd[2223]: Connection closed by 147.75.109.163 port 54554 Nov 12 22:23:59.882284 sshd-session[2221]: pam_unix(sshd:session): session closed for user core Nov 12 22:23:59.888103 systemd[1]: sshd@3-172.31.18.195:22-147.75.109.163:54554.service: Deactivated successfully. Nov 12 22:23:59.891128 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 22:23:59.892592 systemd-logind[1932]: Session 4 logged out. Waiting for processes to exit. Nov 12 22:23:59.894259 systemd-logind[1932]: Removed session 4. Nov 12 22:23:59.915743 systemd[1]: Started sshd@4-172.31.18.195:22-147.75.109.163:54568.service - OpenSSH per-connection server daemon (147.75.109.163:54568). Nov 12 22:24:00.110584 sshd[2228]: Accepted publickey for core from 147.75.109.163 port 54568 ssh2: RSA SHA256:uIlZGZS4ktpZyIP4/chqTIlyFelgeTnlXR9XFJx/YBs Nov 12 22:24:00.113528 sshd-session[2228]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 22:24:00.123024 systemd-logind[1932]: New session 5 of user core. Nov 12 22:24:00.133906 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 22:24:00.250284 sudo[2231]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 12 22:24:00.251039 sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 22:24:01.309623 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:01.310231 systemd[1]: kubelet.service: Consumed 1.283s CPU time. Nov 12 22:24:01.322105 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:24:01.390964 systemd[1]: Reloading requested from client PID 2263 ('systemctl') (unit session-5.scope)... Nov 12 22:24:01.391249 systemd[1]: Reloading... Nov 12 22:24:01.656657 zram_generator::config[2306]: No configuration found. Nov 12 22:24:01.866364 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 12 22:24:02.029774 systemd[1]: Reloading finished in 637 ms. Nov 12 22:24:02.116644 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 12 22:24:02.116836 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 12 22:24:02.117329 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:02.125186 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 22:24:02.408521 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 22:24:02.428531 (kubelet)[2367]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 12 22:24:02.508353 kubelet[2367]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 12 22:24:02.508816 kubelet[2367]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Nov 12 22:24:02.508816 kubelet[2367]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 12 22:24:02.508943 kubelet[2367]: I1112 22:24:02.508901 2367 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" Nov 12 22:24:03.766657 kubelet[2367]: I1112 22:24:03.765715 2367 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" Nov 12 22:24:03.766657 kubelet[2367]: I1112 22:24:03.765766 2367 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 12 22:24:03.766657 kubelet[2367]: I1112 22:24:03.766200 2367 server.go:929] "Client rotation is on, will bootstrap in background" Nov 12 22:24:03.806115 kubelet[2367]: I1112 22:24:03.806053 2367 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 12 22:24:03.822674 kubelet[2367]: E1112 22:24:03.822621 2367 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 12 22:24:03.822871 kubelet[2367]: I1112 22:24:03.822848 2367 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." Nov 12 22:24:03.829913 kubelet[2367]: I1112 22:24:03.829871 2367 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 12 22:24:03.830363 kubelet[2367]: I1112 22:24:03.830339 2367 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Nov 12 22:24:03.830791 kubelet[2367]: I1112 22:24:03.830743 2367 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 12 22:24:03.831206 kubelet[2367]: I1112 22:24:03.830914 2367 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"172.31.18.195","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} Nov 12 22:24:03.831466 kubelet[2367]: I1112 22:24:03.831442 2367 topology_manager.go:138] "Creating topology manager with none policy" Nov 12 22:24:03.831559 kubelet[2367]: I1112 22:24:03.831541 2367 container_manager_linux.go:300] "Creating device plugin manager" Nov 12 22:24:03.831901 kubelet[2367]: I1112 22:24:03.831870 2367 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:24:03.833245 kubelet[2367]: I1112 22:24:03.833217 2367 kubelet.go:408] "Attempting to sync node with API server" Nov 12 22:24:03.833685 kubelet[2367]: I1112 22:24:03.833361 2367 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 12 22:24:03.833685 kubelet[2367]: I1112 22:24:03.833410 2367 kubelet.go:314] "Adding apiserver pod source" Nov 12 22:24:03.833685 kubelet[2367]: I1112 22:24:03.833434 2367 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 12 22:24:03.834295 kubelet[2367]: E1112 22:24:03.834212 2367 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:03.834295 kubelet[2367]: E1112 22:24:03.834295 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:03.838859 kubelet[2367]: I1112 22:24:03.838817 2367 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 12 22:24:03.842648 kubelet[2367]: I1112 22:24:03.841661 2367 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 12 22:24:03.842648 kubelet[2367]: W1112 22:24:03.841824 2367 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 12 22:24:03.842943 kubelet[2367]: I1112 22:24:03.842899 2367 server.go:1269] "Started kubelet" Nov 12 22:24:03.845930 kubelet[2367]: W1112 22:24:03.845778 2367 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Nov 12 22:24:03.846217 kubelet[2367]: E1112 22:24:03.846186 2367 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 12 22:24:03.846456 kubelet[2367]: W1112 22:24:03.846429 2367 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "172.31.18.195" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 12 22:24:03.846570 kubelet[2367]: E1112 22:24:03.846545 2367 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"172.31.18.195\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 12 22:24:03.847228 kubelet[2367]: I1112 22:24:03.847127 2367 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Nov 12 22:24:03.850483 kubelet[2367]: I1112 22:24:03.850412 2367 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 12 22:24:03.854700 kubelet[2367]: I1112 22:24:03.854624 2367 server.go:460] "Adding debug handlers to kubelet server" Nov 12 22:24:03.856560 kubelet[2367]: I1112 22:24:03.856515 2367 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 12 22:24:03.866545 kubelet[2367]: I1112 22:24:03.850540 2367 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 12 22:24:03.870340 kubelet[2367]: I1112 22:24:03.869180 2367 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 12 22:24:03.870340 kubelet[2367]: I1112 22:24:03.868040 2367 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Nov 12 22:24:03.870340 kubelet[2367]: I1112 22:24:03.866771 2367 volume_manager.go:289] "Starting Kubelet Volume Manager" Nov 12 22:24:03.870340 kubelet[2367]: E1112 22:24:03.868272 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:03.870340 kubelet[2367]: I1112 22:24:03.869501 2367 reconciler.go:26] "Reconciler: start to sync state" Nov 12 22:24:03.872122 kubelet[2367]: E1112 22:24:03.872075 2367 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 12 22:24:03.876648 kubelet[2367]: I1112 22:24:03.875235 2367 factory.go:221] Registration of the systemd container factory successfully Nov 12 22:24:03.877223 kubelet[2367]: I1112 22:24:03.877159 2367 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 12 22:24:03.879971 kubelet[2367]: E1112 22:24:03.879910 2367 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.18.195\" not found" node="172.31.18.195" Nov 12 22:24:03.883652 kubelet[2367]: I1112 22:24:03.883395 2367 factory.go:221] Registration of the containerd container factory successfully Nov 12 22:24:03.927361 kubelet[2367]: I1112 22:24:03.926872 2367 cpu_manager.go:214] "Starting CPU manager" policy="none" Nov 12 22:24:03.927361 kubelet[2367]: I1112 22:24:03.926906 2367 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Nov 12 22:24:03.927361 kubelet[2367]: I1112 22:24:03.926938 2367 state_mem.go:36] "Initialized new in-memory state store" Nov 12 22:24:03.935585 kubelet[2367]: I1112 22:24:03.935364 2367 policy_none.go:49] "None policy: Start" Nov 12 22:24:03.938403 kubelet[2367]: I1112 22:24:03.938092 2367 memory_manager.go:170] "Starting memorymanager" policy="None" Nov 12 22:24:03.939746 kubelet[2367]: I1112 22:24:03.939710 2367 state_mem.go:35] "Initializing new in-memory state store" Nov 12 22:24:03.956834 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 12 22:24:03.970045 kubelet[2367]: E1112 22:24:03.969475 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:03.972201 kubelet[2367]: I1112 22:24:03.971311 2367 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 12 22:24:03.976185 kubelet[2367]: I1112 22:24:03.976137 2367 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 12 22:24:03.977215 kubelet[2367]: I1112 22:24:03.976290 2367 status_manager.go:217] "Starting to sync pod status with apiserver" Nov 12 22:24:03.977215 kubelet[2367]: I1112 22:24:03.976329 2367 kubelet.go:2321] "Starting kubelet main sync loop" Nov 12 22:24:03.977215 kubelet[2367]: E1112 22:24:03.976407 2367 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 12 22:24:03.990085 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 12 22:24:03.998332 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 12 22:24:04.008536 kubelet[2367]: I1112 22:24:04.008494 2367 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 12 22:24:04.008999 kubelet[2367]: I1112 22:24:04.008973 2367 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 12 22:24:04.009195 kubelet[2367]: I1112 22:24:04.009135 2367 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 12 22:24:04.010850 kubelet[2367]: I1112 22:24:04.010812 2367 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 12 22:24:04.013425 kubelet[2367]: E1112 22:24:04.013382 2367 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.18.195\" not found" Nov 12 22:24:04.111148 kubelet[2367]: I1112 22:24:04.111090 2367 kubelet_node_status.go:72] "Attempting to register node" node="172.31.18.195" Nov 12 22:24:04.118563 kubelet[2367]: I1112 22:24:04.118462 2367 kubelet_node_status.go:75] "Successfully registered node" node="172.31.18.195" Nov 12 22:24:04.118563 kubelet[2367]: E1112 22:24:04.118513 2367 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"172.31.18.195\": node \"172.31.18.195\" not found" Nov 12 22:24:04.145340 kubelet[2367]: E1112 22:24:04.145210 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.246365 kubelet[2367]: E1112 22:24:04.246283 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.347305 kubelet[2367]: E1112 22:24:04.347247 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.448035 kubelet[2367]: E1112 22:24:04.447889 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.453157 sudo[2231]: pam_unix(sudo:session): session closed for user root Nov 12 22:24:04.476673 sshd[2230]: Connection closed by 147.75.109.163 port 54568 Nov 12 22:24:04.477461 sshd-session[2228]: pam_unix(sshd:session): session closed for user core Nov 12 22:24:04.483454 systemd[1]: sshd@4-172.31.18.195:22-147.75.109.163:54568.service: Deactivated successfully. Nov 12 22:24:04.487085 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 22:24:04.489101 systemd-logind[1932]: Session 5 logged out. Waiting for processes to exit. Nov 12 22:24:04.491278 systemd-logind[1932]: Removed session 5. Nov 12 22:24:04.548711 kubelet[2367]: E1112 22:24:04.548639 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.649297 kubelet[2367]: E1112 22:24:04.649243 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.749986 kubelet[2367]: E1112 22:24:04.749797 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.770079 kubelet[2367]: I1112 22:24:04.770026 2367 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 12 22:24:04.770974 kubelet[2367]: W1112 22:24:04.770898 2367 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 Nov 12 22:24:04.770974 kubelet[2367]: W1112 22:24:04.770907 2367 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 Nov 12 22:24:04.834889 kubelet[2367]: E1112 22:24:04.834807 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:04.850974 kubelet[2367]: E1112 22:24:04.850899 2367 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"172.31.18.195\" not found" Nov 12 22:24:04.953010 kubelet[2367]: I1112 22:24:04.952927 2367 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 12 22:24:04.954091 containerd[1952]: time="2024-11-12T22:24:04.954004405Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 12 22:24:04.954720 kubelet[2367]: I1112 22:24:04.954448 2367 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 12 22:24:05.835003 kubelet[2367]: I1112 22:24:05.834938 2367 apiserver.go:52] "Watching apiserver" Nov 12 22:24:05.835546 kubelet[2367]: E1112 22:24:05.835289 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:05.853296 systemd[1]: Created slice kubepods-burstable-pod8c018bb5_f2ae_47c5_adae_42b09a0e941a.slice - libcontainer container kubepods-burstable-pod8c018bb5_f2ae_47c5_adae_42b09a0e941a.slice. Nov 12 22:24:05.872748 kubelet[2367]: I1112 22:24:05.872699 2367 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Nov 12 22:24:05.882507 kubelet[2367]: I1112 22:24:05.880779 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/8c018bb5-f2ae-47c5-adae-42b09a0e941a-flannel-cfg\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:05.882507 kubelet[2367]: I1112 22:24:05.880840 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/c323c582-053b-46ac-9daf-bab3850fc62f-kube-proxy\") pod \"kube-proxy-tbf8l\" (UID: \"c323c582-053b-46ac-9daf-bab3850fc62f\") " pod="kube-system/kube-proxy-tbf8l" Nov 12 22:24:05.882507 kubelet[2367]: I1112 22:24:05.880878 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/c323c582-053b-46ac-9daf-bab3850fc62f-lib-modules\") pod \"kube-proxy-tbf8l\" (UID: \"c323c582-053b-46ac-9daf-bab3850fc62f\") " pod="kube-system/kube-proxy-tbf8l" Nov 12 22:24:05.882507 kubelet[2367]: I1112 22:24:05.880917 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vtrgv\" (UniqueName: \"kubernetes.io/projected/c323c582-053b-46ac-9daf-bab3850fc62f-kube-api-access-vtrgv\") pod \"kube-proxy-tbf8l\" (UID: \"c323c582-053b-46ac-9daf-bab3850fc62f\") " pod="kube-system/kube-proxy-tbf8l" Nov 12 22:24:05.882507 kubelet[2367]: I1112 22:24:05.880958 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/8c018bb5-f2ae-47c5-adae-42b09a0e941a-run\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:05.881985 systemd[1]: Created slice kubepods-besteffort-podc323c582_053b_46ac_9daf_bab3850fc62f.slice - libcontainer container kubepods-besteffort-podc323c582_053b_46ac_9daf_bab3850fc62f.slice. Nov 12 22:24:05.884226 kubelet[2367]: I1112 22:24:05.880995 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/8c018bb5-f2ae-47c5-adae-42b09a0e941a-cni-plugin\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:05.884226 kubelet[2367]: I1112 22:24:05.881028 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/8c018bb5-f2ae-47c5-adae-42b09a0e941a-cni\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:05.884226 kubelet[2367]: I1112 22:24:05.881071 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c323c582-053b-46ac-9daf-bab3850fc62f-xtables-lock\") pod \"kube-proxy-tbf8l\" (UID: \"c323c582-053b-46ac-9daf-bab3850fc62f\") " pod="kube-system/kube-proxy-tbf8l" Nov 12 22:24:05.884226 kubelet[2367]: I1112 22:24:05.881106 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8c018bb5-f2ae-47c5-adae-42b09a0e941a-xtables-lock\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:05.884226 kubelet[2367]: I1112 22:24:05.881140 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wzr5d\" (UniqueName: \"kubernetes.io/projected/8c018bb5-f2ae-47c5-adae-42b09a0e941a-kube-api-access-wzr5d\") pod \"kube-flannel-ds-ptbfp\" (UID: \"8c018bb5-f2ae-47c5-adae-42b09a0e941a\") " pod="kube-flannel/kube-flannel-ds-ptbfp" Nov 12 22:24:06.177183 containerd[1952]: time="2024-11-12T22:24:06.177029111Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ptbfp,Uid:8c018bb5-f2ae-47c5-adae-42b09a0e941a,Namespace:kube-flannel,Attempt:0,}" Nov 12 22:24:06.196889 containerd[1952]: time="2024-11-12T22:24:06.196781339Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tbf8l,Uid:c323c582-053b-46ac-9daf-bab3850fc62f,Namespace:kube-system,Attempt:0,}" Nov 12 22:24:06.819312 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3128849872.mount: Deactivated successfully. Nov 12 22:24:06.828395 containerd[1952]: time="2024-11-12T22:24:06.828324638Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:24:06.830166 containerd[1952]: time="2024-11-12T22:24:06.830105342Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:24:06.832218 containerd[1952]: time="2024-11-12T22:24:06.832079438Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 12 22:24:06.832218 containerd[1952]: time="2024-11-12T22:24:06.832161938Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 12 22:24:06.835794 kubelet[2367]: E1112 22:24:06.835723 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:06.836559 containerd[1952]: time="2024-11-12T22:24:06.836473142Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:24:06.844714 containerd[1952]: time="2024-11-12T22:24:06.844637666Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 12 22:24:06.847663 containerd[1952]: time="2024-11-12T22:24:06.846958790Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 669.797235ms" Nov 12 22:24:06.852111 containerd[1952]: time="2024-11-12T22:24:06.852049550Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 655.097571ms" Nov 12 22:24:07.020846 containerd[1952]: time="2024-11-12T22:24:07.020230475Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:07.020846 containerd[1952]: time="2024-11-12T22:24:07.020366447Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:07.020846 containerd[1952]: time="2024-11-12T22:24:07.020429003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:07.022053 containerd[1952]: time="2024-11-12T22:24:07.021495527Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:07.022053 containerd[1952]: time="2024-11-12T22:24:07.021751151Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:07.022053 containerd[1952]: time="2024-11-12T22:24:07.021791891Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:07.022053 containerd[1952]: time="2024-11-12T22:24:07.021971195Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:07.024090 containerd[1952]: time="2024-11-12T22:24:07.023071019Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:07.259972 systemd[1]: Started cri-containerd-2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac.scope - libcontainer container 2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac. Nov 12 22:24:07.273942 systemd[1]: Started cri-containerd-6376345d2da589079736e8ce8797d20a291d3a89d0628e62fef51a8bdcbc8ef3.scope - libcontainer container 6376345d2da589079736e8ce8797d20a291d3a89d0628e62fef51a8bdcbc8ef3. Nov 12 22:24:07.349579 containerd[1952]: time="2024-11-12T22:24:07.349500373Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ptbfp,Uid:8c018bb5-f2ae-47c5-adae-42b09a0e941a,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\"" Nov 12 22:24:07.356043 containerd[1952]: time="2024-11-12T22:24:07.355994017Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 12 22:24:07.361918 containerd[1952]: time="2024-11-12T22:24:07.361499641Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tbf8l,Uid:c323c582-053b-46ac-9daf-bab3850fc62f,Namespace:kube-system,Attempt:0,} returns sandbox id \"6376345d2da589079736e8ce8797d20a291d3a89d0628e62fef51a8bdcbc8ef3\"" Nov 12 22:24:07.837101 kubelet[2367]: E1112 22:24:07.836946 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:08.837394 kubelet[2367]: E1112 22:24:08.837330 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:09.202074 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1463237150.mount: Deactivated successfully. Nov 12 22:24:09.249788 containerd[1952]: time="2024-11-12T22:24:09.249687482Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:09.251458 containerd[1952]: time="2024-11-12T22:24:09.251330282Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 12 22:24:09.252996 containerd[1952]: time="2024-11-12T22:24:09.252910418Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:09.257764 containerd[1952]: time="2024-11-12T22:24:09.257638946Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:09.260964 containerd[1952]: time="2024-11-12T22:24:09.259692110Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.903438485s" Nov 12 22:24:09.260964 containerd[1952]: time="2024-11-12T22:24:09.259752278Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 12 22:24:09.263500 containerd[1952]: time="2024-11-12T22:24:09.263402150Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.2\"" Nov 12 22:24:09.267660 containerd[1952]: time="2024-11-12T22:24:09.266562314Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 12 22:24:09.287528 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3064616156.mount: Deactivated successfully. Nov 12 22:24:09.297884 containerd[1952]: time="2024-11-12T22:24:09.297824246Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04\"" Nov 12 22:24:09.299647 containerd[1952]: time="2024-11-12T22:24:09.299481206Z" level=info msg="StartContainer for \"6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04\"" Nov 12 22:24:09.352932 systemd[1]: Started cri-containerd-6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04.scope - libcontainer container 6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04. Nov 12 22:24:09.400505 containerd[1952]: time="2024-11-12T22:24:09.400357503Z" level=info msg="StartContainer for \"6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04\" returns successfully" Nov 12 22:24:09.404098 systemd[1]: cri-containerd-6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04.scope: Deactivated successfully. Nov 12 22:24:09.467543 containerd[1952]: time="2024-11-12T22:24:09.466360767Z" level=info msg="shim disconnected" id=6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04 namespace=k8s.io Nov 12 22:24:09.467543 containerd[1952]: time="2024-11-12T22:24:09.466459287Z" level=warning msg="cleaning up after shim disconnected" id=6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04 namespace=k8s.io Nov 12 22:24:09.467543 containerd[1952]: time="2024-11-12T22:24:09.466478763Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:24:09.837915 kubelet[2367]: E1112 22:24:09.837829 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:10.074802 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6c59f8261bbce99b8d9343d59d79bff517098dca611c928cdd6f9bdc76e3ca04-rootfs.mount: Deactivated successfully. Nov 12 22:24:10.561938 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3696563362.mount: Deactivated successfully. Nov 12 22:24:10.838560 kubelet[2367]: E1112 22:24:10.838483 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:11.108236 containerd[1952]: time="2024-11-12T22:24:11.107903115Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:11.110176 containerd[1952]: time="2024-11-12T22:24:11.110074695Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.2: active requests=0, bytes read=26769664" Nov 12 22:24:11.111586 containerd[1952]: time="2024-11-12T22:24:11.111487575Z" level=info msg="ImageCreate event name:\"sha256:021d2420133054f8835987db659750ff639ab6863776460264dd8025c06644ba\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:11.115741 containerd[1952]: time="2024-11-12T22:24:11.115590651Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:62128d752eb4a9162074697aba46adea4abb8aab2a53c992f20881365b61a4fe\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:11.117730 containerd[1952]: time="2024-11-12T22:24:11.117475167Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.2\" with image id \"sha256:021d2420133054f8835987db659750ff639ab6863776460264dd8025c06644ba\", repo tag \"registry.k8s.io/kube-proxy:v1.31.2\", repo digest \"registry.k8s.io/kube-proxy@sha256:62128d752eb4a9162074697aba46adea4abb8aab2a53c992f20881365b61a4fe\", size \"26768683\" in 1.853980173s" Nov 12 22:24:11.117730 containerd[1952]: time="2024-11-12T22:24:11.117551043Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.2\" returns image reference \"sha256:021d2420133054f8835987db659750ff639ab6863776460264dd8025c06644ba\"" Nov 12 22:24:11.121667 containerd[1952]: time="2024-11-12T22:24:11.120822927Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 12 22:24:11.125108 containerd[1952]: time="2024-11-12T22:24:11.124972527Z" level=info msg="CreateContainer within sandbox \"6376345d2da589079736e8ce8797d20a291d3a89d0628e62fef51a8bdcbc8ef3\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 12 22:24:11.150215 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3248752650.mount: Deactivated successfully. Nov 12 22:24:11.155243 containerd[1952]: time="2024-11-12T22:24:11.154984024Z" level=info msg="CreateContainer within sandbox \"6376345d2da589079736e8ce8797d20a291d3a89d0628e62fef51a8bdcbc8ef3\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"8d7cb074a23b3ba216b1eb273c0cc533c3ddaf067bef55ac1e65168f151d23b3\"" Nov 12 22:24:11.156528 containerd[1952]: time="2024-11-12T22:24:11.156437164Z" level=info msg="StartContainer for \"8d7cb074a23b3ba216b1eb273c0cc533c3ddaf067bef55ac1e65168f151d23b3\"" Nov 12 22:24:11.221949 systemd[1]: Started cri-containerd-8d7cb074a23b3ba216b1eb273c0cc533c3ddaf067bef55ac1e65168f151d23b3.scope - libcontainer container 8d7cb074a23b3ba216b1eb273c0cc533c3ddaf067bef55ac1e65168f151d23b3. Nov 12 22:24:11.295984 containerd[1952]: time="2024-11-12T22:24:11.295795984Z" level=info msg="StartContainer for \"8d7cb074a23b3ba216b1eb273c0cc533c3ddaf067bef55ac1e65168f151d23b3\" returns successfully" Nov 12 22:24:11.839817 kubelet[2367]: E1112 22:24:11.839747 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:12.840359 kubelet[2367]: E1112 22:24:12.840301 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:12.941741 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2847399222.mount: Deactivated successfully. Nov 12 22:24:13.841451 kubelet[2367]: E1112 22:24:13.841347 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:14.130014 containerd[1952]: time="2024-11-12T22:24:14.129803286Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.131959 containerd[1952]: time="2024-11-12T22:24:14.131884662Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 12 22:24:14.132854 containerd[1952]: time="2024-11-12T22:24:14.132802758Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.138765 containerd[1952]: time="2024-11-12T22:24:14.138660258Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:14.141561 containerd[1952]: time="2024-11-12T22:24:14.140992362Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 3.020073639s" Nov 12 22:24:14.141561 containerd[1952]: time="2024-11-12T22:24:14.141050010Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 12 22:24:14.145305 containerd[1952]: time="2024-11-12T22:24:14.145054422Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 12 22:24:14.167186 containerd[1952]: time="2024-11-12T22:24:14.167121679Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6\"" Nov 12 22:24:14.168227 containerd[1952]: time="2024-11-12T22:24:14.168150895Z" level=info msg="StartContainer for \"2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6\"" Nov 12 22:24:14.223026 systemd[1]: Started cri-containerd-2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6.scope - libcontainer container 2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6. Nov 12 22:24:14.272342 systemd[1]: cri-containerd-2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6.scope: Deactivated successfully. Nov 12 22:24:14.275637 containerd[1952]: time="2024-11-12T22:24:14.275574583Z" level=info msg="StartContainer for \"2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6\" returns successfully" Nov 12 22:24:14.313869 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6-rootfs.mount: Deactivated successfully. Nov 12 22:24:14.360343 kubelet[2367]: I1112 22:24:14.358212 2367 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Nov 12 22:24:14.734343 containerd[1952]: time="2024-11-12T22:24:14.734143269Z" level=info msg="shim disconnected" id=2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6 namespace=k8s.io Nov 12 22:24:14.734343 containerd[1952]: time="2024-11-12T22:24:14.734336445Z" level=warning msg="cleaning up after shim disconnected" id=2a865f100f8ae345aba16522252f899f6cdc86dd17d3439b83130fe07a3c7ac6 namespace=k8s.io Nov 12 22:24:14.734930 containerd[1952]: time="2024-11-12T22:24:14.734369349Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 22:24:14.842027 kubelet[2367]: E1112 22:24:14.841919 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:15.037133 containerd[1952]: time="2024-11-12T22:24:15.036987283Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 12 22:24:15.049656 kubelet[2367]: I1112 22:24:15.048849 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tbf8l" podStartSLOduration=7.293400065 podStartE2EDuration="11.048812467s" podCreationTimestamp="2024-11-12 22:24:04 +0000 UTC" firstStartedPulling="2024-11-12 22:24:07.364225657 +0000 UTC m=+4.927133726" lastFinishedPulling="2024-11-12 22:24:11.119637651 +0000 UTC m=+8.682546128" observedRunningTime="2024-11-12 22:24:12.033820372 +0000 UTC m=+9.596728465" watchObservedRunningTime="2024-11-12 22:24:15.048812467 +0000 UTC m=+12.611720548" Nov 12 22:24:15.053627 containerd[1952]: time="2024-11-12T22:24:15.053553535Z" level=info msg="CreateContainer within sandbox \"2607c2a337a1a15b213a05928a5e6eac144542eb383c2127d4500a7830c130ac\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"ecb0ae386bbd5695ae6117c10bc8498f0ab882274d58cbfedf317e25866b8daf\"" Nov 12 22:24:15.054539 containerd[1952]: time="2024-11-12T22:24:15.054486439Z" level=info msg="StartContainer for \"ecb0ae386bbd5695ae6117c10bc8498f0ab882274d58cbfedf317e25866b8daf\"" Nov 12 22:24:15.099955 systemd[1]: Started cri-containerd-ecb0ae386bbd5695ae6117c10bc8498f0ab882274d58cbfedf317e25866b8daf.scope - libcontainer container ecb0ae386bbd5695ae6117c10bc8498f0ab882274d58cbfedf317e25866b8daf. Nov 12 22:24:15.163636 containerd[1952]: time="2024-11-12T22:24:15.159469736Z" level=info msg="StartContainer for \"ecb0ae386bbd5695ae6117c10bc8498f0ab882274d58cbfedf317e25866b8daf\" returns successfully" Nov 12 22:24:15.843301 kubelet[2367]: E1112 22:24:15.843193 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:16.067098 kubelet[2367]: I1112 22:24:16.066983 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ptbfp" podStartSLOduration=5.278258415 podStartE2EDuration="12.06695864s" podCreationTimestamp="2024-11-12 22:24:04 +0000 UTC" firstStartedPulling="2024-11-12 22:24:07.354242665 +0000 UTC m=+4.917150734" lastFinishedPulling="2024-11-12 22:24:14.142942902 +0000 UTC m=+11.705850959" observedRunningTime="2024-11-12 22:24:16.065552972 +0000 UTC m=+13.628461053" watchObservedRunningTime="2024-11-12 22:24:16.06695864 +0000 UTC m=+13.629866721" Nov 12 22:24:16.231846 (udev-worker)[2863]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:16.250733 systemd-networkd[1562]: flannel.1: Link UP Nov 12 22:24:16.250753 systemd-networkd[1562]: flannel.1: Gained carrier Nov 12 22:24:16.652534 systemd[1]: Created slice kubepods-besteffort-pod048f7c2e_7bd6_453a_9361_89553391a8f9.slice - libcontainer container kubepods-besteffort-pod048f7c2e_7bd6_453a_9361_89553391a8f9.slice. Nov 12 22:24:16.758357 kubelet[2367]: I1112 22:24:16.758224 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8mc9q\" (UniqueName: \"kubernetes.io/projected/048f7c2e-7bd6-453a-9361-89553391a8f9-kube-api-access-8mc9q\") pod \"nginx-deployment-8587fbcb89-sjcnq\" (UID: \"048f7c2e-7bd6-453a-9361-89553391a8f9\") " pod="default/nginx-deployment-8587fbcb89-sjcnq" Nov 12 22:24:16.844193 kubelet[2367]: E1112 22:24:16.844096 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:16.960336 containerd[1952]: time="2024-11-12T22:24:16.960177972Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-sjcnq,Uid:048f7c2e-7bd6-453a-9361-89553391a8f9,Namespace:default,Attempt:0,}" Nov 12 22:24:16.994514 systemd-networkd[1562]: cni0: Link UP Nov 12 22:24:16.994538 systemd-networkd[1562]: cni0: Gained carrier Nov 12 22:24:16.998093 systemd-networkd[1562]: cni0: Lost carrier Nov 12 22:24:17.005316 (udev-worker)[2960]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:17.008481 systemd-networkd[1562]: veth46dd1cf2: Link UP Nov 12 22:24:17.012711 kernel: cni0: port 1(veth46dd1cf2) entered blocking state Nov 12 22:24:17.012913 kernel: cni0: port 1(veth46dd1cf2) entered disabled state Nov 12 22:24:17.015632 kernel: veth46dd1cf2: entered allmulticast mode Nov 12 22:24:17.023303 kernel: veth46dd1cf2: entered promiscuous mode Nov 12 22:24:17.023405 kernel: cni0: port 1(veth46dd1cf2) entered blocking state Nov 12 22:24:17.023446 kernel: cni0: port 1(veth46dd1cf2) entered forwarding state Nov 12 22:24:17.025650 kernel: cni0: port 1(veth46dd1cf2) entered disabled state Nov 12 22:24:17.039075 kernel: cni0: port 1(veth46dd1cf2) entered blocking state Nov 12 22:24:17.039178 kernel: cni0: port 1(veth46dd1cf2) entered forwarding state Nov 12 22:24:17.040060 systemd-networkd[1562]: veth46dd1cf2: Gained carrier Nov 12 22:24:17.042171 systemd-networkd[1562]: cni0: Gained carrier Nov 12 22:24:17.045879 containerd[1952]: 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)(0x4000106628), "name":"cbr0", "type":"bridge"} Nov 12 22:24:17.045879 containerd[1952]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:17.076850 containerd[1952]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2024-11-12T22:24:17.076714377Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:17.077640 containerd[1952]: time="2024-11-12T22:24:17.077534085Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:17.077869 containerd[1952]: time="2024-11-12T22:24:17.077812221Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:17.078235 containerd[1952]: time="2024-11-12T22:24:17.078175509Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:17.117008 systemd[1]: Started cri-containerd-eed455cf77417573bbf287676a6a0f64e3ab4433a20ba60c1a051ea35aa057da.scope - libcontainer container eed455cf77417573bbf287676a6a0f64e3ab4433a20ba60c1a051ea35aa057da. Nov 12 22:24:17.190081 containerd[1952]: time="2024-11-12T22:24:17.189973558Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-sjcnq,Uid:048f7c2e-7bd6-453a-9361-89553391a8f9,Namespace:default,Attempt:0,} returns sandbox id \"eed455cf77417573bbf287676a6a0f64e3ab4433a20ba60c1a051ea35aa057da\"" Nov 12 22:24:17.194310 containerd[1952]: time="2024-11-12T22:24:17.194247778Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:24:17.844488 kubelet[2367]: E1112 22:24:17.844422 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:18.137091 systemd-networkd[1562]: flannel.1: Gained IPv6LL Nov 12 22:24:18.840851 systemd-networkd[1562]: veth46dd1cf2: Gained IPv6LL Nov 12 22:24:18.844809 kubelet[2367]: E1112 22:24:18.844625 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:18.904885 systemd-networkd[1562]: cni0: Gained IPv6LL Nov 12 22:24:19.845772 kubelet[2367]: E1112 22:24:19.845575 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:20.430269 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1752646843.mount: Deactivated successfully. Nov 12 22:24:20.767940 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 12 22:24:20.846688 kubelet[2367]: E1112 22:24:20.846533 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:21.635424 ntpd[1918]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 12 22:24:21.636200 ntpd[1918]: Listen normally on 7 cni0 192.168.1.1:123 Nov 12 22:24:21.637054 ntpd[1918]: 12 Nov 22:24:21 ntpd[1918]: Listen normally on 6 flannel.1 192.168.1.0:123 Nov 12 22:24:21.637054 ntpd[1918]: 12 Nov 22:24:21 ntpd[1918]: Listen normally on 7 cni0 192.168.1.1:123 Nov 12 22:24:21.637054 ntpd[1918]: 12 Nov 22:24:21 ntpd[1918]: Listen normally on 8 flannel.1 [fe80::90f0:42ff:fec5:22ee%3]:123 Nov 12 22:24:21.637054 ntpd[1918]: 12 Nov 22:24:21 ntpd[1918]: Listen normally on 9 cni0 [fe80::60e5:b5ff:fe3a:2d35%4]:123 Nov 12 22:24:21.637054 ntpd[1918]: 12 Nov 22:24:21 ntpd[1918]: Listen normally on 10 veth46dd1cf2 [fe80::6443:71ff:fedb:9dfc%5]:123 Nov 12 22:24:21.636307 ntpd[1918]: Listen normally on 8 flannel.1 [fe80::90f0:42ff:fec5:22ee%3]:123 Nov 12 22:24:21.636390 ntpd[1918]: Listen normally on 9 cni0 [fe80::60e5:b5ff:fe3a:2d35%4]:123 Nov 12 22:24:21.636460 ntpd[1918]: Listen normally on 10 veth46dd1cf2 [fe80::6443:71ff:fedb:9dfc%5]:123 Nov 12 22:24:21.847647 kubelet[2367]: E1112 22:24:21.847509 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:21.900243 containerd[1952]: time="2024-11-12T22:24:21.899928869Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:21.902347 containerd[1952]: time="2024-11-12T22:24:21.902181293Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67683180" Nov 12 22:24:21.903254 containerd[1952]: time="2024-11-12T22:24:21.903175961Z" level=info msg="ImageCreate event name:\"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:21.909172 containerd[1952]: time="2024-11-12T22:24:21.909055409Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:21.912412 containerd[1952]: time="2024-11-12T22:24:21.911874245Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 4.717545167s" Nov 12 22:24:21.912412 containerd[1952]: time="2024-11-12T22:24:21.911944253Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:24:21.916653 containerd[1952]: time="2024-11-12T22:24:21.916547561Z" level=info msg="CreateContainer within sandbox \"eed455cf77417573bbf287676a6a0f64e3ab4433a20ba60c1a051ea35aa057da\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 12 22:24:21.943977 containerd[1952]: time="2024-11-12T22:24:21.943722581Z" level=info msg="CreateContainer within sandbox \"eed455cf77417573bbf287676a6a0f64e3ab4433a20ba60c1a051ea35aa057da\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"6c8cee77ae395e1a31ae52ce5314bd6fc2da9208b499003c6a9cd81b3bae61de\"" Nov 12 22:24:21.944929 containerd[1952]: time="2024-11-12T22:24:21.944882069Z" level=info msg="StartContainer for \"6c8cee77ae395e1a31ae52ce5314bd6fc2da9208b499003c6a9cd81b3bae61de\"" Nov 12 22:24:22.000883 systemd[1]: Started cri-containerd-6c8cee77ae395e1a31ae52ce5314bd6fc2da9208b499003c6a9cd81b3bae61de.scope - libcontainer container 6c8cee77ae395e1a31ae52ce5314bd6fc2da9208b499003c6a9cd81b3bae61de. Nov 12 22:24:22.055661 containerd[1952]: time="2024-11-12T22:24:22.055023614Z" level=info msg="StartContainer for \"6c8cee77ae395e1a31ae52ce5314bd6fc2da9208b499003c6a9cd81b3bae61de\" returns successfully" Nov 12 22:24:22.847993 kubelet[2367]: E1112 22:24:22.847922 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:23.833734 kubelet[2367]: E1112 22:24:23.833660 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:23.848107 kubelet[2367]: E1112 22:24:23.848062 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:24.848650 kubelet[2367]: E1112 22:24:24.848568 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:25.830776 kubelet[2367]: I1112 22:24:25.830526 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-sjcnq" podStartSLOduration=5.109631438 podStartE2EDuration="9.830491017s" podCreationTimestamp="2024-11-12 22:24:16 +0000 UTC" firstStartedPulling="2024-11-12 22:24:17.193124386 +0000 UTC m=+14.756032455" lastFinishedPulling="2024-11-12 22:24:21.913983977 +0000 UTC m=+19.476892034" observedRunningTime="2024-11-12 22:24:22.085106714 +0000 UTC m=+19.648014783" watchObservedRunningTime="2024-11-12 22:24:25.830491017 +0000 UTC m=+23.393399086" Nov 12 22:24:25.833956 kubelet[2367]: I1112 22:24:25.833873 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1ea73a83-21a1-418a-bc86-7ed8e3208605-data\") pod \"nfs-server-provisioner-0\" (UID: \"1ea73a83-21a1-418a-bc86-7ed8e3208605\") " pod="default/nfs-server-provisioner-0" Nov 12 22:24:25.834375 kubelet[2367]: I1112 22:24:25.834264 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nnkln\" (UniqueName: \"kubernetes.io/projected/1ea73a83-21a1-418a-bc86-7ed8e3208605-kube-api-access-nnkln\") pod \"nfs-server-provisioner-0\" (UID: \"1ea73a83-21a1-418a-bc86-7ed8e3208605\") " pod="default/nfs-server-provisioner-0" Nov 12 22:24:25.845684 systemd[1]: Created slice kubepods-besteffort-pod1ea73a83_21a1_418a_bc86_7ed8e3208605.slice - libcontainer container kubepods-besteffort-pod1ea73a83_21a1_418a_bc86_7ed8e3208605.slice. Nov 12 22:24:25.849303 kubelet[2367]: E1112 22:24:25.849225 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:26.154015 containerd[1952]: time="2024-11-12T22:24:26.153199518Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1ea73a83-21a1-418a-bc86-7ed8e3208605,Namespace:default,Attempt:0,}" Nov 12 22:24:26.191764 (udev-worker)[3129]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:26.192512 systemd-networkd[1562]: vethf9f34de3: Link UP Nov 12 22:24:26.196561 kernel: cni0: port 2(vethf9f34de3) entered blocking state Nov 12 22:24:26.196730 kernel: cni0: port 2(vethf9f34de3) entered disabled state Nov 12 22:24:26.197771 kernel: vethf9f34de3: entered allmulticast mode Nov 12 22:24:26.199120 kernel: vethf9f34de3: entered promiscuous mode Nov 12 22:24:26.211692 kernel: cni0: port 2(vethf9f34de3) entered blocking state Nov 12 22:24:26.211810 kernel: cni0: port 2(vethf9f34de3) entered forwarding state Nov 12 22:24:26.214567 systemd-networkd[1562]: vethf9f34de3: Gained carrier Nov 12 22:24:26.219334 containerd[1952]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 12 22:24:26.219334 containerd[1952]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:26.248673 containerd[1952]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2024-11-12T22:24:26.247501363Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:26.248673 containerd[1952]: time="2024-11-12T22:24:26.248588935Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:26.249182 containerd[1952]: time="2024-11-12T22:24:26.248640631Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:26.249182 containerd[1952]: time="2024-11-12T22:24:26.248785123Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:26.288952 systemd[1]: Started cri-containerd-47e8d9b5116962ab83df465447ca46b6e7876ca7e7196f87e0d0221767d51659.scope - libcontainer container 47e8d9b5116962ab83df465447ca46b6e7876ca7e7196f87e0d0221767d51659. Nov 12 22:24:26.355829 containerd[1952]: time="2024-11-12T22:24:26.355752475Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1ea73a83-21a1-418a-bc86-7ed8e3208605,Namespace:default,Attempt:0,} returns sandbox id \"47e8d9b5116962ab83df465447ca46b6e7876ca7e7196f87e0d0221767d51659\"" Nov 12 22:24:26.358783 containerd[1952]: time="2024-11-12T22:24:26.358705807Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 12 22:24:26.849561 kubelet[2367]: E1112 22:24:26.849465 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:27.609627 systemd-networkd[1562]: vethf9f34de3: Gained IPv6LL Nov 12 22:24:27.850674 kubelet[2367]: E1112 22:24:27.850494 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:28.850919 kubelet[2367]: E1112 22:24:28.850801 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:29.409125 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1512180076.mount: Deactivated successfully. Nov 12 22:24:29.635574 ntpd[1918]: Listen normally on 11 vethf9f34de3 [fe80::f42f:b1ff:fea7:d949%6]:123 Nov 12 22:24:29.636098 ntpd[1918]: 12 Nov 22:24:29 ntpd[1918]: Listen normally on 11 vethf9f34de3 [fe80::f42f:b1ff:fea7:d949%6]:123 Nov 12 22:24:29.852073 kubelet[2367]: E1112 22:24:29.851976 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:30.852538 kubelet[2367]: E1112 22:24:30.852369 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:31.853267 kubelet[2367]: E1112 22:24:31.853202 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:32.853628 kubelet[2367]: E1112 22:24:32.853562 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:32.926240 containerd[1952]: time="2024-11-12T22:24:32.926160052Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:32.928926 containerd[1952]: time="2024-11-12T22:24:32.928844356Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 12 22:24:32.929803 containerd[1952]: time="2024-11-12T22:24:32.929742748Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:32.936414 containerd[1952]: time="2024-11-12T22:24:32.936338260Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:32.937953 containerd[1952]: time="2024-11-12T22:24:32.937737400Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 6.578966481s" Nov 12 22:24:32.937953 containerd[1952]: time="2024-11-12T22:24:32.937795888Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 12 22:24:32.943106 containerd[1952]: time="2024-11-12T22:24:32.943046116Z" level=info msg="CreateContainer within sandbox \"47e8d9b5116962ab83df465447ca46b6e7876ca7e7196f87e0d0221767d51659\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 12 22:24:32.970324 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount326233968.mount: Deactivated successfully. Nov 12 22:24:32.980501 containerd[1952]: time="2024-11-12T22:24:32.980426488Z" level=info msg="CreateContainer within sandbox \"47e8d9b5116962ab83df465447ca46b6e7876ca7e7196f87e0d0221767d51659\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8f53f6289045a73326c88e879968ba9b279b0ba0139095f4f0d9f915c9cf36d4\"" Nov 12 22:24:32.981504 containerd[1952]: time="2024-11-12T22:24:32.981444616Z" level=info msg="StartContainer for \"8f53f6289045a73326c88e879968ba9b279b0ba0139095f4f0d9f915c9cf36d4\"" Nov 12 22:24:33.031957 systemd[1]: Started cri-containerd-8f53f6289045a73326c88e879968ba9b279b0ba0139095f4f0d9f915c9cf36d4.scope - libcontainer container 8f53f6289045a73326c88e879968ba9b279b0ba0139095f4f0d9f915c9cf36d4. Nov 12 22:24:33.086698 containerd[1952]: time="2024-11-12T22:24:33.086469637Z" level=info msg="StartContainer for \"8f53f6289045a73326c88e879968ba9b279b0ba0139095f4f0d9f915c9cf36d4\" returns successfully" Nov 12 22:24:33.854393 kubelet[2367]: E1112 22:24:33.854311 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:34.855306 kubelet[2367]: E1112 22:24:34.855213 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:35.044721 update_engine[1939]: I20241112 22:24:35.044581 1939 update_attempter.cc:509] Updating boot flags... Nov 12 22:24:35.125667 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3359) Nov 12 22:24:35.394817 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3350) Nov 12 22:24:35.664910 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (3350) Nov 12 22:24:35.855998 kubelet[2367]: E1112 22:24:35.855883 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:36.856122 kubelet[2367]: E1112 22:24:36.856049 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:37.856732 kubelet[2367]: E1112 22:24:37.856669 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:38.857438 kubelet[2367]: E1112 22:24:38.857332 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:39.857931 kubelet[2367]: E1112 22:24:39.857858 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:40.859102 kubelet[2367]: E1112 22:24:40.859023 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:41.859725 kubelet[2367]: E1112 22:24:41.859659 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:42.860697 kubelet[2367]: E1112 22:24:42.860543 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:43.211902 kubelet[2367]: I1112 22:24:43.211526 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.629939958 podStartE2EDuration="18.211489427s" podCreationTimestamp="2024-11-12 22:24:25 +0000 UTC" firstStartedPulling="2024-11-12 22:24:26.358159615 +0000 UTC m=+23.921067684" lastFinishedPulling="2024-11-12 22:24:32.939709084 +0000 UTC m=+30.502617153" observedRunningTime="2024-11-12 22:24:33.124373281 +0000 UTC m=+30.687281350" watchObservedRunningTime="2024-11-12 22:24:43.211489427 +0000 UTC m=+40.774397496" Nov 12 22:24:43.224332 systemd[1]: Created slice kubepods-besteffort-pod6556d5a0_98ab_4aa5_bbce_7c717ffd5f4d.slice - libcontainer container kubepods-besteffort-pod6556d5a0_98ab_4aa5_bbce_7c717ffd5f4d.slice. Nov 12 22:24:43.342299 kubelet[2367]: I1112 22:24:43.342182 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xb4gq\" (UniqueName: \"kubernetes.io/projected/6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d-kube-api-access-xb4gq\") pod \"test-pod-1\" (UID: \"6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d\") " pod="default/test-pod-1" Nov 12 22:24:43.342299 kubelet[2367]: I1112 22:24:43.342258 2367 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-e5d04580-496d-4b0e-ab5f-22242c358d75\" (UniqueName: \"kubernetes.io/nfs/6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d-pvc-e5d04580-496d-4b0e-ab5f-22242c358d75\") pod \"test-pod-1\" (UID: \"6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d\") " pod="default/test-pod-1" Nov 12 22:24:43.486159 kernel: FS-Cache: Loaded Nov 12 22:24:43.532946 kernel: RPC: Registered named UNIX socket transport module. Nov 12 22:24:43.533115 kernel: RPC: Registered udp transport module. Nov 12 22:24:43.533247 kernel: RPC: Registered tcp transport module. Nov 12 22:24:43.533719 kernel: RPC: Registered tcp-with-tls transport module. Nov 12 22:24:43.534825 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 12 22:24:43.834378 kubelet[2367]: E1112 22:24:43.834303 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:43.861720 kubelet[2367]: E1112 22:24:43.861622 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:43.862347 kernel: NFS: Registering the id_resolver key type Nov 12 22:24:43.862470 kernel: Key type id_resolver registered Nov 12 22:24:43.862542 kernel: Key type id_legacy registered Nov 12 22:24:43.901738 nfsidmap[3675]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 12 22:24:43.909141 nfsidmap[3676]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 12 22:24:44.132328 containerd[1952]: time="2024-11-12T22:24:44.132085295Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d,Namespace:default,Attempt:0,}" Nov 12 22:24:44.177761 systemd-networkd[1562]: veth2013c741: Link UP Nov 12 22:24:44.180283 kernel: cni0: port 3(veth2013c741) entered blocking state Nov 12 22:24:44.180462 kernel: cni0: port 3(veth2013c741) entered disabled state Nov 12 22:24:44.182230 kernel: veth2013c741: entered allmulticast mode Nov 12 22:24:44.182859 kernel: veth2013c741: entered promiscuous mode Nov 12 22:24:44.185550 (udev-worker)[3662]: Network interface NamePolicy= disabled on kernel command line. Nov 12 22:24:44.196992 kernel: cni0: port 3(veth2013c741) entered blocking state Nov 12 22:24:44.197143 kernel: cni0: port 3(veth2013c741) entered forwarding state Nov 12 22:24:44.195907 systemd-networkd[1562]: veth2013c741: Gained carrier Nov 12 22:24:44.200696 containerd[1952]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 12 22:24:44.200696 containerd[1952]: delegateAdd: netconf sent to delegate plugin: Nov 12 22:24:44.239336 containerd[1952]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":8951,"name":"cbr0","type":"bridge"}time="2024-11-12T22:24:44.238982148Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 22:24:44.239336 containerd[1952]: time="2024-11-12T22:24:44.239101020Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 22:24:44.239336 containerd[1952]: time="2024-11-12T22:24:44.239139756Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:44.239895 containerd[1952]: time="2024-11-12T22:24:44.239351892Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 22:24:44.271924 systemd[1]: Started cri-containerd-dfe70c12d88b5cfd63b741be336fd2544cb2dcc02abc0a5003f42009f886b6e8.scope - libcontainer container dfe70c12d88b5cfd63b741be336fd2544cb2dcc02abc0a5003f42009f886b6e8. Nov 12 22:24:44.346853 containerd[1952]: time="2024-11-12T22:24:44.346690356Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:6556d5a0-98ab-4aa5-bbce-7c717ffd5f4d,Namespace:default,Attempt:0,} returns sandbox id \"dfe70c12d88b5cfd63b741be336fd2544cb2dcc02abc0a5003f42009f886b6e8\"" Nov 12 22:24:44.355230 containerd[1952]: time="2024-11-12T22:24:44.354861757Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 22:24:44.665049 containerd[1952]: time="2024-11-12T22:24:44.664810058Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 22:24:44.665981 containerd[1952]: time="2024-11-12T22:24:44.665864966Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 12 22:24:44.673901 containerd[1952]: time="2024-11-12T22:24:44.673823090Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\", size \"67683058\" in 318.880717ms" Nov 12 22:24:44.673901 containerd[1952]: time="2024-11-12T22:24:44.673888694Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 22:24:44.677854 containerd[1952]: time="2024-11-12T22:24:44.677665166Z" level=info msg="CreateContainer within sandbox \"dfe70c12d88b5cfd63b741be336fd2544cb2dcc02abc0a5003f42009f886b6e8\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 12 22:24:44.703999 containerd[1952]: time="2024-11-12T22:24:44.703910198Z" level=info msg="CreateContainer within sandbox \"dfe70c12d88b5cfd63b741be336fd2544cb2dcc02abc0a5003f42009f886b6e8\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"121c0a1028f282080034e239e829388082b0ca28008ef8bd4487c269591d55c5\"" Nov 12 22:24:44.705767 containerd[1952]: time="2024-11-12T22:24:44.705567410Z" level=info msg="StartContainer for \"121c0a1028f282080034e239e829388082b0ca28008ef8bd4487c269591d55c5\"" Nov 12 22:24:44.756977 systemd[1]: Started cri-containerd-121c0a1028f282080034e239e829388082b0ca28008ef8bd4487c269591d55c5.scope - libcontainer container 121c0a1028f282080034e239e829388082b0ca28008ef8bd4487c269591d55c5. Nov 12 22:24:44.805689 containerd[1952]: time="2024-11-12T22:24:44.805510299Z" level=info msg="StartContainer for \"121c0a1028f282080034e239e829388082b0ca28008ef8bd4487c269591d55c5\" returns successfully" Nov 12 22:24:44.862994 kubelet[2367]: E1112 22:24:44.862912 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:45.155370 kubelet[2367]: I1112 22:24:45.154884 2367 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.832274319 podStartE2EDuration="19.1548558s" podCreationTimestamp="2024-11-12 22:24:26 +0000 UTC" firstStartedPulling="2024-11-12 22:24:44.352346509 +0000 UTC m=+41.915254566" lastFinishedPulling="2024-11-12 22:24:44.674927978 +0000 UTC m=+42.237836047" observedRunningTime="2024-11-12 22:24:45.154778832 +0000 UTC m=+42.717686925" watchObservedRunningTime="2024-11-12 22:24:45.1548558 +0000 UTC m=+42.717763869" Nov 12 22:24:45.863196 kubelet[2367]: E1112 22:24:45.863115 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:45.976955 systemd-networkd[1562]: veth2013c741: Gained IPv6LL Nov 12 22:24:46.864097 kubelet[2367]: E1112 22:24:46.864022 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:47.864271 kubelet[2367]: E1112 22:24:47.864202 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:48.635954 ntpd[1918]: Listen normally on 12 veth2013c741 [fe80::8025:38ff:fe37:d15%7]:123 Nov 12 22:24:48.636690 ntpd[1918]: 12 Nov 22:24:48 ntpd[1918]: Listen normally on 12 veth2013c741 [fe80::8025:38ff:fe37:d15%7]:123 Nov 12 22:24:48.865110 kubelet[2367]: E1112 22:24:48.865036 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:49.865803 kubelet[2367]: E1112 22:24:49.865727 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:50.866398 kubelet[2367]: E1112 22:24:50.866330 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:51.867066 kubelet[2367]: E1112 22:24:51.866963 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:52.867861 kubelet[2367]: E1112 22:24:52.867753 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:53.868727 kubelet[2367]: E1112 22:24:53.868652 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:54.869585 kubelet[2367]: E1112 22:24:54.869489 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:55.870217 kubelet[2367]: E1112 22:24:55.870156 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:56.871138 kubelet[2367]: E1112 22:24:56.871065 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:57.871876 kubelet[2367]: E1112 22:24:57.871800 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:58.873056 kubelet[2367]: E1112 22:24:58.872950 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:24:59.873939 kubelet[2367]: E1112 22:24:59.873874 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:00.875031 kubelet[2367]: E1112 22:25:00.874963 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:01.875318 kubelet[2367]: E1112 22:25:01.875221 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:02.876011 kubelet[2367]: E1112 22:25:02.875852 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:03.834711 kubelet[2367]: E1112 22:25:03.834532 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:03.876264 kubelet[2367]: E1112 22:25:03.876190 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:04.876841 kubelet[2367]: E1112 22:25:04.876732 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:04.933061 kubelet[2367]: E1112 22:25:04.932966 2367 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:05.877696 kubelet[2367]: E1112 22:25:05.877632 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:06.878185 kubelet[2367]: E1112 22:25:06.878118 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:07.878483 kubelet[2367]: E1112 22:25:07.878382 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:08.878893 kubelet[2367]: E1112 22:25:08.878818 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:09.879517 kubelet[2367]: E1112 22:25:09.879427 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:10.880135 kubelet[2367]: E1112 22:25:10.880062 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:11.881120 kubelet[2367]: E1112 22:25:11.881005 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:12.882013 kubelet[2367]: E1112 22:25:12.881939 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:13.882626 kubelet[2367]: E1112 22:25:13.882515 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:14.883759 kubelet[2367]: E1112 22:25:14.883682 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:14.934259 kubelet[2367]: E1112 22:25:14.933998 2367 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:15.884459 kubelet[2367]: E1112 22:25:15.884386 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:16.884853 kubelet[2367]: E1112 22:25:16.884786 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:17.885499 kubelet[2367]: E1112 22:25:17.885429 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:18.886547 kubelet[2367]: E1112 22:25:18.886464 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:19.887766 kubelet[2367]: E1112 22:25:19.887663 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:20.888410 kubelet[2367]: E1112 22:25:20.888346 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:21.889238 kubelet[2367]: E1112 22:25:21.889160 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:22.889414 kubelet[2367]: E1112 22:25:22.889337 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:23.834422 kubelet[2367]: E1112 22:25:23.834349 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:23.890236 kubelet[2367]: E1112 22:25:23.890162 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:24.891192 kubelet[2367]: E1112 22:25:24.891087 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:24.935049 kubelet[2367]: E1112 22:25:24.934825 2367 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:25.891373 kubelet[2367]: E1112 22:25:25.891307 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:26.892115 kubelet[2367]: E1112 22:25:26.892029 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:27.893352 kubelet[2367]: E1112 22:25:27.893260 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:28.894269 kubelet[2367]: E1112 22:25:28.894197 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:29.895193 kubelet[2367]: E1112 22:25:29.895120 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:30.896053 kubelet[2367]: E1112 22:25:30.895988 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:31.897239 kubelet[2367]: E1112 22:25:31.897162 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:32.897788 kubelet[2367]: E1112 22:25:32.897724 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:33.898690 kubelet[2367]: E1112 22:25:33.898540 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:34.899339 kubelet[2367]: E1112 22:25:34.899269 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:34.935812 kubelet[2367]: E1112 22:25:34.935553 2367 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 22:25:34.962631 kubelet[2367]: E1112 22:25:34.961502 2367 controller.go:195] "Failed to update lease" err="Put \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": read tcp 172.31.18.195:55654->172.31.31.39:6443: read: connection reset by peer" Nov 12 22:25:34.962631 kubelet[2367]: I1112 22:25:34.961583 2367 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Nov 12 22:25:35.899973 kubelet[2367]: E1112 22:25:35.899879 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:35.974756 kubelet[2367]: E1112 22:25:35.974166 2367 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": dial tcp 172.31.31.39:6443: connect: connection refused - error from a previous attempt: read tcp 172.31.18.195:39290->172.31.31.39:6443: read: connection reset by peer" interval="200ms" Nov 12 22:25:36.900721 kubelet[2367]: E1112 22:25:36.900636 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:37.901694 kubelet[2367]: E1112 22:25:37.901624 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:38.902870 kubelet[2367]: E1112 22:25:38.902798 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:39.903660 kubelet[2367]: E1112 22:25:39.903521 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:40.904518 kubelet[2367]: E1112 22:25:40.904442 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:41.905527 kubelet[2367]: E1112 22:25:41.905430 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:42.906540 kubelet[2367]: E1112 22:25:42.906437 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:43.834556 kubelet[2367]: E1112 22:25:43.834479 2367 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:43.907552 kubelet[2367]: E1112 22:25:43.907468 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:44.908553 kubelet[2367]: E1112 22:25:44.908476 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:45.909869 kubelet[2367]: E1112 22:25:45.909767 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:46.176096 kubelet[2367]: E1112 22:25:46.175918 2367 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.31.39:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.195?timeout=10s\": net/http: request canceled while waiting for connection (Client.Timeout exceeded while awaiting headers)" interval="400ms" Nov 12 22:25:46.911025 kubelet[2367]: E1112 22:25:46.910940 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:47.911996 kubelet[2367]: E1112 22:25:47.911913 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:48.913142 kubelet[2367]: E1112 22:25:48.913010 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:49.913503 kubelet[2367]: E1112 22:25:49.913427 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:50.914364 kubelet[2367]: E1112 22:25:50.914295 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:51.915513 kubelet[2367]: E1112 22:25:51.915434 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:52.915995 kubelet[2367]: E1112 22:25:52.915923 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:53.917200 kubelet[2367]: E1112 22:25:53.917134 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:54.917619 kubelet[2367]: E1112 22:25:54.917526 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 22:25:55.918191 kubelet[2367]: E1112 22:25:55.918112 2367 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"