Nov 12 17:42:05.210301 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Nov 12 17:42:05.210345 kernel: Linux version 6.6.60-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Tue Nov 12 16:24:35 -00 2024 Nov 12 17:42:05.210370 kernel: KASLR disabled due to lack of seed Nov 12 17:42:05.210417 kernel: efi: EFI v2.7 by EDK II Nov 12 17:42:05.210463 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7852ee18 Nov 12 17:42:05.210480 kernel: ACPI: Early table checksum verification disabled Nov 12 17:42:05.210498 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Nov 12 17:42:05.210514 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Nov 12 17:42:05.210530 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Nov 12 17:42:05.210546 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Nov 12 17:42:05.210569 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Nov 12 17:42:05.210585 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Nov 12 17:42:05.210600 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Nov 12 17:42:05.210617 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Nov 12 17:42:05.210635 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Nov 12 17:42:05.210656 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Nov 12 17:42:05.210674 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Nov 12 17:42:05.210707 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Nov 12 17:42:05.210726 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Nov 12 17:42:05.210743 kernel: printk: bootconsole [uart0] enabled Nov 12 17:42:05.210760 kernel: NUMA: Failed to initialise from firmware Nov 12 17:42:05.210777 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 17:42:05.210794 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Nov 12 17:42:05.210810 kernel: Zone ranges: Nov 12 17:42:05.210827 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Nov 12 17:42:05.210844 kernel: DMA32 empty Nov 12 17:42:05.210866 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Nov 12 17:42:05.210883 kernel: Movable zone start for each node Nov 12 17:42:05.210899 kernel: Early memory node ranges Nov 12 17:42:05.210916 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Nov 12 17:42:05.210932 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Nov 12 17:42:05.210949 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Nov 12 17:42:05.210966 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Nov 12 17:42:05.210982 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Nov 12 17:42:05.210999 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Nov 12 17:42:05.211016 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Nov 12 17:42:05.211032 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Nov 12 17:42:05.211049 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Nov 12 17:42:05.211070 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Nov 12 17:42:05.211087 kernel: psci: probing for conduit method from ACPI. Nov 12 17:42:05.211111 kernel: psci: PSCIv1.0 detected in firmware. Nov 12 17:42:05.211129 kernel: psci: Using standard PSCI v0.2 function IDs Nov 12 17:42:05.211146 kernel: psci: Trusted OS migration not required Nov 12 17:42:05.211168 kernel: psci: SMC Calling Convention v1.1 Nov 12 17:42:05.211186 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Nov 12 17:42:05.211204 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Nov 12 17:42:05.211222 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 12 17:42:05.211239 kernel: Detected PIPT I-cache on CPU0 Nov 12 17:42:05.211257 kernel: CPU features: detected: GIC system register CPU interface Nov 12 17:42:05.211275 kernel: CPU features: detected: Spectre-v2 Nov 12 17:42:05.211292 kernel: CPU features: detected: Spectre-v3a Nov 12 17:42:05.211309 kernel: CPU features: detected: Spectre-BHB Nov 12 17:42:05.211327 kernel: CPU features: detected: ARM erratum 1742098 Nov 12 17:42:05.211345 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Nov 12 17:42:05.211366 kernel: alternatives: applying boot alternatives Nov 12 17:42:05.211401 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=8c276c03cfeb31103ba0b5f1af613bdc698463ad3d29e6750e34154929bf187e Nov 12 17:42:05.211445 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Nov 12 17:42:05.211464 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 12 17:42:05.211482 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 12 17:42:05.211500 kernel: Fallback order for Node 0: 0 Nov 12 17:42:05.211518 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Nov 12 17:42:05.211536 kernel: Policy zone: Normal Nov 12 17:42:05.211554 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 12 17:42:05.211572 kernel: software IO TLB: area num 2. Nov 12 17:42:05.211590 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Nov 12 17:42:05.211624 kernel: Memory: 3820216K/4030464K available (10240K kernel code, 2184K rwdata, 8096K rodata, 39360K init, 897K bss, 210248K reserved, 0K cma-reserved) Nov 12 17:42:05.211642 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 12 17:42:05.211660 kernel: trace event string verifier disabled Nov 12 17:42:05.211678 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 12 17:42:05.211696 kernel: rcu: RCU event tracing is enabled. Nov 12 17:42:05.211715 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 12 17:42:05.211733 kernel: Trampoline variant of Tasks RCU enabled. Nov 12 17:42:05.211751 kernel: Tracing variant of Tasks RCU enabled. Nov 12 17:42:05.211769 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 12 17:42:05.211787 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 12 17:42:05.211805 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 12 17:42:05.211827 kernel: GICv3: 96 SPIs implemented Nov 12 17:42:05.211845 kernel: GICv3: 0 Extended SPIs implemented Nov 12 17:42:05.211862 kernel: Root IRQ handler: gic_handle_irq Nov 12 17:42:05.211880 kernel: GICv3: GICv3 features: 16 PPIs Nov 12 17:42:05.211898 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Nov 12 17:42:05.211916 kernel: ITS [mem 0x10080000-0x1009ffff] Nov 12 17:42:05.211935 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) Nov 12 17:42:05.211954 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) Nov 12 17:42:05.211972 kernel: GICv3: using LPI property table @0x00000004000d0000 Nov 12 17:42:05.211991 kernel: ITS: Using hypervisor restricted LPI range [128] Nov 12 17:42:05.212010 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 Nov 12 17:42:05.212029 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 12 17:42:05.212053 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Nov 12 17:42:05.212072 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Nov 12 17:42:05.212090 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Nov 12 17:42:05.212109 kernel: Console: colour dummy device 80x25 Nov 12 17:42:05.212128 kernel: printk: console [tty1] enabled Nov 12 17:42:05.212147 kernel: ACPI: Core revision 20230628 Nov 12 17:42:05.212167 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Nov 12 17:42:05.212186 kernel: pid_max: default: 32768 minimum: 301 Nov 12 17:42:05.212204 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 12 17:42:05.212224 kernel: landlock: Up and running. Nov 12 17:42:05.212248 kernel: SELinux: Initializing. Nov 12 17:42:05.212268 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 17:42:05.212288 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 12 17:42:05.212306 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 17:42:05.212325 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 12 17:42:05.212343 kernel: rcu: Hierarchical SRCU implementation. Nov 12 17:42:05.212362 kernel: rcu: Max phase no-delay instances is 400. Nov 12 17:42:05.212380 kernel: Platform MSI: ITS@0x10080000 domain created Nov 12 17:42:05.212441 kernel: PCI/MSI: ITS@0x10080000 domain created Nov 12 17:42:05.212488 kernel: Remapping and enabling EFI services. Nov 12 17:42:05.212510 kernel: smp: Bringing up secondary CPUs ... Nov 12 17:42:05.212528 kernel: Detected PIPT I-cache on CPU1 Nov 12 17:42:05.212547 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Nov 12 17:42:05.212567 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 Nov 12 17:42:05.212586 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Nov 12 17:42:05.212606 kernel: smp: Brought up 1 node, 2 CPUs Nov 12 17:42:05.212625 kernel: SMP: Total of 2 processors activated. Nov 12 17:42:05.212644 kernel: CPU features: detected: 32-bit EL0 Support Nov 12 17:42:05.212671 kernel: CPU features: detected: 32-bit EL1 Support Nov 12 17:42:05.212690 kernel: CPU features: detected: CRC32 instructions Nov 12 17:42:05.212721 kernel: CPU: All CPU(s) started at EL1 Nov 12 17:42:05.212744 kernel: alternatives: applying system-wide alternatives Nov 12 17:42:05.212763 kernel: devtmpfs: initialized Nov 12 17:42:05.212782 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 12 17:42:05.212801 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 12 17:42:05.212820 kernel: pinctrl core: initialized pinctrl subsystem Nov 12 17:42:05.212840 kernel: SMBIOS 3.0.0 present. Nov 12 17:42:05.212864 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Nov 12 17:42:05.212884 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 12 17:42:05.212903 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 12 17:42:05.212923 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 12 17:42:05.212942 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 12 17:42:05.212962 kernel: audit: initializing netlink subsys (disabled) Nov 12 17:42:05.212981 kernel: audit: type=2000 audit(0.319:1): state=initialized audit_enabled=0 res=1 Nov 12 17:42:05.213006 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 12 17:42:05.213027 kernel: cpuidle: using governor menu Nov 12 17:42:05.213047 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 12 17:42:05.213067 kernel: ASID allocator initialised with 65536 entries Nov 12 17:42:05.213085 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 12 17:42:05.213105 kernel: Serial: AMBA PL011 UART driver Nov 12 17:42:05.213124 kernel: Modules: 17520 pages in range for non-PLT usage Nov 12 17:42:05.213144 kernel: Modules: 509040 pages in range for PLT usage Nov 12 17:42:05.213164 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 12 17:42:05.213191 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 12 17:42:05.213211 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 12 17:42:05.213230 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 12 17:42:05.213250 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 12 17:42:05.213269 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 12 17:42:05.213289 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 12 17:42:05.213309 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 12 17:42:05.213328 kernel: ACPI: Added _OSI(Module Device) Nov 12 17:42:05.213347 kernel: ACPI: Added _OSI(Processor Device) Nov 12 17:42:05.213372 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 12 17:42:05.213443 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 12 17:42:05.213467 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 12 17:42:05.213486 kernel: ACPI: Interpreter enabled Nov 12 17:42:05.213506 kernel: ACPI: Using GIC for interrupt routing Nov 12 17:42:05.213525 kernel: ACPI: MCFG table detected, 1 entries Nov 12 17:42:05.213545 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Nov 12 17:42:05.214537 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 12 17:42:05.214858 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 12 17:42:05.215098 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 12 17:42:05.215322 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Nov 12 17:42:05.215669 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Nov 12 17:42:05.215714 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Nov 12 17:42:05.215734 kernel: acpiphp: Slot [1] registered Nov 12 17:42:05.215754 kernel: acpiphp: Slot [2] registered Nov 12 17:42:05.215774 kernel: acpiphp: Slot [3] registered Nov 12 17:42:05.215807 kernel: acpiphp: Slot [4] registered Nov 12 17:42:05.215826 kernel: acpiphp: Slot [5] registered Nov 12 17:42:05.215846 kernel: acpiphp: Slot [6] registered Nov 12 17:42:05.215865 kernel: acpiphp: Slot [7] registered Nov 12 17:42:05.215885 kernel: acpiphp: Slot [8] registered Nov 12 17:42:05.215904 kernel: acpiphp: Slot [9] registered Nov 12 17:42:05.215923 kernel: acpiphp: Slot [10] registered Nov 12 17:42:05.215942 kernel: acpiphp: Slot [11] registered Nov 12 17:42:05.215961 kernel: acpiphp: Slot [12] registered Nov 12 17:42:05.215980 kernel: acpiphp: Slot [13] registered Nov 12 17:42:05.216004 kernel: acpiphp: Slot [14] registered Nov 12 17:42:05.216023 kernel: acpiphp: Slot [15] registered Nov 12 17:42:05.216043 kernel: acpiphp: Slot [16] registered Nov 12 17:42:05.216062 kernel: acpiphp: Slot [17] registered Nov 12 17:42:05.216081 kernel: acpiphp: Slot [18] registered Nov 12 17:42:05.216099 kernel: acpiphp: Slot [19] registered Nov 12 17:42:05.216118 kernel: acpiphp: Slot [20] registered Nov 12 17:42:05.216137 kernel: acpiphp: Slot [21] registered Nov 12 17:42:05.216156 kernel: acpiphp: Slot [22] registered Nov 12 17:42:05.216179 kernel: acpiphp: Slot [23] registered Nov 12 17:42:05.216199 kernel: acpiphp: Slot [24] registered Nov 12 17:42:05.216217 kernel: acpiphp: Slot [25] registered Nov 12 17:42:05.216236 kernel: acpiphp: Slot [26] registered Nov 12 17:42:05.216255 kernel: acpiphp: Slot [27] registered Nov 12 17:42:05.216274 kernel: acpiphp: Slot [28] registered Nov 12 17:42:05.216293 kernel: acpiphp: Slot [29] registered Nov 12 17:42:05.216312 kernel: acpiphp: Slot [30] registered Nov 12 17:42:05.216332 kernel: acpiphp: Slot [31] registered Nov 12 17:42:05.216351 kernel: PCI host bridge to bus 0000:00 Nov 12 17:42:05.217707 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Nov 12 17:42:05.217927 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 12 17:42:05.218124 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Nov 12 17:42:05.218326 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Nov 12 17:42:05.218662 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Nov 12 17:42:05.219065 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Nov 12 17:42:05.219679 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Nov 12 17:42:05.219948 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Nov 12 17:42:05.220170 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Nov 12 17:42:05.221486 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 17:42:05.221808 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Nov 12 17:42:05.222053 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Nov 12 17:42:05.222351 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Nov 12 17:42:05.222658 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Nov 12 17:42:05.222907 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Nov 12 17:42:05.223137 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Nov 12 17:42:05.223365 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Nov 12 17:42:05.223627 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Nov 12 17:42:05.223851 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Nov 12 17:42:05.224077 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Nov 12 17:42:05.224298 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Nov 12 17:42:05.228311 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 12 17:42:05.228591 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Nov 12 17:42:05.228627 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 12 17:42:05.228649 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 12 17:42:05.228670 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 12 17:42:05.228691 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 12 17:42:05.228711 kernel: iommu: Default domain type: Translated Nov 12 17:42:05.228744 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 12 17:42:05.228765 kernel: efivars: Registered efivars operations Nov 12 17:42:05.228785 kernel: vgaarb: loaded Nov 12 17:42:05.228805 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 12 17:42:05.228825 kernel: VFS: Disk quotas dquot_6.6.0 Nov 12 17:42:05.228846 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 12 17:42:05.228867 kernel: pnp: PnP ACPI init Nov 12 17:42:05.229167 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Nov 12 17:42:05.229220 kernel: pnp: PnP ACPI: found 1 devices Nov 12 17:42:05.229241 kernel: NET: Registered PF_INET protocol family Nov 12 17:42:05.229261 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 12 17:42:05.229286 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 12 17:42:05.229307 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 12 17:42:05.229327 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 12 17:42:05.229348 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 12 17:42:05.229368 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 12 17:42:05.229425 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 17:42:05.229459 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 12 17:42:05.229480 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 12 17:42:05.229500 kernel: PCI: CLS 0 bytes, default 64 Nov 12 17:42:05.229520 kernel: kvm [1]: HYP mode not available Nov 12 17:42:05.229540 kernel: Initialise system trusted keyrings Nov 12 17:42:05.229561 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 12 17:42:05.229581 kernel: Key type asymmetric registered Nov 12 17:42:05.229601 kernel: Asymmetric key parser 'x509' registered Nov 12 17:42:05.229621 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 12 17:42:05.229646 kernel: io scheduler mq-deadline registered Nov 12 17:42:05.229666 kernel: io scheduler kyber registered Nov 12 17:42:05.229686 kernel: io scheduler bfq registered Nov 12 17:42:05.229996 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Nov 12 17:42:05.230034 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 12 17:42:05.230054 kernel: ACPI: button: Power Button [PWRB] Nov 12 17:42:05.230074 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Nov 12 17:42:05.230094 kernel: ACPI: button: Sleep Button [SLPB] Nov 12 17:42:05.230122 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 12 17:42:05.230143 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Nov 12 17:42:05.234543 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Nov 12 17:42:05.234603 kernel: printk: console [ttyS0] disabled Nov 12 17:42:05.234624 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Nov 12 17:42:05.234644 kernel: printk: console [ttyS0] enabled Nov 12 17:42:05.234664 kernel: printk: bootconsole [uart0] disabled Nov 12 17:42:05.234699 kernel: thunder_xcv, ver 1.0 Nov 12 17:42:05.234723 kernel: thunder_bgx, ver 1.0 Nov 12 17:42:05.234743 kernel: nicpf, ver 1.0 Nov 12 17:42:05.234774 kernel: nicvf, ver 1.0 Nov 12 17:42:05.235080 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 12 17:42:05.235292 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-11-12T17:42:04 UTC (1731433324) Nov 12 17:42:05.235320 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 12 17:42:05.235340 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Nov 12 17:42:05.235360 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 12 17:42:05.235379 kernel: watchdog: Hard watchdog permanently disabled Nov 12 17:42:05.235438 kernel: NET: Registered PF_INET6 protocol family Nov 12 17:42:05.235458 kernel: Segment Routing with IPv6 Nov 12 17:42:05.235478 kernel: In-situ OAM (IOAM) with IPv6 Nov 12 17:42:05.235497 kernel: NET: Registered PF_PACKET protocol family Nov 12 17:42:05.235516 kernel: Key type dns_resolver registered Nov 12 17:42:05.235535 kernel: registered taskstats version 1 Nov 12 17:42:05.235554 kernel: Loading compiled-in X.509 certificates Nov 12 17:42:05.235574 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.60-flatcar: 277bea35d8d47c9841f307ab609d4271c3622dcb' Nov 12 17:42:05.235593 kernel: Key type .fscrypt registered Nov 12 17:42:05.235612 kernel: Key type fscrypt-provisioning registered Nov 12 17:42:05.235636 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 12 17:42:05.235655 kernel: ima: Allocated hash algorithm: sha1 Nov 12 17:42:05.235674 kernel: ima: No architecture policies found Nov 12 17:42:05.235693 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 12 17:42:05.235712 kernel: clk: Disabling unused clocks Nov 12 17:42:05.235733 kernel: Freeing unused kernel memory: 39360K Nov 12 17:42:05.235752 kernel: Run /init as init process Nov 12 17:42:05.235771 kernel: with arguments: Nov 12 17:42:05.235790 kernel: /init Nov 12 17:42:05.235813 kernel: with environment: Nov 12 17:42:05.235833 kernel: HOME=/ Nov 12 17:42:05.235852 kernel: TERM=linux Nov 12 17:42:05.235871 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 12 17:42:05.235895 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 17:42:05.235920 systemd[1]: Detected virtualization amazon. Nov 12 17:42:05.235941 systemd[1]: Detected architecture arm64. Nov 12 17:42:05.235965 systemd[1]: Running in initrd. Nov 12 17:42:05.235986 systemd[1]: No hostname configured, using default hostname. Nov 12 17:42:05.236006 systemd[1]: Hostname set to . Nov 12 17:42:05.236027 systemd[1]: Initializing machine ID from VM UUID. Nov 12 17:42:05.236047 systemd[1]: Queued start job for default target initrd.target. Nov 12 17:42:05.236068 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 17:42:05.236089 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 17:42:05.236112 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 12 17:42:05.236139 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 17:42:05.236160 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 12 17:42:05.236182 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 12 17:42:05.236206 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 17:42:05.236227 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 12 17:42:05.236248 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 17:42:05.236270 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 17:42:05.236296 systemd[1]: Reached target paths.target - Path Units. Nov 12 17:42:05.236317 systemd[1]: Reached target slices.target - Slice Units. Nov 12 17:42:05.236338 systemd[1]: Reached target swap.target - Swaps. Nov 12 17:42:05.236359 systemd[1]: Reached target timers.target - Timer Units. Nov 12 17:42:05.236380 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 17:42:05.238531 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 17:42:05.238557 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 12 17:42:05.238580 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 12 17:42:05.238601 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 17:42:05.238635 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 17:42:05.238657 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 17:42:05.238678 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 17:42:05.238721 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 12 17:42:05.238743 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 17:42:05.238764 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 12 17:42:05.238786 systemd[1]: Starting systemd-fsck-usr.service... Nov 12 17:42:05.238807 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 17:42:05.238834 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 17:42:05.238856 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 17:42:05.238880 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 12 17:42:05.238903 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 17:42:05.239001 systemd-journald[251]: Collecting audit messages is disabled. Nov 12 17:42:05.239066 systemd[1]: Finished systemd-fsck-usr.service. Nov 12 17:42:05.239094 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 12 17:42:05.239118 systemd-journald[251]: Journal started Nov 12 17:42:05.239164 systemd-journald[251]: Runtime Journal (/run/log/journal/ec2ba8939b6c1cc4f14e2538c95cb30b) is 8.0M, max 75.3M, 67.3M free. Nov 12 17:42:05.219626 systemd-modules-load[252]: Inserted module 'overlay' Nov 12 17:42:05.244611 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 17:42:05.254430 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 17:42:05.259356 kernel: Bridge firewalling registered Nov 12 17:42:05.259482 systemd-modules-load[252]: Inserted module 'br_netfilter' Nov 12 17:42:05.263059 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 17:42:05.266578 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 17:42:05.284881 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 17:42:05.298672 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 17:42:05.311773 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 17:42:05.316186 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 12 17:42:05.321693 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 17:42:05.354503 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 17:42:05.364345 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 17:42:05.375860 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 17:42:05.386787 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 17:42:05.391327 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 17:42:05.403813 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 12 17:42:05.447339 dracut-cmdline[288]: dracut-dracut-053 Nov 12 17:42:05.459005 dracut-cmdline[288]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=8c276c03cfeb31103ba0b5f1af613bdc698463ad3d29e6750e34154929bf187e Nov 12 17:42:05.484684 systemd-resolved[287]: Positive Trust Anchors: Nov 12 17:42:05.486568 systemd-resolved[287]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 17:42:05.486640 systemd-resolved[287]: 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 17:42:05.618421 kernel: SCSI subsystem initialized Nov 12 17:42:05.626427 kernel: Loading iSCSI transport class v2.0-870. Nov 12 17:42:05.639435 kernel: iscsi: registered transport (tcp) Nov 12 17:42:05.662431 kernel: iscsi: registered transport (qla4xxx) Nov 12 17:42:05.662505 kernel: QLogic iSCSI HBA Driver Nov 12 17:42:05.728572 kernel: random: crng init done Nov 12 17:42:05.728934 systemd-resolved[287]: Defaulting to hostname 'linux'. Nov 12 17:42:05.732817 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 17:42:05.736995 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 17:42:05.762874 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 12 17:42:05.775757 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 12 17:42:05.817148 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 12 17:42:05.817236 kernel: device-mapper: uevent: version 1.0.3 Nov 12 17:42:05.818876 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 12 17:42:05.891484 kernel: raid6: neonx8 gen() 6552 MB/s Nov 12 17:42:05.908449 kernel: raid6: neonx4 gen() 6455 MB/s Nov 12 17:42:05.925444 kernel: raid6: neonx2 gen() 5396 MB/s Nov 12 17:42:05.942452 kernel: raid6: neonx1 gen() 3912 MB/s Nov 12 17:42:05.959433 kernel: raid6: int64x8 gen() 3808 MB/s Nov 12 17:42:05.976425 kernel: raid6: int64x4 gen() 3708 MB/s Nov 12 17:42:05.993419 kernel: raid6: int64x2 gen() 3597 MB/s Nov 12 17:42:06.011209 kernel: raid6: int64x1 gen() 2768 MB/s Nov 12 17:42:06.011248 kernel: raid6: using algorithm neonx8 gen() 6552 MB/s Nov 12 17:42:06.029164 kernel: raid6: .... xor() 4877 MB/s, rmw enabled Nov 12 17:42:06.029203 kernel: raid6: using neon recovery algorithm Nov 12 17:42:06.037662 kernel: xor: measuring software checksum speed Nov 12 17:42:06.037720 kernel: 8regs : 10974 MB/sec Nov 12 17:42:06.038763 kernel: 32regs : 11943 MB/sec Nov 12 17:42:06.039931 kernel: arm64_neon : 9557 MB/sec Nov 12 17:42:06.039973 kernel: xor: using function: 32regs (11943 MB/sec) Nov 12 17:42:06.125450 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 12 17:42:06.148472 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 12 17:42:06.171696 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 17:42:06.215811 systemd-udevd[470]: Using default interface naming scheme 'v255'. Nov 12 17:42:06.225755 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 17:42:06.236720 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 12 17:42:06.277352 dracut-pre-trigger[474]: rd.md=0: removing MD RAID activation Nov 12 17:42:06.344773 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 17:42:06.353721 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 17:42:06.478695 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 17:42:06.490707 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 12 17:42:06.534453 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 12 17:42:06.539523 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 17:42:06.544730 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 17:42:06.550082 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 17:42:06.571701 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 12 17:42:06.625159 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 12 17:42:06.709473 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 12 17:42:06.709543 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Nov 12 17:42:06.734256 kernel: ena 0000:00:05.0: ENA device version: 0.10 Nov 12 17:42:06.735129 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Nov 12 17:42:06.735452 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:23:9b:fa:93:93 Nov 12 17:42:06.727173 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 17:42:06.727547 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 17:42:06.757614 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Nov 12 17:42:06.757675 kernel: nvme nvme0: pci function 0000:00:04.0 Nov 12 17:42:06.732760 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 17:42:06.735028 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 17:42:06.735326 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 17:42:06.739490 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 17:42:06.739938 (udev-worker)[517]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:42:06.777319 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 17:42:06.788517 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 12 17:42:06.798265 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 12 17:42:06.798350 kernel: GPT:9289727 != 16777215 Nov 12 17:42:06.798379 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 12 17:42:06.798446 kernel: GPT:9289727 != 16777215 Nov 12 17:42:06.798474 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 12 17:42:06.798500 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 17:42:06.813249 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 17:42:06.828632 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 12 17:42:06.855484 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 17:42:06.940480 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (532) Nov 12 17:42:06.979427 kernel: BTRFS: device fsid 93a9d474-e751-47b7-a65f-e39ca9abd47a devid 1 transid 40 /dev/nvme0n1p3 scanned by (udev-worker) (517) Nov 12 17:42:06.999197 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Nov 12 17:42:07.053566 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Nov 12 17:42:07.083936 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 17:42:07.099048 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Nov 12 17:42:07.105003 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Nov 12 17:42:07.121887 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 12 17:42:07.138325 disk-uuid[660]: Primary Header is updated. Nov 12 17:42:07.138325 disk-uuid[660]: Secondary Entries is updated. Nov 12 17:42:07.138325 disk-uuid[660]: Secondary Header is updated. Nov 12 17:42:07.150471 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 17:42:07.159475 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 17:42:08.166428 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 12 17:42:08.168315 disk-uuid[661]: The operation has completed successfully. Nov 12 17:42:08.356692 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 12 17:42:08.356963 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 12 17:42:08.406675 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 12 17:42:08.417563 sh[921]: Success Nov 12 17:42:08.446458 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 12 17:42:08.568153 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 12 17:42:08.576127 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 12 17:42:08.586620 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 12 17:42:08.628654 kernel: BTRFS info (device dm-0): first mount of filesystem 93a9d474-e751-47b7-a65f-e39ca9abd47a Nov 12 17:42:08.628718 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 12 17:42:08.628756 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 12 17:42:08.630016 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 12 17:42:08.631134 kernel: BTRFS info (device dm-0): using free space tree Nov 12 17:42:08.752430 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 12 17:42:08.772119 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 12 17:42:08.777193 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 12 17:42:08.793672 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 12 17:42:08.800689 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 12 17:42:08.819026 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 936a2172-6c61-4af6-a047-e38e0a3ff18b Nov 12 17:42:08.819149 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 17:42:08.819195 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 17:42:08.825440 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 17:42:08.848860 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 12 17:42:08.851776 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 936a2172-6c61-4af6-a047-e38e0a3ff18b Nov 12 17:42:08.878483 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 12 17:42:08.898000 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 12 17:42:09.041295 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 17:42:09.048878 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 17:42:09.115044 systemd-networkd[1113]: lo: Link UP Nov 12 17:42:09.115081 systemd-networkd[1113]: lo: Gained carrier Nov 12 17:42:09.121713 systemd-networkd[1113]: Enumeration completed Nov 12 17:42:09.121955 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 17:42:09.124874 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 17:42:09.124882 systemd-networkd[1113]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 17:42:09.125351 systemd[1]: Reached target network.target - Network. Nov 12 17:42:09.140214 systemd-networkd[1113]: eth0: Link UP Nov 12 17:42:09.140240 systemd-networkd[1113]: eth0: Gained carrier Nov 12 17:42:09.140269 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 17:42:09.156550 systemd-networkd[1113]: eth0: DHCPv4 address 172.31.18.135/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 17:42:09.397014 ignition[1024]: Ignition 2.19.0 Nov 12 17:42:09.397045 ignition[1024]: Stage: fetch-offline Nov 12 17:42:09.398986 ignition[1024]: no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:09.399037 ignition[1024]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:09.403102 ignition[1024]: Ignition finished successfully Nov 12 17:42:09.409484 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 17:42:09.420002 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 12 17:42:09.455528 ignition[1122]: Ignition 2.19.0 Nov 12 17:42:09.455557 ignition[1122]: Stage: fetch Nov 12 17:42:09.457557 ignition[1122]: no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:09.457589 ignition[1122]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:09.457788 ignition[1122]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:09.476248 ignition[1122]: PUT result: OK Nov 12 17:42:09.480050 ignition[1122]: parsed url from cmdline: "" Nov 12 17:42:09.480068 ignition[1122]: no config URL provided Nov 12 17:42:09.480085 ignition[1122]: reading system config file "/usr/lib/ignition/user.ign" Nov 12 17:42:09.480110 ignition[1122]: no config at "/usr/lib/ignition/user.ign" Nov 12 17:42:09.480143 ignition[1122]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:09.488504 ignition[1122]: PUT result: OK Nov 12 17:42:09.489906 ignition[1122]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Nov 12 17:42:09.492677 ignition[1122]: GET result: OK Nov 12 17:42:09.492788 ignition[1122]: parsing config with SHA512: 9f7f0fd8d616c7937c49d91af6c9b03dfbc549ab5b5cb79b78b20bfd9b632e68818a9fc7db1df0a52e09bfa64a875ad984b70405ba92fed94d0fd06f9967e8ae Nov 12 17:42:09.499141 unknown[1122]: fetched base config from "system" Nov 12 17:42:09.499439 unknown[1122]: fetched base config from "system" Nov 12 17:42:09.499883 ignition[1122]: fetch: fetch complete Nov 12 17:42:09.499455 unknown[1122]: fetched user config from "aws" Nov 12 17:42:09.499897 ignition[1122]: fetch: fetch passed Nov 12 17:42:09.499997 ignition[1122]: Ignition finished successfully Nov 12 17:42:09.512653 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 12 17:42:09.523837 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 12 17:42:09.556040 ignition[1128]: Ignition 2.19.0 Nov 12 17:42:09.556070 ignition[1128]: Stage: kargs Nov 12 17:42:09.557035 ignition[1128]: no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:09.557064 ignition[1128]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:09.557223 ignition[1128]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:09.559583 ignition[1128]: PUT result: OK Nov 12 17:42:09.567441 ignition[1128]: kargs: kargs passed Nov 12 17:42:09.567558 ignition[1128]: Ignition finished successfully Nov 12 17:42:09.574074 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 12 17:42:09.596825 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 12 17:42:09.627735 ignition[1134]: Ignition 2.19.0 Nov 12 17:42:09.627764 ignition[1134]: Stage: disks Nov 12 17:42:09.628649 ignition[1134]: no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:09.628686 ignition[1134]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:09.628914 ignition[1134]: PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:09.632154 ignition[1134]: PUT result: OK Nov 12 17:42:09.643113 ignition[1134]: disks: disks passed Nov 12 17:42:09.643251 ignition[1134]: Ignition finished successfully Nov 12 17:42:09.647606 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 12 17:42:09.653541 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 12 17:42:09.657751 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 12 17:42:09.662485 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 17:42:09.664689 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 17:42:09.666838 systemd[1]: Reached target basic.target - Basic System. Nov 12 17:42:09.684949 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 12 17:42:09.734190 systemd-fsck[1143]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 12 17:42:09.746497 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 12 17:42:09.759764 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 12 17:42:09.863445 kernel: EXT4-fs (nvme0n1p9): mounted filesystem b3af0fd7-3c7c-4cdc-9b88-dae3d10ea922 r/w with ordered data mode. Quota mode: none. Nov 12 17:42:09.864145 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 12 17:42:09.869040 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 12 17:42:09.894583 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 17:42:09.901623 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 12 17:42:09.905596 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 12 17:42:09.905782 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 12 17:42:09.909071 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 17:42:09.929915 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1162) Nov 12 17:42:09.930006 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 936a2172-6c61-4af6-a047-e38e0a3ff18b Nov 12 17:42:09.932301 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 17:42:09.933879 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 17:42:09.938816 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 12 17:42:09.944000 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 17:42:09.953709 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 12 17:42:09.958176 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 17:42:10.330614 systemd-networkd[1113]: eth0: Gained IPv6LL Nov 12 17:42:10.560177 initrd-setup-root[1186]: cut: /sysroot/etc/passwd: No such file or directory Nov 12 17:42:10.587984 initrd-setup-root[1193]: cut: /sysroot/etc/group: No such file or directory Nov 12 17:42:10.598260 initrd-setup-root[1200]: cut: /sysroot/etc/shadow: No such file or directory Nov 12 17:42:10.608005 initrd-setup-root[1207]: cut: /sysroot/etc/gshadow: No such file or directory Nov 12 17:42:11.086535 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 12 17:42:11.100592 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 12 17:42:11.105840 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 12 17:42:11.129431 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 936a2172-6c61-4af6-a047-e38e0a3ff18b Nov 12 17:42:11.128326 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 12 17:42:11.175933 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 12 17:42:11.184597 ignition[1275]: INFO : Ignition 2.19.0 Nov 12 17:42:11.184597 ignition[1275]: INFO : Stage: mount Nov 12 17:42:11.188598 ignition[1275]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:11.188598 ignition[1275]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:11.188598 ignition[1275]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:11.199883 ignition[1275]: INFO : PUT result: OK Nov 12 17:42:11.204011 ignition[1275]: INFO : mount: mount passed Nov 12 17:42:11.206948 ignition[1275]: INFO : Ignition finished successfully Nov 12 17:42:11.210274 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 12 17:42:11.231662 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 12 17:42:11.253986 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 12 17:42:11.270801 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1286) Nov 12 17:42:11.270862 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 936a2172-6c61-4af6-a047-e38e0a3ff18b Nov 12 17:42:11.273940 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Nov 12 17:42:11.273976 kernel: BTRFS info (device nvme0n1p6): using free space tree Nov 12 17:42:11.280446 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 12 17:42:11.282821 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 12 17:42:11.319196 ignition[1303]: INFO : Ignition 2.19.0 Nov 12 17:42:11.319196 ignition[1303]: INFO : Stage: files Nov 12 17:42:11.323499 ignition[1303]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:11.323499 ignition[1303]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:11.323499 ignition[1303]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:11.323499 ignition[1303]: INFO : PUT result: OK Nov 12 17:42:11.344044 ignition[1303]: DEBUG : files: compiled without relabeling support, skipping Nov 12 17:42:11.346501 ignition[1303]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 12 17:42:11.346501 ignition[1303]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 12 17:42:11.368493 ignition[1303]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 12 17:42:11.371689 ignition[1303]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 12 17:42:11.374691 unknown[1303]: wrote ssh authorized keys file for user: core Nov 12 17:42:11.377149 ignition[1303]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 12 17:42:11.381055 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 17:42:11.385851 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.29.2-arm64.raw: attempt #1 Nov 12 17:42:11.896822 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 12 17:42:12.287871 ignition[1303]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.29.2-arm64.raw" Nov 12 17:42:12.293238 ignition[1303]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 12 17:42:12.293238 ignition[1303]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 12 17:42:12.293238 ignition[1303]: INFO : files: files passed Nov 12 17:42:12.293238 ignition[1303]: INFO : Ignition finished successfully Nov 12 17:42:12.303292 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 12 17:42:12.316747 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 12 17:42:12.324984 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 12 17:42:12.335283 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 12 17:42:12.338371 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 12 17:42:12.365427 initrd-setup-root-after-ignition[1331]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 17:42:12.369041 initrd-setup-root-after-ignition[1335]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 12 17:42:12.372067 initrd-setup-root-after-ignition[1331]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 12 17:42:12.377046 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 17:42:12.382156 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 12 17:42:12.395819 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 12 17:42:12.452875 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 12 17:42:12.454481 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 12 17:42:12.460953 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 12 17:42:12.462918 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 12 17:42:12.464903 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 12 17:42:12.468700 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 12 17:42:12.517867 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 17:42:12.531708 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 12 17:42:12.555791 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 12 17:42:12.560414 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 17:42:12.563139 systemd[1]: Stopped target timers.target - Timer Units. Nov 12 17:42:12.569490 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 12 17:42:12.570199 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 12 17:42:12.576457 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 12 17:42:12.578735 systemd[1]: Stopped target basic.target - Basic System. Nov 12 17:42:12.583960 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 12 17:42:12.586169 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 12 17:42:12.589019 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 12 17:42:12.596987 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 12 17:42:12.599505 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 12 17:42:12.602055 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 12 17:42:12.608005 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 12 17:42:12.611674 systemd[1]: Stopped target swap.target - Swaps. Nov 12 17:42:12.616382 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 12 17:42:12.616673 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 12 17:42:12.620974 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 12 17:42:12.623306 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 17:42:12.626329 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 12 17:42:12.628523 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 17:42:12.632140 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 12 17:42:12.632778 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 12 17:42:12.638465 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 12 17:42:12.638797 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 12 17:42:12.654523 systemd[1]: ignition-files.service: Deactivated successfully. Nov 12 17:42:12.654837 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 12 17:42:12.667767 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 12 17:42:12.669701 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 12 17:42:12.671888 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 17:42:12.698290 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 12 17:42:12.702620 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 12 17:42:12.706542 ignition[1355]: INFO : Ignition 2.19.0 Nov 12 17:42:12.706542 ignition[1355]: INFO : Stage: umount Nov 12 17:42:12.706542 ignition[1355]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 12 17:42:12.706542 ignition[1355]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Nov 12 17:42:12.706542 ignition[1355]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Nov 12 17:42:12.706485 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 17:42:12.729655 ignition[1355]: INFO : PUT result: OK Nov 12 17:42:12.729655 ignition[1355]: INFO : umount: umount passed Nov 12 17:42:12.729655 ignition[1355]: INFO : Ignition finished successfully Nov 12 17:42:12.709961 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 12 17:42:12.710287 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 12 17:42:12.739967 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 12 17:42:12.741872 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 12 17:42:12.750829 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 12 17:42:12.751769 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 12 17:42:12.760549 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 12 17:42:12.760739 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 12 17:42:12.764032 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 12 17:42:12.764144 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 12 17:42:12.768945 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 12 17:42:12.769100 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 12 17:42:12.775768 systemd[1]: Stopped target network.target - Network. Nov 12 17:42:12.783625 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 12 17:42:12.783757 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 12 17:42:12.792712 systemd[1]: Stopped target paths.target - Path Units. Nov 12 17:42:12.797744 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 12 17:42:12.815074 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 17:42:12.824648 systemd[1]: Stopped target slices.target - Slice Units. Nov 12 17:42:12.828044 systemd[1]: Stopped target sockets.target - Socket Units. Nov 12 17:42:12.830136 systemd[1]: iscsid.socket: Deactivated successfully. Nov 12 17:42:12.830232 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 12 17:42:12.832477 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 12 17:42:12.832559 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 12 17:42:12.834595 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 12 17:42:12.834714 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 12 17:42:12.837072 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 12 17:42:12.837167 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 12 17:42:12.839570 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 12 17:42:12.841824 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 12 17:42:12.848927 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 12 17:42:12.850214 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 12 17:42:12.851003 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 12 17:42:12.853919 systemd-networkd[1113]: eth0: DHCPv6 lease lost Nov 12 17:42:12.859252 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 12 17:42:12.859517 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 12 17:42:12.866863 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 12 17:42:12.866966 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 12 17:42:12.876896 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 12 17:42:12.877014 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 12 17:42:12.890855 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 12 17:42:12.900886 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 12 17:42:12.901025 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 12 17:42:12.912296 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 17:42:12.931031 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 12 17:42:12.931459 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 12 17:42:12.964309 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 12 17:42:12.967292 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 12 17:42:12.968817 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 12 17:42:12.968957 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 12 17:42:12.981600 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 12 17:42:12.981908 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 17:42:12.989788 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 12 17:42:12.990748 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 17:42:12.996849 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 12 17:42:12.997092 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 12 17:42:13.006565 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 12 17:42:13.006707 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 12 17:42:13.009240 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 12 17:42:13.009331 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 17:42:13.011591 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 12 17:42:13.011710 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 12 17:42:13.014546 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 12 17:42:13.014664 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 12 17:42:13.018929 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 12 17:42:13.019057 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 12 17:42:13.045256 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 12 17:42:13.053777 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 12 17:42:13.053913 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 17:42:13.056467 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 12 17:42:13.056579 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 17:42:13.077503 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 12 17:42:13.077819 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 12 17:42:13.087295 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 12 17:42:13.109861 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 12 17:42:13.128465 systemd[1]: Switching root. Nov 12 17:42:13.187702 systemd-journald[251]: Journal stopped Nov 12 17:42:16.417265 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). Nov 12 17:42:16.420484 kernel: SELinux: policy capability network_peer_controls=1 Nov 12 17:42:16.420571 kernel: SELinux: policy capability open_perms=1 Nov 12 17:42:16.420613 kernel: SELinux: policy capability extended_socket_class=1 Nov 12 17:42:16.420656 kernel: SELinux: policy capability always_check_network=0 Nov 12 17:42:16.420689 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 12 17:42:16.420723 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 12 17:42:16.420757 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 12 17:42:16.420786 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 12 17:42:16.420818 kernel: audit: type=1403 audit(1731433334.310:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 12 17:42:16.420860 systemd[1]: Successfully loaded SELinux policy in 91.540ms. Nov 12 17:42:16.420908 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 27.754ms. Nov 12 17:42:16.420947 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 17:42:16.420981 systemd[1]: Detected virtualization amazon. Nov 12 17:42:16.421021 systemd[1]: Detected architecture arm64. Nov 12 17:42:16.421053 systemd[1]: Detected first boot. Nov 12 17:42:16.421086 systemd[1]: Initializing machine ID from VM UUID. Nov 12 17:42:16.421119 zram_generator::config[1398]: No configuration found. Nov 12 17:42:16.421158 systemd[1]: Populated /etc with preset unit settings. Nov 12 17:42:16.421192 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 12 17:42:16.421230 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 12 17:42:16.421263 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 12 17:42:16.421295 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 12 17:42:16.421328 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 12 17:42:16.421363 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 12 17:42:16.423925 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 12 17:42:16.424305 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 12 17:42:16.424350 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 12 17:42:16.424901 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 12 17:42:16.424950 systemd[1]: Created slice user.slice - User and Session Slice. Nov 12 17:42:16.424985 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 12 17:42:16.425019 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 12 17:42:16.425049 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 12 17:42:16.425082 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 12 17:42:16.425122 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 12 17:42:16.425162 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 12 17:42:16.425194 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 12 17:42:16.427821 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 12 17:42:16.427858 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 12 17:42:16.427890 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 12 17:42:16.427924 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 12 17:42:16.427964 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 12 17:42:16.427998 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 12 17:42:16.428032 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 12 17:42:16.428063 systemd[1]: Reached target slices.target - Slice Units. Nov 12 17:42:16.428095 systemd[1]: Reached target swap.target - Swaps. Nov 12 17:42:16.428130 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 12 17:42:16.428167 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 12 17:42:16.428201 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 12 17:42:16.428238 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 12 17:42:16.428273 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 12 17:42:16.428309 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 12 17:42:16.428341 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 12 17:42:16.428374 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 12 17:42:16.429528 systemd[1]: Mounting media.mount - External Media Directory... Nov 12 17:42:16.429574 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 12 17:42:16.429605 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 12 17:42:16.429636 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 12 17:42:16.429672 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 17:42:16.429715 systemd[1]: Reached target machines.target - Containers. Nov 12 17:42:16.429748 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 12 17:42:16.429782 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 17:42:16.429812 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 12 17:42:16.429842 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 12 17:42:16.429872 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 17:42:16.429902 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 17:42:16.429933 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 17:42:16.429964 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 12 17:42:16.429999 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 17:42:16.430031 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 12 17:42:16.430062 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 12 17:42:16.430093 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 12 17:42:16.430124 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 12 17:42:16.430154 systemd[1]: Stopped systemd-fsck-usr.service. Nov 12 17:42:16.430184 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 12 17:42:16.430217 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 12 17:42:16.430248 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 12 17:42:16.430282 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 12 17:42:16.430314 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 12 17:42:16.430347 systemd[1]: verity-setup.service: Deactivated successfully. Nov 12 17:42:16.430379 systemd[1]: Stopped verity-setup.service. Nov 12 17:42:16.433239 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 12 17:42:16.433285 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 12 17:42:16.433319 kernel: fuse: init (API version 7.39) Nov 12 17:42:16.433353 systemd[1]: Mounted media.mount - External Media Directory. Nov 12 17:42:16.433410 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 12 17:42:16.433466 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 12 17:42:16.433500 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 12 17:42:16.433533 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 12 17:42:16.433566 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 12 17:42:16.433603 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 12 17:42:16.433637 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 17:42:16.433672 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 17:42:16.433704 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 17:42:16.433736 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 17:42:16.433768 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 12 17:42:16.433800 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 12 17:42:16.433834 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 12 17:42:16.433869 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 12 17:42:16.433910 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 12 17:42:16.433944 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 12 17:42:16.433986 kernel: loop: module loaded Nov 12 17:42:16.434019 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 12 17:42:16.434059 kernel: ACPI: bus type drm_connector registered Nov 12 17:42:16.434090 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 12 17:42:16.434129 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 12 17:42:16.434162 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 12 17:42:16.434196 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 12 17:42:16.434296 systemd-journald[1489]: Collecting audit messages is disabled. Nov 12 17:42:16.434361 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 12 17:42:16.439125 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 12 17:42:16.439192 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 17:42:16.439241 systemd-journald[1489]: Journal started Nov 12 17:42:16.439297 systemd-journald[1489]: Runtime Journal (/run/log/journal/ec2ba8939b6c1cc4f14e2538c95cb30b) is 8.0M, max 75.3M, 67.3M free. Nov 12 17:42:15.693366 systemd[1]: Queued start job for default target multi-user.target. Nov 12 17:42:15.771834 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 12 17:42:15.773053 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 12 17:42:16.457538 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 12 17:42:16.461118 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 17:42:16.477687 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 12 17:42:16.492459 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 12 17:42:16.512476 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 12 17:42:16.526730 systemd[1]: Started systemd-journald.service - Journal Service. Nov 12 17:42:16.539609 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 12 17:42:16.543741 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 17:42:16.544158 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 17:42:16.547164 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 17:42:16.548564 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 17:42:16.551042 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 12 17:42:16.553635 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 12 17:42:16.570033 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 12 17:42:16.573130 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 12 17:42:16.642496 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 12 17:42:16.647136 kernel: loop0: detected capacity change from 0 to 114432 Nov 12 17:42:16.657697 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 12 17:42:16.672893 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 12 17:42:16.675536 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 17:42:16.695745 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 12 17:42:16.698955 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 12 17:42:16.727853 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 12 17:42:16.740724 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 12 17:42:16.760646 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 12 17:42:16.765557 systemd-journald[1489]: Time spent on flushing to /var/log/journal/ec2ba8939b6c1cc4f14e2538c95cb30b is 73.008ms for 898 entries. Nov 12 17:42:16.765557 systemd-journald[1489]: System Journal (/var/log/journal/ec2ba8939b6c1cc4f14e2538c95cb30b) is 8.0M, max 195.6M, 187.6M free. Nov 12 17:42:16.878784 systemd-journald[1489]: Received client request to flush runtime journal. Nov 12 17:42:16.878877 kernel: loop1: detected capacity change from 0 to 114328 Nov 12 17:42:16.786343 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 12 17:42:16.788836 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 12 17:42:16.816904 udevadm[1538]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 12 17:42:16.871512 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 12 17:42:16.885748 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 12 17:42:16.890334 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 12 17:42:16.928500 kernel: loop2: detected capacity change from 0 to 52536 Nov 12 17:42:16.993949 systemd-tmpfiles[1545]: ACLs are not supported, ignoring. Nov 12 17:42:16.993989 systemd-tmpfiles[1545]: ACLs are not supported, ignoring. Nov 12 17:42:17.006287 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 12 17:42:17.050447 kernel: loop3: detected capacity change from 0 to 194512 Nov 12 17:42:17.103535 kernel: loop4: detected capacity change from 0 to 114432 Nov 12 17:42:17.122465 kernel: loop5: detected capacity change from 0 to 114328 Nov 12 17:42:17.137451 kernel: loop6: detected capacity change from 0 to 52536 Nov 12 17:42:17.150471 kernel: loop7: detected capacity change from 0 to 194512 Nov 12 17:42:17.168042 (sd-merge)[1552]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-ami'. Nov 12 17:42:17.169087 (sd-merge)[1552]: Merged extensions into '/usr'. Nov 12 17:42:17.182709 systemd[1]: Reloading requested from client PID 1509 ('systemd-sysext') (unit systemd-sysext.service)... Nov 12 17:42:17.182743 systemd[1]: Reloading... Nov 12 17:42:17.404537 zram_generator::config[1579]: No configuration found. Nov 12 17:42:17.759639 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 17:42:17.885255 systemd[1]: Reloading finished in 700 ms. Nov 12 17:42:17.934371 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 12 17:42:17.939556 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 12 17:42:17.964731 systemd[1]: Starting ensure-sysext.service... Nov 12 17:42:17.969031 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 12 17:42:17.976833 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 12 17:42:18.003615 systemd[1]: Reloading requested from client PID 1630 ('systemctl') (unit ensure-sysext.service)... Nov 12 17:42:18.003644 systemd[1]: Reloading... Nov 12 17:42:18.069842 systemd-udevd[1632]: Using default interface naming scheme 'v255'. Nov 12 17:42:18.080296 systemd-tmpfiles[1631]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 12 17:42:18.081127 systemd-tmpfiles[1631]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 12 17:42:18.083504 systemd-tmpfiles[1631]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 12 17:42:18.084253 systemd-tmpfiles[1631]: ACLs are not supported, ignoring. Nov 12 17:42:18.084473 systemd-tmpfiles[1631]: ACLs are not supported, ignoring. Nov 12 17:42:18.100065 systemd-tmpfiles[1631]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 17:42:18.100109 systemd-tmpfiles[1631]: Skipping /boot Nov 12 17:42:18.152873 systemd-tmpfiles[1631]: Detected autofs mount point /boot during canonicalization of boot. Nov 12 17:42:18.152895 systemd-tmpfiles[1631]: Skipping /boot Nov 12 17:42:18.283435 zram_generator::config[1668]: No configuration found. Nov 12 17:42:18.382200 (udev-worker)[1665]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:42:18.387436 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1676) Nov 12 17:42:18.387554 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1676) Nov 12 17:42:18.675503 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 17:42:18.699114 ldconfig[1505]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 12 17:42:18.774476 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1667) Nov 12 17:42:18.844570 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 12 17:42:18.845332 systemd[1]: Reloading finished in 841 ms. Nov 12 17:42:18.881956 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 12 17:42:18.887493 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 12 17:42:18.907594 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 12 17:42:18.970803 systemd[1]: Finished ensure-sysext.service. Nov 12 17:42:19.010903 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Nov 12 17:42:19.020763 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 12 17:42:19.022036 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 12 17:42:19.026751 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 12 17:42:19.037822 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 12 17:42:19.046780 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 12 17:42:19.054717 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 12 17:42:19.057218 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 12 17:42:19.061751 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 12 17:42:19.070642 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 12 17:42:19.079540 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 12 17:42:19.081661 systemd[1]: Reached target time-set.target - System Time Set. Nov 12 17:42:19.087749 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 12 17:42:19.096785 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 12 17:42:19.165225 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 12 17:42:19.165649 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 12 17:42:19.178827 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 12 17:42:19.181779 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 12 17:42:19.184549 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 12 17:42:19.187896 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 12 17:42:19.188200 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 12 17:42:19.214039 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 12 17:42:19.223097 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 12 17:42:19.244595 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 12 17:42:19.245849 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 12 17:42:19.248434 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 12 17:42:19.261512 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 12 17:42:19.278769 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 12 17:42:19.289777 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Nov 12 17:42:19.293146 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 12 17:42:19.306735 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 12 17:42:19.314781 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 12 17:42:19.368377 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 12 17:42:19.378648 lvm[1863]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 17:42:19.411885 augenrules[1869]: No rules Nov 12 17:42:19.407216 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Nov 12 17:42:19.432794 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 12 17:42:19.436541 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 12 17:42:19.441074 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 12 17:42:19.456654 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 12 17:42:19.461382 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 12 17:42:19.485254 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 12 17:42:19.503428 lvm[1880]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 12 17:42:19.523508 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 12 17:42:19.526436 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 17:42:19.560072 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 12 17:42:19.616595 systemd-networkd[1835]: lo: Link UP Nov 12 17:42:19.617198 systemd-networkd[1835]: lo: Gained carrier Nov 12 17:42:19.620497 systemd-networkd[1835]: Enumeration completed Nov 12 17:42:19.620719 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 12 17:42:19.626425 systemd-networkd[1835]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 17:42:19.626602 systemd-networkd[1835]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 12 17:42:19.629143 systemd-networkd[1835]: eth0: Link UP Nov 12 17:42:19.629960 systemd-networkd[1835]: eth0: Gained carrier Nov 12 17:42:19.630134 systemd-networkd[1835]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 12 17:42:19.630555 systemd-resolved[1837]: Positive Trust Anchors: Nov 12 17:42:19.630593 systemd-resolved[1837]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 12 17:42:19.630659 systemd-resolved[1837]: 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 17:42:19.633745 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 12 17:42:19.644554 systemd-networkd[1835]: eth0: DHCPv4 address 172.31.18.135/20, gateway 172.31.16.1 acquired from 172.31.16.1 Nov 12 17:42:19.649793 systemd-resolved[1837]: Defaulting to hostname 'linux'. Nov 12 17:42:19.654452 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 12 17:42:19.657042 systemd[1]: Reached target network.target - Network. Nov 12 17:42:19.659004 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 12 17:42:19.661789 systemd[1]: Reached target sysinit.target - System Initialization. Nov 12 17:42:19.664297 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 12 17:42:19.666928 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 17:42:19.669822 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 12 17:42:19.672549 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 12 17:42:19.675230 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 12 17:42:19.677827 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 12 17:42:19.677891 systemd[1]: Reached target paths.target - Path Units. Nov 12 17:42:19.679838 systemd[1]: Reached target timers.target - Timer Units. Nov 12 17:42:19.701040 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 12 17:42:19.707535 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 12 17:42:19.717458 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 12 17:42:19.721070 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 12 17:42:19.723788 systemd[1]: Reached target sockets.target - Socket Units. Nov 12 17:42:19.726207 systemd[1]: Reached target basic.target - Basic System. Nov 12 17:42:19.728798 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 12 17:42:19.728858 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 12 17:42:19.740575 systemd[1]: Starting containerd.service - containerd container runtime... Nov 12 17:42:19.747015 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 12 17:42:19.755914 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 12 17:42:19.761841 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 12 17:42:19.769972 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 12 17:42:19.772615 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 17:42:19.782828 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 12 17:42:19.788720 systemd[1]: Started ntpd.service - Network Time Service. Nov 12 17:42:19.796619 systemd[1]: Starting setup-oem.service - Setup OEM... Nov 12 17:42:19.804761 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 12 17:42:19.812692 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 12 17:42:19.817667 jq[1897]: false Nov 12 17:42:19.827744 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 12 17:42:19.830581 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 12 17:42:19.831468 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 12 17:42:19.844940 systemd[1]: Starting update-engine.service - Update Engine... Nov 12 17:42:19.869665 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 12 17:42:19.880243 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 12 17:42:19.883586 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 12 17:42:19.911336 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 12 17:42:19.943258 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 12 17:42:19.943664 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 12 17:42:19.961242 dbus-daemon[1896]: [system] SELinux support is enabled Nov 12 17:42:19.961627 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 12 17:42:19.977496 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 17:42:19.977573 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 12 17:42:19.980109 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 17:42:19.980152 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 12 17:42:19.987153 jq[1908]: true Nov 12 17:42:20.000211 dbus-daemon[1896]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1835 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Nov 12 17:42:20.010879 update_engine[1905]: I20241112 17:42:20.010707 1905 main.cc:92] Flatcar Update Engine starting Nov 12 17:42:20.037775 update_engine[1905]: I20241112 17:42:20.023670 1905 update_check_scheduler.cc:74] Next update check in 4m46s Nov 12 17:42:20.034852 systemd[1]: Started update-engine.service - Update Engine. Nov 12 17:42:20.048459 extend-filesystems[1898]: Found loop4 Nov 12 17:42:20.048459 extend-filesystems[1898]: Found loop5 Nov 12 17:42:20.048459 extend-filesystems[1898]: Found loop6 Nov 12 17:42:20.048459 extend-filesystems[1898]: Found loop7 Nov 12 17:42:20.048459 extend-filesystems[1898]: Found nvme0n1 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p1 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p2 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p3 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found usr Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p4 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p6 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p7 Nov 12 17:42:20.105631 extend-filesystems[1898]: Found nvme0n1p9 Nov 12 17:42:20.105631 extend-filesystems[1898]: Checking size of /dev/nvme0n1p9 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 15:49:27 UTC 2024 (1): Starting Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: ---------------------------------------------------- Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: ntp-4 is maintained by Network Time Foundation, Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: corporation. Support and training for ntp-4 are Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: available at https://www.nwtime.org/support Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: ---------------------------------------------------- Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: proto: precision = 0.108 usec (-23) Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: basedate set to 2024-10-31 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: gps base set to 2024-11-03 (week 2339) Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listen normally on 3 eth0 172.31.18.135:123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listen normally on 4 lo [::1]:123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: bind(21) AF_INET6 fe80::423:9bff:fefa:9393%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: unable to create socket on eth0 (5) for fe80::423:9bff:fefa:9393%2#123 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: failed to init interface for address fe80::423:9bff:fefa:9393%2 Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: Listening on routing socket on fd #21 for interface updates Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 17:42:20.127703 ntpd[1900]: 12 Nov 17:42:20 ntpd[1900]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 17:42:20.057829 ntpd[1900]: ntpd 4.2.8p17@1.4004-o Tue Nov 12 15:49:27 UTC 2024 (1): Starting Nov 12 17:42:20.049748 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Nov 12 17:42:20.133314 jq[1931]: true Nov 12 17:42:20.057889 ntpd[1900]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Nov 12 17:42:20.057005 (ntainerd)[1928]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 12 17:42:20.057910 ntpd[1900]: ---------------------------------------------------- Nov 12 17:42:20.065193 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 12 17:42:20.057928 ntpd[1900]: ntp-4 is maintained by Network Time Foundation, Nov 12 17:42:20.073695 systemd[1]: motdgen.service: Deactivated successfully. Nov 12 17:42:20.057948 ntpd[1900]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Nov 12 17:42:20.075517 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 12 17:42:20.057965 ntpd[1900]: corporation. Support and training for ntp-4 are Nov 12 17:42:20.057984 ntpd[1900]: available at https://www.nwtime.org/support Nov 12 17:42:20.058001 ntpd[1900]: ---------------------------------------------------- Nov 12 17:42:20.063499 ntpd[1900]: proto: precision = 0.108 usec (-23) Nov 12 17:42:20.074790 ntpd[1900]: basedate set to 2024-10-31 Nov 12 17:42:20.074826 ntpd[1900]: gps base set to 2024-11-03 (week 2339) Nov 12 17:42:20.079723 ntpd[1900]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 17:42:20.079830 ntpd[1900]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 17:42:20.080227 ntpd[1900]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 17:42:20.080326 ntpd[1900]: Listen normally on 3 eth0 172.31.18.135:123 Nov 12 17:42:20.080444 ntpd[1900]: Listen normally on 4 lo [::1]:123 Nov 12 17:42:20.080548 ntpd[1900]: bind(21) AF_INET6 fe80::423:9bff:fefa:9393%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 17:42:20.080595 ntpd[1900]: unable to create socket on eth0 (5) for fe80::423:9bff:fefa:9393%2#123 Nov 12 17:42:20.080622 ntpd[1900]: failed to init interface for address fe80::423:9bff:fefa:9393%2 Nov 12 17:42:20.080681 ntpd[1900]: Listening on routing socket on fd #21 for interface updates Nov 12 17:42:20.086278 ntpd[1900]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 17:42:20.086369 ntpd[1900]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 17:42:20.149498 systemd[1]: Finished setup-oem.service - Setup OEM. Nov 12 17:42:20.181434 extend-filesystems[1898]: Resized partition /dev/nvme0n1p9 Nov 12 17:42:20.188885 extend-filesystems[1948]: resize2fs 1.47.1 (20-May-2024) Nov 12 17:42:20.206512 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Nov 12 17:42:20.272511 systemd-logind[1904]: Watching system buttons on /dev/input/event0 (Power Button) Nov 12 17:42:20.272585 systemd-logind[1904]: Watching system buttons on /dev/input/event1 (Sleep Button) Nov 12 17:42:20.272983 systemd-logind[1904]: New seat seat0. Nov 12 17:42:20.274568 systemd[1]: Started systemd-logind.service - User Login Management. Nov 12 17:42:20.279708 coreos-metadata[1895]: Nov 12 17:42:20.277 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 17:42:20.288124 coreos-metadata[1895]: Nov 12 17:42:20.285 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Nov 12 17:42:20.295378 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.291 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.291 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.298 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.304 INFO Fetch failed with 404: resource not found Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.304 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.304 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.304 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.305 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.305 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.308 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.308 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.309 INFO Fetch successful Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.310 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Nov 12 17:42:20.339904 coreos-metadata[1895]: Nov 12 17:42:20.314 INFO Fetch successful Nov 12 17:42:20.345086 extend-filesystems[1948]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 12 17:42:20.345086 extend-filesystems[1948]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 12 17:42:20.345086 extend-filesystems[1948]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Nov 12 17:42:20.357612 extend-filesystems[1898]: Resized filesystem in /dev/nvme0n1p9 Nov 12 17:42:20.352292 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 12 17:42:20.356744 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 12 17:42:20.461262 bash[1972]: Updated "/home/core/.ssh/authorized_keys" Nov 12 17:42:20.465497 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 12 17:42:20.469140 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 12 17:42:20.472510 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 12 17:42:20.495345 systemd[1]: Starting sshkeys.service... Nov 12 17:42:20.545546 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1665) Nov 12 17:42:20.554654 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 12 17:42:20.597462 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 12 17:42:20.651650 locksmithd[1934]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 12 17:42:20.788973 dbus-daemon[1896]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 12 17:42:20.789249 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Nov 12 17:42:20.792884 dbus-daemon[1896]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=1933 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Nov 12 17:42:20.807559 systemd[1]: Starting polkit.service - Authorization Manager... Nov 12 17:42:20.912867 polkitd[2055]: Started polkitd version 121 Nov 12 17:42:20.975586 polkitd[2055]: Loading rules from directory /etc/polkit-1/rules.d Nov 12 17:42:20.975710 polkitd[2055]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 12 17:42:20.981849 polkitd[2055]: Finished loading, compiling and executing 2 rules Nov 12 17:42:20.993055 dbus-daemon[1896]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 12 17:42:20.993716 systemd[1]: Started polkit.service - Authorization Manager. Nov 12 17:42:21.000427 polkitd[2055]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 12 17:42:21.003060 coreos-metadata[2001]: Nov 12 17:42:21.002 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Nov 12 17:42:21.003060 coreos-metadata[2001]: Nov 12 17:42:21.003 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Nov 12 17:42:21.003930 coreos-metadata[2001]: Nov 12 17:42:21.003 INFO Fetch successful Nov 12 17:42:21.003930 coreos-metadata[2001]: Nov 12 17:42:21.003 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Nov 12 17:42:21.003930 coreos-metadata[2001]: Nov 12 17:42:21.003 INFO Fetch successful Nov 12 17:42:21.028042 unknown[2001]: wrote ssh authorized keys file for user: core Nov 12 17:42:21.059468 ntpd[1900]: bind(24) AF_INET6 fe80::423:9bff:fefa:9393%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 17:42:21.060307 ntpd[1900]: 12 Nov 17:42:21 ntpd[1900]: bind(24) AF_INET6 fe80::423:9bff:fefa:9393%2#123 flags 0x11 failed: Cannot assign requested address Nov 12 17:42:21.060307 ntpd[1900]: 12 Nov 17:42:21 ntpd[1900]: unable to create socket on eth0 (6) for fe80::423:9bff:fefa:9393%2#123 Nov 12 17:42:21.060307 ntpd[1900]: 12 Nov 17:42:21 ntpd[1900]: failed to init interface for address fe80::423:9bff:fefa:9393%2 Nov 12 17:42:21.059556 ntpd[1900]: unable to create socket on eth0 (6) for fe80::423:9bff:fefa:9393%2#123 Nov 12 17:42:21.059595 ntpd[1900]: failed to init interface for address fe80::423:9bff:fefa:9393%2 Nov 12 17:42:21.064953 systemd-resolved[1837]: System hostname changed to 'ip-172-31-18-135'. Nov 12 17:42:21.065093 systemd-hostnamed[1933]: Hostname set to (transient) Nov 12 17:42:21.081123 containerd[1928]: time="2024-11-12T17:42:21.080987182Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Nov 12 17:42:21.104198 update-ssh-keys[2090]: Updated "/home/core/.ssh/authorized_keys" Nov 12 17:42:21.107612 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 12 17:42:21.119680 systemd[1]: Finished sshkeys.service. Nov 12 17:42:21.155483 containerd[1928]: time="2024-11-12T17:42:21.154028350Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.157874 containerd[1928]: time="2024-11-12T17:42:21.157766494Z" 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 17:42:21.157874 containerd[1928]: time="2024-11-12T17:42:21.157863778Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 12 17:42:21.158071 containerd[1928]: time="2024-11-12T17:42:21.157913674Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 12 17:42:21.158302 containerd[1928]: time="2024-11-12T17:42:21.158242618Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 12 17:42:21.158415 containerd[1928]: time="2024-11-12T17:42:21.158302606Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.158559 containerd[1928]: time="2024-11-12T17:42:21.158506630Z" 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 17:42:21.158679 containerd[1928]: time="2024-11-12T17:42:21.158621662Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.159213 containerd[1928]: time="2024-11-12T17:42:21.159094882Z" 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 17:42:21.159213 containerd[1928]: time="2024-11-12T17:42:21.159188554Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.159540 containerd[1928]: time="2024-11-12T17:42:21.159238798Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 12 17:42:21.159540 containerd[1928]: time="2024-11-12T17:42:21.159270214Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.159718450Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.160336294Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 12 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.160682914Z" 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 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.160723330Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 12 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.160951486Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 12 17:42:21.161476 containerd[1928]: time="2024-11-12T17:42:21.161057758Z" level=info msg="metadata content store policy set" policy=shared Nov 12 17:42:21.169047 containerd[1928]: time="2024-11-12T17:42:21.168956746Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 12 17:42:21.169197 containerd[1928]: time="2024-11-12T17:42:21.169152454Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 12 17:42:21.169326 containerd[1928]: time="2024-11-12T17:42:21.169282462Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 12 17:42:21.169401 containerd[1928]: time="2024-11-12T17:42:21.169334662Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 12 17:42:21.169459 containerd[1928]: time="2024-11-12T17:42:21.169377358Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 12 17:42:21.169728 containerd[1928]: time="2024-11-12T17:42:21.169681402Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 12 17:42:21.170908 containerd[1928]: time="2024-11-12T17:42:21.170811658Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 12 17:42:21.171360 containerd[1928]: time="2024-11-12T17:42:21.171281758Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 12 17:42:21.171489 containerd[1928]: time="2024-11-12T17:42:21.171370726Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 12 17:42:21.171489 containerd[1928]: time="2024-11-12T17:42:21.171437914Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 12 17:42:21.171489 containerd[1928]: time="2024-11-12T17:42:21.171471826Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171615 containerd[1928]: time="2024-11-12T17:42:21.171505102Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171615 containerd[1928]: time="2024-11-12T17:42:21.171538258Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171615 containerd[1928]: time="2024-11-12T17:42:21.171571102Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171615 containerd[1928]: time="2024-11-12T17:42:21.171604306Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171783 containerd[1928]: time="2024-11-12T17:42:21.171641470Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171783 containerd[1928]: time="2024-11-12T17:42:21.171671674Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171783 containerd[1928]: time="2024-11-12T17:42:21.171708310Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 12 17:42:21.171783 containerd[1928]: time="2024-11-12T17:42:21.171750154Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.171969 containerd[1928]: time="2024-11-12T17:42:21.171781906Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.171969 containerd[1928]: time="2024-11-12T17:42:21.171813130Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.171969 containerd[1928]: time="2024-11-12T17:42:21.171848242Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.171969 containerd[1928]: time="2024-11-12T17:42:21.171882790Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.171969 containerd[1928]: time="2024-11-12T17:42:21.171949606Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.171988582Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172033258Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172074310Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172112734Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172150618Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172184314Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.172230 containerd[1928]: time="2024-11-12T17:42:21.172221298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172270006Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172327162Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172365322Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172455910Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172638742Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172892254Z" 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 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172943086Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.172979482Z" 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 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.173006962Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.173042602Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.173070202Z" level=info msg="NRI interface is disabled by configuration." Nov 12 17:42:21.174657 containerd[1928]: time="2024-11-12T17:42:21.173100454Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 12 17:42:21.175257 containerd[1928]: time="2024-11-12T17:42:21.173746174Z" 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 17:42:21.175257 containerd[1928]: time="2024-11-12T17:42:21.173888770Z" level=info msg="Connect containerd service" Nov 12 17:42:21.175257 containerd[1928]: time="2024-11-12T17:42:21.173973454Z" level=info msg="using legacy CRI server" Nov 12 17:42:21.175257 containerd[1928]: time="2024-11-12T17:42:21.173999638Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 12 17:42:21.175257 containerd[1928]: time="2024-11-12T17:42:21.174215734Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 12 17:42:21.177484 containerd[1928]: time="2024-11-12T17:42:21.176687290Z" 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 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.178436254Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.178617010Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.178954366Z" level=info msg="Start subscribing containerd event" Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.179065570Z" level=info msg="Start recovering state" Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.179228782Z" level=info msg="Start event monitor" Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.179263018Z" level=info msg="Start snapshots syncer" Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.179288194Z" level=info msg="Start cni network conf syncer for default" Nov 12 17:42:21.180458 containerd[1928]: time="2024-11-12T17:42:21.179323006Z" level=info msg="Start streaming server" Nov 12 17:42:21.183228 containerd[1928]: time="2024-11-12T17:42:21.183150250Z" level=info msg="containerd successfully booted in 0.104025s" Nov 12 17:42:21.183458 systemd[1]: Started containerd.service - containerd container runtime. Nov 12 17:42:21.338703 systemd-networkd[1835]: eth0: Gained IPv6LL Nov 12 17:42:21.343817 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 12 17:42:21.347718 systemd[1]: Reached target network-online.target - Network is Online. Nov 12 17:42:21.360003 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Nov 12 17:42:21.377940 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 17:42:21.385956 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 12 17:42:21.478764 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 12 17:42:21.512254 amazon-ssm-agent[2098]: Initializing new seelog logger Nov 12 17:42:21.515436 amazon-ssm-agent[2098]: New Seelog Logger Creation Complete Nov 12 17:42:21.515436 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.515436 amazon-ssm-agent[2098]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.515675 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 processing appconfig overrides Nov 12 17:42:21.518951 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.518951 amazon-ssm-agent[2098]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.519152 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 processing appconfig overrides Nov 12 17:42:21.522473 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.522473 amazon-ssm-agent[2098]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.522473 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 processing appconfig overrides Nov 12 17:42:21.522743 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO Proxy environment variables: Nov 12 17:42:21.529451 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.529451 amazon-ssm-agent[2098]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Nov 12 17:42:21.529628 amazon-ssm-agent[2098]: 2024/11/12 17:42:21 processing appconfig overrides Nov 12 17:42:21.622199 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO https_proxy: Nov 12 17:42:21.723887 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO http_proxy: Nov 12 17:42:21.820927 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO no_proxy: Nov 12 17:42:21.924293 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO Checking if agent identity type OnPrem can be assumed Nov 12 17:42:22.020208 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO Checking if agent identity type EC2 can be assumed Nov 12 17:42:22.120068 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO Agent will take identity from EC2 Nov 12 17:42:22.219551 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 17:42:22.319314 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 17:42:22.419906 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] using named pipe channel for IPC Nov 12 17:42:22.519209 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Nov 12 17:42:22.558765 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 17:42:22.575049 (kubelet)[2121]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 12 17:42:22.619573 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Nov 12 17:42:22.719975 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] Starting Core Agent Nov 12 17:42:22.820367 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [amazon-ssm-agent] registrar detected. Attempting registration Nov 12 17:42:22.920652 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [Registrar] Starting registrar module Nov 12 17:42:23.020966 amazon-ssm-agent[2098]: 2024-11-12 17:42:21 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Nov 12 17:42:23.201914 amazon-ssm-agent[2098]: 2024-11-12 17:42:23 INFO [EC2Identity] EC2 registration was successful. Nov 12 17:42:23.245682 amazon-ssm-agent[2098]: 2024-11-12 17:42:23 INFO [CredentialRefresher] credentialRefresher has started Nov 12 17:42:23.245682 amazon-ssm-agent[2098]: 2024-11-12 17:42:23 INFO [CredentialRefresher] Starting credentials refresher loop Nov 12 17:42:23.245842 amazon-ssm-agent[2098]: 2024-11-12 17:42:23 INFO EC2RoleProvider Successfully connected with instance profile role credentials Nov 12 17:42:23.276204 sshd_keygen[1929]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 12 17:42:23.303355 amazon-ssm-agent[2098]: 2024-11-12 17:42:23 INFO [CredentialRefresher] Next credential rotation will be in 31.358325906833333 minutes Nov 12 17:42:23.331518 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 12 17:42:23.351841 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 12 17:42:23.364692 systemd[1]: Started sshd@0-172.31.18.135:22-139.178.89.65:56368.service - OpenSSH per-connection server daemon (139.178.89.65:56368). Nov 12 17:42:23.387870 systemd[1]: issuegen.service: Deactivated successfully. Nov 12 17:42:23.391338 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 12 17:42:23.410918 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 12 17:42:23.458937 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 12 17:42:23.473131 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 12 17:42:23.482797 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 12 17:42:23.485563 systemd[1]: Reached target getty.target - Login Prompts. Nov 12 17:42:23.487914 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 12 17:42:23.490415 systemd[1]: Startup finished in 1.303s (kernel) + 9.467s (initrd) + 9.269s (userspace) = 20.039s. Nov 12 17:42:23.608093 sshd[2136]: Accepted publickey for core from 139.178.89.65 port 56368 ssh2: RSA SHA256:1a90X/uDC0ILhfMiA2YbbwEMVTxtJewsfiol0dYezPk Nov 12 17:42:23.610738 sshd[2136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 17:42:23.629754 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 12 17:42:23.637956 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 12 17:42:23.646568 systemd-logind[1904]: New session 1 of user core. Nov 12 17:42:23.693684 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 12 17:42:23.706309 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 12 17:42:23.728739 (systemd)[2153]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 12 17:42:23.769439 kubelet[2121]: E1112 17:42:23.769188 2121 run.go:74] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 12 17:42:23.777721 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 12 17:42:23.778324 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 12 17:42:23.779319 systemd[1]: kubelet.service: Consumed 1.368s CPU time. Nov 12 17:42:23.972811 systemd[2153]: Queued start job for default target default.target. Nov 12 17:42:23.985497 systemd[2153]: Created slice app.slice - User Application Slice. Nov 12 17:42:23.985558 systemd[2153]: Reached target paths.target - Paths. Nov 12 17:42:23.985590 systemd[2153]: Reached target timers.target - Timers. Nov 12 17:42:23.988550 systemd[2153]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 12 17:42:24.017458 systemd[2153]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 12 17:42:24.017740 systemd[2153]: Reached target sockets.target - Sockets. Nov 12 17:42:24.017778 systemd[2153]: Reached target basic.target - Basic System. Nov 12 17:42:24.017869 systemd[2153]: Reached target default.target - Main User Target. Nov 12 17:42:24.017942 systemd[2153]: Startup finished in 274ms. Nov 12 17:42:24.018174 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 12 17:42:24.032192 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 12 17:42:24.058610 ntpd[1900]: Listen normally on 7 eth0 [fe80::423:9bff:fefa:9393%2]:123 Nov 12 17:42:24.059914 ntpd[1900]: 12 Nov 17:42:24 ntpd[1900]: Listen normally on 7 eth0 [fe80::423:9bff:fefa:9393%2]:123 Nov 12 17:42:24.198129 systemd[1]: Started sshd@1-172.31.18.135:22-139.178.89.65:56370.service - OpenSSH per-connection server daemon (139.178.89.65:56370). Nov 12 17:42:24.286018 amazon-ssm-agent[2098]: 2024-11-12 17:42:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Nov 12 17:42:24.386368 amazon-ssm-agent[2098]: 2024-11-12 17:42:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2169) started Nov 12 17:42:24.394435 sshd[2166]: Accepted publickey for core from 139.178.89.65 port 56370 ssh2: RSA SHA256:1a90X/uDC0ILhfMiA2YbbwEMVTxtJewsfiol0dYezPk Nov 12 17:42:24.396697 sshd[2166]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 17:42:24.409526 systemd-logind[1904]: New session 2 of user core. Nov 12 17:42:24.416712 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 12 17:42:24.487176 amazon-ssm-agent[2098]: 2024-11-12 17:42:24 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Nov 12 17:42:24.551177 sshd[2166]: pam_unix(sshd:session): session closed for user core Nov 12 17:42:24.559936 systemd[1]: sshd@1-172.31.18.135:22-139.178.89.65:56370.service: Deactivated successfully. Nov 12 17:42:24.564507 systemd[1]: session-2.scope: Deactivated successfully. Nov 12 17:42:24.573692 systemd-logind[1904]: Session 2 logged out. Waiting for processes to exit. Nov 12 17:42:24.590827 systemd[1]: Started sshd@2-172.31.18.135:22-139.178.89.65:56372.service - OpenSSH per-connection server daemon (139.178.89.65:56372). Nov 12 17:42:24.593150 systemd-logind[1904]: Removed session 2. Nov 12 17:42:24.772866 sshd[2183]: Accepted publickey for core from 139.178.89.65 port 56372 ssh2: RSA SHA256:1a90X/uDC0ILhfMiA2YbbwEMVTxtJewsfiol0dYezPk Nov 12 17:42:24.775682 sshd[2183]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 17:42:24.784908 systemd-logind[1904]: New session 3 of user core. Nov 12 17:42:24.793693 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 12 17:42:24.915735 sshd[2183]: pam_unix(sshd:session): session closed for user core Nov 12 17:42:24.921949 systemd-logind[1904]: Session 3 logged out. Waiting for processes to exit. Nov 12 17:42:24.923523 systemd[1]: sshd@2-172.31.18.135:22-139.178.89.65:56372.service: Deactivated successfully. Nov 12 17:42:24.926558 systemd[1]: session-3.scope: Deactivated successfully. Nov 12 17:42:24.930040 systemd-logind[1904]: Removed session 3. Nov 12 17:42:24.957043 systemd[1]: Started sshd@3-172.31.18.135:22-139.178.89.65:56374.service - OpenSSH per-connection server daemon (139.178.89.65:56374). Nov 12 17:42:25.135643 sshd[2190]: Accepted publickey for core from 139.178.89.65 port 56374 ssh2: RSA SHA256:1a90X/uDC0ILhfMiA2YbbwEMVTxtJewsfiol0dYezPk Nov 12 17:42:25.138914 sshd[2190]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 17:42:25.148096 systemd-logind[1904]: New session 4 of user core. Nov 12 17:42:25.158884 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 12 17:42:25.293438 sshd[2190]: pam_unix(sshd:session): session closed for user core Nov 12 17:42:25.300116 systemd[1]: sshd@3-172.31.18.135:22-139.178.89.65:56374.service: Deactivated successfully. Nov 12 17:42:25.304055 systemd[1]: session-4.scope: Deactivated successfully. Nov 12 17:42:25.307040 systemd-logind[1904]: Session 4 logged out. Waiting for processes to exit. Nov 12 17:42:25.309178 systemd-logind[1904]: Removed session 4. Nov 12 17:42:25.330892 systemd[1]: Started sshd@4-172.31.18.135:22-139.178.89.65:56380.service - OpenSSH per-connection server daemon (139.178.89.65:56380). Nov 12 17:42:25.513531 sshd[2197]: Accepted publickey for core from 139.178.89.65 port 56380 ssh2: RSA SHA256:1a90X/uDC0ILhfMiA2YbbwEMVTxtJewsfiol0dYezPk Nov 12 17:42:25.516061 sshd[2197]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 12 17:42:25.525647 systemd-logind[1904]: New session 5 of user core. Nov 12 17:42:25.535675 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 12 17:42:25.673171 sudo[2200]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 12 17:42:25.673900 sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 12 17:42:26.748785 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 17:42:26.749153 systemd[1]: kubelet.service: Consumed 1.368s CPU time. Nov 12 17:42:26.759911 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 17:42:26.804686 systemd[1]: Reloading requested from client PID 2238 ('systemctl') (unit session-5.scope)... Nov 12 17:42:26.804713 systemd[1]: Reloading... Nov 12 17:42:27.034456 zram_generator::config[2278]: No configuration found. Nov 12 17:42:26.831042 systemd-resolved[1837]: Clock change detected. Flushing caches. Nov 12 17:42:26.883639 systemd-journald[1489]: Time jumped backwards, rotating. Nov 12 17:42:27.071381 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 17:42:27.264100 systemd[1]: Reloading finished in 685 ms. Nov 12 17:42:27.366274 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 12 17:42:27.366487 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 12 17:42:27.368947 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 17:42:27.380356 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 12 17:42:27.924160 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 12 17:42:27.944350 (kubelet)[2342]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 12 17:42:28.030533 kubelet[2342]: 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 17:42:28.030533 kubelet[2342]: 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 17:42:28.030533 kubelet[2342]: 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 17:42:28.031088 kubelet[2342]: I1112 17:42:28.030619 2342 server.go:204] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 12 17:42:29.191198 kubelet[2342]: I1112 17:42:29.191120 2342 server.go:487] "Kubelet version" kubeletVersion="v1.29.2" Nov 12 17:42:29.191198 kubelet[2342]: I1112 17:42:29.191181 2342 server.go:489] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 12 17:42:29.191842 kubelet[2342]: I1112 17:42:29.191566 2342 server.go:919] "Client rotation is on, will bootstrap in background" Nov 12 17:42:29.224223 kubelet[2342]: I1112 17:42:29.224142 2342 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 12 17:42:29.242103 kubelet[2342]: I1112 17:42:29.242051 2342 server.go:745] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 12 17:42:29.242798 kubelet[2342]: I1112 17:42:29.242601 2342 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 12 17:42:29.243033 kubelet[2342]: I1112 17:42:29.242967 2342 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Nov 12 17:42:29.243184 kubelet[2342]: I1112 17:42:29.243042 2342 topology_manager.go:138] "Creating topology manager with none policy" Nov 12 17:42:29.243184 kubelet[2342]: I1112 17:42:29.243069 2342 container_manager_linux.go:301] "Creating device plugin manager" Nov 12 17:42:29.243301 kubelet[2342]: I1112 17:42:29.243274 2342 state_mem.go:36] "Initialized new in-memory state store" Nov 12 17:42:29.247269 kubelet[2342]: I1112 17:42:29.247185 2342 kubelet.go:396] "Attempting to sync node with API server" Nov 12 17:42:29.247269 kubelet[2342]: I1112 17:42:29.247248 2342 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 12 17:42:29.247819 kubelet[2342]: I1112 17:42:29.247294 2342 kubelet.go:312] "Adding apiserver pod source" Nov 12 17:42:29.247819 kubelet[2342]: I1112 17:42:29.247343 2342 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 12 17:42:29.250238 kubelet[2342]: E1112 17:42:29.250194 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:29.250815 kubelet[2342]: E1112 17:42:29.250532 2342 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:29.252057 kubelet[2342]: I1112 17:42:29.251889 2342 kuberuntime_manager.go:258] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Nov 12 17:42:29.254807 kubelet[2342]: I1112 17:42:29.252985 2342 kubelet.go:809] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 12 17:42:29.254807 kubelet[2342]: W1112 17:42:29.253123 2342 probe.go:268] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 12 17:42:29.254807 kubelet[2342]: I1112 17:42:29.254318 2342 server.go:1256] "Started kubelet" Nov 12 17:42:29.255584 kubelet[2342]: I1112 17:42:29.255533 2342 server.go:162] "Starting to listen" address="0.0.0.0" port=10250 Nov 12 17:42:29.257260 kubelet[2342]: I1112 17:42:29.257203 2342 server.go:461] "Adding debug handlers to kubelet server" Nov 12 17:42:29.259518 kubelet[2342]: I1112 17:42:29.259425 2342 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 12 17:42:29.260105 kubelet[2342]: I1112 17:42:29.260072 2342 server.go:233] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 12 17:42:29.262852 kubelet[2342]: I1112 17:42:29.262775 2342 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 12 17:42:29.278834 kubelet[2342]: I1112 17:42:29.278783 2342 volume_manager.go:291] "Starting Kubelet Volume Manager" Nov 12 17:42:29.279216 kubelet[2342]: E1112 17:42:29.279154 2342 event.go:346] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{172.31.18.135.180749752690c468 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:172.31.18.135,UID:172.31.18.135,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:172.31.18.135,},FirstTimestamp:2024-11-12 17:42:29.254268008 +0000 UTC m=+1.301806688,LastTimestamp:2024-11-12 17:42:29.254268008 +0000 UTC m=+1.301806688,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:172.31.18.135,}" Nov 12 17:42:29.280523 kubelet[2342]: W1112 17:42:29.280444 2342 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Nov 12 17:42:29.280523 kubelet[2342]: E1112 17:42:29.280530 2342 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: 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 Nov 12 17:42:29.280997 kubelet[2342]: W1112 17:42:29.280871 2342 reflector.go:539] vendor/k8s.io/client-go/informers/factory.go:159: failed to list *v1.Node: nodes "172.31.18.135" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 12 17:42:29.280997 kubelet[2342]: E1112 17:42:29.280928 2342 reflector.go:147] vendor/k8s.io/client-go/informers/factory.go:159: Failed to watch *v1.Node: failed to list *v1.Node: nodes "172.31.18.135" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 12 17:42:29.281944 kubelet[2342]: I1112 17:42:29.281889 2342 desired_state_of_world_populator.go:151] "Desired state populator starts to run" Nov 12 17:42:29.282302 kubelet[2342]: I1112 17:42:29.282267 2342 reconciler_new.go:29] "Reconciler: start to sync state" Nov 12 17:42:29.285047 kubelet[2342]: I1112 17:42:29.285011 2342 factory.go:221] Registration of the systemd container factory successfully Nov 12 17:42:29.285417 kubelet[2342]: I1112 17:42:29.285385 2342 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 17:42:29.289407 kubelet[2342]: E1112 17:42:29.289362 2342 kubelet.go:1462] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 12 17:42:29.295836 kubelet[2342]: I1112 17:42:29.295119 2342 factory.go:221] Registration of the containerd container factory successfully Nov 12 17:42:29.327419 kubelet[2342]: I1112 17:42:29.327376 2342 cpu_manager.go:214] "Starting CPU manager" policy="none" Nov 12 17:42:29.327601 kubelet[2342]: I1112 17:42:29.327579 2342 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Nov 12 17:42:29.327711 kubelet[2342]: I1112 17:42:29.327692 2342 state_mem.go:36] "Initialized new in-memory state store" Nov 12 17:42:29.332224 kubelet[2342]: I1112 17:42:29.332183 2342 policy_none.go:49] "None policy: Start" Nov 12 17:42:29.332925 kubelet[2342]: E1112 17:42:29.332872 2342 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"172.31.18.135\" not found" node="172.31.18.135" Nov 12 17:42:29.334580 kubelet[2342]: I1112 17:42:29.334540 2342 memory_manager.go:170] "Starting memorymanager" policy="None" Nov 12 17:42:29.334880 kubelet[2342]: I1112 17:42:29.334850 2342 state_mem.go:35] "Initializing new in-memory state store" Nov 12 17:42:29.354490 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 12 17:42:29.375161 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 12 17:42:29.384915 kubelet[2342]: I1112 17:42:29.383898 2342 kubelet_node_status.go:73] "Attempting to register node" node="172.31.18.135" Nov 12 17:42:29.388116 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 12 17:42:29.397411 kubelet[2342]: I1112 17:42:29.397343 2342 kubelet_node_status.go:76] "Successfully registered node" node="172.31.18.135" Nov 12 17:42:29.400686 kubelet[2342]: I1112 17:42:29.400607 2342 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 12 17:42:29.401840 kubelet[2342]: I1112 17:42:29.401456 2342 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 12 17:42:29.413670 kubelet[2342]: I1112 17:42:29.413591 2342 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 12 17:42:29.417312 kubelet[2342]: E1112 17:42:29.416831 2342 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"172.31.18.135\" not found" Nov 12 17:42:29.419078 kubelet[2342]: I1112 17:42:29.418999 2342 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 12 17:42:29.419078 kubelet[2342]: I1112 17:42:29.419073 2342 status_manager.go:217] "Starting to sync pod status with apiserver" Nov 12 17:42:29.419256 kubelet[2342]: I1112 17:42:29.419112 2342 kubelet.go:2329] "Starting kubelet main sync loop" Nov 12 17:42:29.419256 kubelet[2342]: E1112 17:42:29.419219 2342 kubelet.go:2353] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 12 17:42:29.432316 kubelet[2342]: E1112 17:42:29.432260 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:29.532679 kubelet[2342]: E1112 17:42:29.532531 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:29.633309 kubelet[2342]: E1112 17:42:29.633216 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:29.734249 kubelet[2342]: E1112 17:42:29.734170 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:29.835099 kubelet[2342]: E1112 17:42:29.834939 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:29.935172 kubelet[2342]: E1112 17:42:29.935118 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:30.036015 kubelet[2342]: E1112 17:42:30.035951 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:30.136828 kubelet[2342]: E1112 17:42:30.136631 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:30.201514 kubelet[2342]: I1112 17:42:30.201437 2342 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 12 17:42:30.202109 kubelet[2342]: W1112 17:42:30.201720 2342 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.CSIDriver ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Nov 12 17:42:30.202109 kubelet[2342]: W1112 17:42:30.201812 2342 reflector.go:462] vendor/k8s.io/client-go/informers/factory.go:159: watch of *v1.RuntimeClass ended with: very short watch: vendor/k8s.io/client-go/informers/factory.go:159: Unexpected watch close - watch lasted less than a second and no items received Nov 12 17:42:30.236860 kubelet[2342]: E1112 17:42:30.236792 2342 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"172.31.18.135\" not found" Nov 12 17:42:30.249559 kubelet[2342]: I1112 17:42:30.249504 2342 apiserver.go:52] "Watching apiserver" Nov 12 17:42:30.251514 kubelet[2342]: E1112 17:42:30.251268 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:30.254760 kubelet[2342]: I1112 17:42:30.254693 2342 topology_manager.go:215] "Topology Admit Handler" podUID="97bfad83-619b-4d19-8256-6ecb2a08917f" podNamespace="kube-flannel" podName="kube-flannel-ds-kpshf" Nov 12 17:42:30.255641 kubelet[2342]: I1112 17:42:30.254889 2342 topology_manager.go:215] "Topology Admit Handler" podUID="0ae86d06-e9c1-48e9-ae22-67a65cec76d6" podNamespace="kube-system" podName="kube-proxy-pghbn" Nov 12 17:42:30.269983 systemd[1]: Created slice kubepods-burstable-pod97bfad83_619b_4d19_8256_6ecb2a08917f.slice - libcontainer container kubepods-burstable-pod97bfad83_619b_4d19_8256_6ecb2a08917f.slice. Nov 12 17:42:30.282775 kubelet[2342]: I1112 17:42:30.282719 2342 desired_state_of_world_populator.go:159] "Finished populating initial desired state of world" Nov 12 17:42:30.289299 kubelet[2342]: I1112 17:42:30.288948 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t6h7f\" (UniqueName: \"kubernetes.io/projected/0ae86d06-e9c1-48e9-ae22-67a65cec76d6-kube-api-access-t6h7f\") pod \"kube-proxy-pghbn\" (UID: \"0ae86d06-e9c1-48e9-ae22-67a65cec76d6\") " pod="kube-system/kube-proxy-pghbn" Nov 12 17:42:30.289299 kubelet[2342]: I1112 17:42:30.289022 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/97bfad83-619b-4d19-8256-6ecb2a08917f-cni\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.289299 kubelet[2342]: I1112 17:42:30.289117 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/97bfad83-619b-4d19-8256-6ecb2a08917f-flannel-cfg\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.289299 kubelet[2342]: I1112 17:42:30.289198 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/0ae86d06-e9c1-48e9-ae22-67a65cec76d6-kube-proxy\") pod \"kube-proxy-pghbn\" (UID: \"0ae86d06-e9c1-48e9-ae22-67a65cec76d6\") " pod="kube-system/kube-proxy-pghbn" Nov 12 17:42:30.289299 kubelet[2342]: I1112 17:42:30.289257 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8ddw4\" (UniqueName: \"kubernetes.io/projected/97bfad83-619b-4d19-8256-6ecb2a08917f-kube-api-access-8ddw4\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.289598 kubelet[2342]: I1112 17:42:30.289351 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/0ae86d06-e9c1-48e9-ae22-67a65cec76d6-xtables-lock\") pod \"kube-proxy-pghbn\" (UID: \"0ae86d06-e9c1-48e9-ae22-67a65cec76d6\") " pod="kube-system/kube-proxy-pghbn" Nov 12 17:42:30.289598 kubelet[2342]: I1112 17:42:30.289411 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/0ae86d06-e9c1-48e9-ae22-67a65cec76d6-lib-modules\") pod \"kube-proxy-pghbn\" (UID: \"0ae86d06-e9c1-48e9-ae22-67a65cec76d6\") " pod="kube-system/kube-proxy-pghbn" Nov 12 17:42:30.289598 kubelet[2342]: I1112 17:42:30.289456 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/97bfad83-619b-4d19-8256-6ecb2a08917f-run\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.289598 kubelet[2342]: I1112 17:42:30.289523 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/97bfad83-619b-4d19-8256-6ecb2a08917f-cni-plugin\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.289598 kubelet[2342]: I1112 17:42:30.289569 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/97bfad83-619b-4d19-8256-6ecb2a08917f-xtables-lock\") pod \"kube-flannel-ds-kpshf\" (UID: \"97bfad83-619b-4d19-8256-6ecb2a08917f\") " pod="kube-flannel/kube-flannel-ds-kpshf" Nov 12 17:42:30.293237 systemd[1]: Created slice kubepods-besteffort-pod0ae86d06_e9c1_48e9_ae22_67a65cec76d6.slice - libcontainer container kubepods-besteffort-pod0ae86d06_e9c1_48e9_ae22_67a65cec76d6.slice. Nov 12 17:42:30.338563 kubelet[2342]: I1112 17:42:30.338507 2342 kuberuntime_manager.go:1529] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 12 17:42:30.339310 containerd[1928]: time="2024-11-12T17:42:30.339263325Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 12 17:42:30.341005 kubelet[2342]: I1112 17:42:30.340188 2342 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 12 17:42:30.463152 sudo[2200]: pam_unix(sudo:session): session closed for user root Nov 12 17:42:30.487202 sshd[2197]: pam_unix(sshd:session): session closed for user core Nov 12 17:42:30.494284 systemd[1]: sshd@4-172.31.18.135:22-139.178.89.65:56380.service: Deactivated successfully. Nov 12 17:42:30.498622 systemd[1]: session-5.scope: Deactivated successfully. Nov 12 17:42:30.500605 systemd-logind[1904]: Session 5 logged out. Waiting for processes to exit. Nov 12 17:42:30.504475 systemd-logind[1904]: Removed session 5. Nov 12 17:42:30.587679 containerd[1928]: time="2024-11-12T17:42:30.587192338Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kpshf,Uid:97bfad83-619b-4d19-8256-6ecb2a08917f,Namespace:kube-flannel,Attempt:0,}" Nov 12 17:42:30.603459 containerd[1928]: time="2024-11-12T17:42:30.603283414Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pghbn,Uid:0ae86d06-e9c1-48e9-ae22-67a65cec76d6,Namespace:kube-system,Attempt:0,}" Nov 12 17:42:31.241814 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount305773158.mount: Deactivated successfully. Nov 12 17:42:31.252352 kubelet[2342]: E1112 17:42:31.252272 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:31.264261 containerd[1928]: time="2024-11-12T17:42:31.264183082Z" 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 17:42:31.267217 containerd[1928]: time="2024-11-12T17:42:31.266848234Z" 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 17:42:31.268469 containerd[1928]: time="2024-11-12T17:42:31.268354810Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 12 17:42:31.270246 containerd[1928]: time="2024-11-12T17:42:31.270176086Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269173" Nov 12 17:42:31.272916 containerd[1928]: time="2024-11-12T17:42:31.272710258Z" 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 17:42:31.283780 containerd[1928]: time="2024-11-12T17:42:31.281479966Z" 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 17:42:31.283780 containerd[1928]: time="2024-11-12T17:42:31.283276498Z" 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 695.941084ms" Nov 12 17:42:31.285608 containerd[1928]: time="2024-11-12T17:42:31.285538114Z" 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 682.1395ms" Nov 12 17:42:31.561849 containerd[1928]: time="2024-11-12T17:42:31.561537587Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 17:42:31.561849 containerd[1928]: time="2024-11-12T17:42:31.561800783Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 17:42:31.562855 containerd[1928]: time="2024-11-12T17:42:31.561953003Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:31.562855 containerd[1928]: time="2024-11-12T17:42:31.562172027Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:31.564802 containerd[1928]: time="2024-11-12T17:42:31.563549399Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 17:42:31.564802 containerd[1928]: time="2024-11-12T17:42:31.563698619Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 17:42:31.564802 containerd[1928]: time="2024-11-12T17:42:31.563727923Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:31.564802 containerd[1928]: time="2024-11-12T17:42:31.563892911Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:31.777340 systemd[1]: Started cri-containerd-aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab.scope - libcontainer container aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab. Nov 12 17:42:31.784017 systemd[1]: Started cri-containerd-cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db.scope - libcontainer container cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db. Nov 12 17:42:31.845690 containerd[1928]: time="2024-11-12T17:42:31.844861836Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pghbn,Uid:0ae86d06-e9c1-48e9-ae22-67a65cec76d6,Namespace:kube-system,Attempt:0,} returns sandbox id \"cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db\"" Nov 12 17:42:31.859551 containerd[1928]: time="2024-11-12T17:42:31.859472844Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.10\"" Nov 12 17:42:31.872359 containerd[1928]: time="2024-11-12T17:42:31.872291173Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kpshf,Uid:97bfad83-619b-4d19-8256-6ecb2a08917f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\"" Nov 12 17:42:32.252939 kubelet[2342]: E1112 17:42:32.252510 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:32.414049 systemd[1]: run-containerd-runc-k8s.io-cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db-runc.7MZcVV.mount: Deactivated successfully. Nov 12 17:42:33.252897 kubelet[2342]: E1112 17:42:33.252684 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:33.320948 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3819233427.mount: Deactivated successfully. Nov 12 17:42:33.829356 containerd[1928]: time="2024-11-12T17:42:33.829265930Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.29.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:33.832318 containerd[1928]: time="2024-11-12T17:42:33.832227734Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.29.10: active requests=0, bytes read=25272229" Nov 12 17:42:33.834976 containerd[1928]: time="2024-11-12T17:42:33.834880226Z" level=info msg="ImageCreate event name:\"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:33.840634 containerd[1928]: time="2024-11-12T17:42:33.840031850Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:33.841683 containerd[1928]: time="2024-11-12T17:42:33.841598762Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.29.10\" with image id \"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\", repo tag \"registry.k8s.io/kube-proxy:v1.29.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\", size \"25271248\" in 1.981877254s" Nov 12 17:42:33.841683 containerd[1928]: time="2024-11-12T17:42:33.841672730Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.29.10\" returns image reference \"sha256:4e66440765478454d48b169d648b000501e24066c0bad7c378bd9e8506bb919f\"" Nov 12 17:42:33.843186 containerd[1928]: time="2024-11-12T17:42:33.843126782Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 12 17:42:33.846080 containerd[1928]: time="2024-11-12T17:42:33.846013874Z" level=info msg="CreateContainer within sandbox \"cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 12 17:42:33.881616 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1335393350.mount: Deactivated successfully. Nov 12 17:42:33.890072 containerd[1928]: time="2024-11-12T17:42:33.889987899Z" level=info msg="CreateContainer within sandbox \"cab32c8fbe1acdc928a7ae676973f6747968caa61f31d9cff1916f2effe770db\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"88f593d1c10d5c3c0e931c49aa9d3a8a8f461c117dbe17fb0848f535f4d1ec7d\"" Nov 12 17:42:33.891944 containerd[1928]: time="2024-11-12T17:42:33.891874035Z" level=info msg="StartContainer for \"88f593d1c10d5c3c0e931c49aa9d3a8a8f461c117dbe17fb0848f535f4d1ec7d\"" Nov 12 17:42:33.955523 systemd[1]: Started cri-containerd-88f593d1c10d5c3c0e931c49aa9d3a8a8f461c117dbe17fb0848f535f4d1ec7d.scope - libcontainer container 88f593d1c10d5c3c0e931c49aa9d3a8a8f461c117dbe17fb0848f535f4d1ec7d. Nov 12 17:42:34.018571 containerd[1928]: time="2024-11-12T17:42:34.018482171Z" level=info msg="StartContainer for \"88f593d1c10d5c3c0e931c49aa9d3a8a8f461c117dbe17fb0848f535f4d1ec7d\" returns successfully" Nov 12 17:42:34.253905 kubelet[2342]: E1112 17:42:34.253760 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:35.254539 kubelet[2342]: E1112 17:42:35.254467 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:35.781012 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3238480637.mount: Deactivated successfully. Nov 12 17:42:35.857218 containerd[1928]: time="2024-11-12T17:42:35.857128936Z" 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 17:42:35.859188 containerd[1928]: time="2024-11-12T17:42:35.859095364Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 12 17:42:35.861433 containerd[1928]: time="2024-11-12T17:42:35.861349732Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:35.867596 containerd[1928]: time="2024-11-12T17:42:35.867513532Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:35.869174 containerd[1928]: time="2024-11-12T17:42:35.869123368Z" 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 2.025767458s" Nov 12 17:42:35.869549 containerd[1928]: time="2024-11-12T17:42:35.869283820Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 12 17:42:35.872374 containerd[1928]: time="2024-11-12T17:42:35.872315680Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 12 17:42:35.897819 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount652767405.mount: Deactivated successfully. Nov 12 17:42:35.901580 containerd[1928]: time="2024-11-12T17:42:35.901441745Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63\"" Nov 12 17:42:35.902934 containerd[1928]: time="2024-11-12T17:42:35.902394365Z" level=info msg="StartContainer for \"d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63\"" Nov 12 17:42:35.950056 systemd[1]: Started cri-containerd-d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63.scope - libcontainer container d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63. Nov 12 17:42:35.995306 containerd[1928]: time="2024-11-12T17:42:35.995232989Z" level=info msg="StartContainer for \"d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63\" returns successfully" Nov 12 17:42:35.996796 systemd[1]: cri-containerd-d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63.scope: Deactivated successfully. Nov 12 17:42:36.255076 kubelet[2342]: E1112 17:42:36.254978 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:36.495455 kubelet[2342]: I1112 17:42:36.495305 2342 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-system/kube-proxy-pghbn" podStartSLOduration=5.510648842 podStartE2EDuration="7.495181612s" podCreationTimestamp="2024-11-12 17:42:29 +0000 UTC" firstStartedPulling="2024-11-12 17:42:31.857703132 +0000 UTC m=+3.905241788" lastFinishedPulling="2024-11-12 17:42:33.842235842 +0000 UTC m=+5.889774558" observedRunningTime="2024-11-12 17:42:34.475885921 +0000 UTC m=+6.523424649" watchObservedRunningTime="2024-11-12 17:42:36.495181612 +0000 UTC m=+8.542720280" Nov 12 17:42:36.548077 containerd[1928]: time="2024-11-12T17:42:36.547868092Z" level=info msg="shim disconnected" id=d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63 namespace=k8s.io Nov 12 17:42:36.548077 containerd[1928]: time="2024-11-12T17:42:36.547943968Z" level=warning msg="cleaning up after shim disconnected" id=d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63 namespace=k8s.io Nov 12 17:42:36.548077 containerd[1928]: time="2024-11-12T17:42:36.547968316Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 17:42:36.618225 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d1f21afa83c68213e26cba486d130bfcd794985cad6a5666c9622583e6b96d63-rootfs.mount: Deactivated successfully. Nov 12 17:42:37.255544 kubelet[2342]: E1112 17:42:37.255470 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:37.469157 containerd[1928]: time="2024-11-12T17:42:37.469019956Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 12 17:42:38.256678 kubelet[2342]: E1112 17:42:38.256604 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:39.258801 kubelet[2342]: E1112 17:42:39.258498 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:39.564639 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1269660733.mount: Deactivated successfully. Nov 12 17:42:40.261437 kubelet[2342]: E1112 17:42:40.261377 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:40.700629 containerd[1928]: time="2024-11-12T17:42:40.700551608Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:40.702663 containerd[1928]: time="2024-11-12T17:42:40.702590888Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 12 17:42:40.704250 containerd[1928]: time="2024-11-12T17:42:40.704159420Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:40.710457 containerd[1928]: time="2024-11-12T17:42:40.710357612Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:40.713819 containerd[1928]: time="2024-11-12T17:42:40.712521236Z" 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.243421012s" Nov 12 17:42:40.713819 containerd[1928]: time="2024-11-12T17:42:40.712579796Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 12 17:42:40.716229 containerd[1928]: time="2024-11-12T17:42:40.716157644Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 12 17:42:40.741569 containerd[1928]: time="2024-11-12T17:42:40.741401913Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2\"" Nov 12 17:42:40.742942 containerd[1928]: time="2024-11-12T17:42:40.742201857Z" level=info msg="StartContainer for \"df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2\"" Nov 12 17:42:40.796107 systemd[1]: Started cri-containerd-df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2.scope - libcontainer container df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2. Nov 12 17:42:40.848590 systemd[1]: cri-containerd-df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2.scope: Deactivated successfully. Nov 12 17:42:40.852901 containerd[1928]: time="2024-11-12T17:42:40.852704457Z" level=info msg="StartContainer for \"df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2\" returns successfully" Nov 12 17:42:40.889974 kubelet[2342]: I1112 17:42:40.889712 2342 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Nov 12 17:42:41.262877 kubelet[2342]: E1112 17:42:41.262795 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:41.278547 containerd[1928]: time="2024-11-12T17:42:41.278459563Z" level=info msg="shim disconnected" id=df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2 namespace=k8s.io Nov 12 17:42:41.278547 containerd[1928]: time="2024-11-12T17:42:41.278536423Z" level=warning msg="cleaning up after shim disconnected" id=df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2 namespace=k8s.io Nov 12 17:42:41.278804 containerd[1928]: time="2024-11-12T17:42:41.278558287Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 12 17:42:41.484913 containerd[1928]: time="2024-11-12T17:42:41.484489376Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 12 17:42:41.518148 containerd[1928]: time="2024-11-12T17:42:41.517983404Z" level=info msg="CreateContainer within sandbox \"aab79d04e044b4fd6ec830d3a6e9551566aa937de8b33c83d91e790c3145d6ab\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e4dbbafd898813ab974f1b17bb58e064a53b651cdd237a861e493406236f9d5d\"" Nov 12 17:42:41.519527 containerd[1928]: time="2024-11-12T17:42:41.519430076Z" level=info msg="StartContainer for \"e4dbbafd898813ab974f1b17bb58e064a53b651cdd237a861e493406236f9d5d\"" Nov 12 17:42:41.559093 systemd[1]: Started cri-containerd-e4dbbafd898813ab974f1b17bb58e064a53b651cdd237a861e493406236f9d5d.scope - libcontainer container e4dbbafd898813ab974f1b17bb58e064a53b651cdd237a861e493406236f9d5d. Nov 12 17:42:41.607782 containerd[1928]: time="2024-11-12T17:42:41.607672341Z" level=info msg="StartContainer for \"e4dbbafd898813ab974f1b17bb58e064a53b651cdd237a861e493406236f9d5d\" returns successfully" Nov 12 17:42:41.730792 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-df23444f723cdc35e40d408f067ddd970863db2002be7dce6687120624669ba2-rootfs.mount: Deactivated successfully. Nov 12 17:42:42.263778 kubelet[2342]: E1112 17:42:42.263693 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:42.512850 kubelet[2342]: I1112 17:42:42.512790 2342 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-kpshf" podStartSLOduration=4.674944538 podStartE2EDuration="13.512689365s" podCreationTimestamp="2024-11-12 17:42:29 +0000 UTC" firstStartedPulling="2024-11-12 17:42:31.875137129 +0000 UTC m=+3.922675785" lastFinishedPulling="2024-11-12 17:42:40.712881944 +0000 UTC m=+12.760420612" observedRunningTime="2024-11-12 17:42:42.512653389 +0000 UTC m=+14.560192057" watchObservedRunningTime="2024-11-12 17:42:42.512689365 +0000 UTC m=+14.560228033" Nov 12 17:42:42.745992 kubelet[2342]: I1112 17:42:42.745460 2342 topology_manager.go:215] "Topology Admit Handler" podUID="c2bd88a9-0f86-497c-802f-e1f88304ca64" podNamespace="default" podName="nginx-deployment-6d5f899847-mngzf" Nov 12 17:42:42.750581 (udev-worker)[2830]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:42:42.763482 systemd[1]: Created slice kubepods-besteffort-podc2bd88a9_0f86_497c_802f_e1f88304ca64.slice - libcontainer container kubepods-besteffort-podc2bd88a9_0f86_497c_802f_e1f88304ca64.slice. Nov 12 17:42:42.770009 kubelet[2342]: I1112 17:42:42.767688 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7rq8c\" (UniqueName: \"kubernetes.io/projected/c2bd88a9-0f86-497c-802f-e1f88304ca64-kube-api-access-7rq8c\") pod \"nginx-deployment-6d5f899847-mngzf\" (UID: \"c2bd88a9-0f86-497c-802f-e1f88304ca64\") " pod="default/nginx-deployment-6d5f899847-mngzf" Nov 12 17:42:42.784176 systemd-networkd[1835]: flannel.1: Link UP Nov 12 17:42:42.785593 systemd-networkd[1835]: flannel.1: Gained carrier Nov 12 17:42:43.074815 containerd[1928]: time="2024-11-12T17:42:43.074715428Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mngzf,Uid:c2bd88a9-0f86-497c-802f-e1f88304ca64,Namespace:default,Attempt:0,}" Nov 12 17:42:43.114251 systemd-networkd[1835]: cni0: Link UP Nov 12 17:42:43.114270 systemd-networkd[1835]: cni0: Gained carrier Nov 12 17:42:43.114932 systemd-networkd[1835]: cni0: Lost carrier Nov 12 17:42:43.130879 (udev-worker)[2928]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:42:43.134446 systemd-networkd[1835]: vethfc5f144c: Link UP Nov 12 17:42:43.136836 kernel: cni0: port 1(vethfc5f144c) entered blocking state Nov 12 17:42:43.136965 kernel: cni0: port 1(vethfc5f144c) entered disabled state Nov 12 17:42:43.140892 kernel: vethfc5f144c: entered allmulticast mode Nov 12 17:42:43.141018 kernel: vethfc5f144c: entered promiscuous mode Nov 12 17:42:43.142221 kernel: cni0: port 1(vethfc5f144c) entered blocking state Nov 12 17:42:43.142273 kernel: cni0: port 1(vethfc5f144c) entered forwarding state Nov 12 17:42:43.144697 kernel: cni0: port 1(vethfc5f144c) entered disabled state Nov 12 17:42:43.163067 kernel: cni0: port 1(vethfc5f144c) entered blocking state Nov 12 17:42:43.163165 kernel: cni0: port 1(vethfc5f144c) entered forwarding state Nov 12 17:42:43.163236 systemd-networkd[1835]: vethfc5f144c: Gained carrier Nov 12 17:42:43.164557 systemd-networkd[1835]: cni0: Gained carrier Nov 12 17:42:43.168860 containerd[1928]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Nov 12 17:42:43.168860 containerd[1928]: delegateAdd: netconf sent to delegate plugin: Nov 12 17:42:43.199733 containerd[1928]: {"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-12T17:42:43.199101957Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 17:42:43.199733 containerd[1928]: time="2024-11-12T17:42:43.199233969Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 17:42:43.199733 containerd[1928]: time="2024-11-12T17:42:43.199273701Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:43.200651 containerd[1928]: time="2024-11-12T17:42:43.200357961Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:43.244231 systemd[1]: Started cri-containerd-6de83943535e9834028bf0cf4290aaa6abe5b691160db55fa5241e6210d9cf82.scope - libcontainer container 6de83943535e9834028bf0cf4290aaa6abe5b691160db55fa5241e6210d9cf82. Nov 12 17:42:43.264947 kubelet[2342]: E1112 17:42:43.264887 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:43.311540 containerd[1928]: time="2024-11-12T17:42:43.311417409Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-6d5f899847-mngzf,Uid:c2bd88a9-0f86-497c-802f-e1f88304ca64,Namespace:default,Attempt:0,} returns sandbox id \"6de83943535e9834028bf0cf4290aaa6abe5b691160db55fa5241e6210d9cf82\"" Nov 12 17:42:43.315156 containerd[1928]: time="2024-11-12T17:42:43.314811741Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 17:42:44.215276 systemd-networkd[1835]: flannel.1: Gained IPv6LL Nov 12 17:42:44.266059 kubelet[2342]: E1112 17:42:44.265984 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:44.791165 systemd-networkd[1835]: vethfc5f144c: Gained IPv6LL Nov 12 17:42:45.176242 systemd-networkd[1835]: cni0: Gained IPv6LL Nov 12 17:42:45.267060 kubelet[2342]: E1112 17:42:45.266954 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:46.267257 kubelet[2342]: E1112 17:42:46.267172 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:47.270005 kubelet[2342]: E1112 17:42:47.269893 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:47.331148 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1357421893.mount: Deactivated successfully. Nov 12 17:42:47.831011 ntpd[1900]: Listen normally on 8 flannel.1 192.168.1.0:123 Nov 12 17:42:47.831124 ntpd[1900]: Listen normally on 9 cni0 192.168.1.1:123 Nov 12 17:42:47.831604 ntpd[1900]: 12 Nov 17:42:47 ntpd[1900]: Listen normally on 8 flannel.1 192.168.1.0:123 Nov 12 17:42:47.831604 ntpd[1900]: 12 Nov 17:42:47 ntpd[1900]: Listen normally on 9 cni0 192.168.1.1:123 Nov 12 17:42:47.831604 ntpd[1900]: 12 Nov 17:42:47 ntpd[1900]: Listen normally on 10 flannel.1 [fe80::3882:aff:febe:cee6%3]:123 Nov 12 17:42:47.831604 ntpd[1900]: 12 Nov 17:42:47 ntpd[1900]: Listen normally on 11 cni0 [fe80::5065:24ff:fe14:d1ea%4]:123 Nov 12 17:42:47.831604 ntpd[1900]: 12 Nov 17:42:47 ntpd[1900]: Listen normally on 12 vethfc5f144c [fe80::b4ae:37ff:fe8a:ad2a%5]:123 Nov 12 17:42:47.831220 ntpd[1900]: Listen normally on 10 flannel.1 [fe80::3882:aff:febe:cee6%3]:123 Nov 12 17:42:47.831299 ntpd[1900]: Listen normally on 11 cni0 [fe80::5065:24ff:fe14:d1ea%4]:123 Nov 12 17:42:47.831372 ntpd[1900]: Listen normally on 12 vethfc5f144c [fe80::b4ae:37ff:fe8a:ad2a%5]:123 Nov 12 17:42:48.270714 kubelet[2342]: E1112 17:42:48.270253 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:49.021974 containerd[1928]: time="2024-11-12T17:42:49.021839402Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:49.024508 containerd[1928]: time="2024-11-12T17:42:49.024391442Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67683180" Nov 12 17:42:49.026494 containerd[1928]: time="2024-11-12T17:42:49.026339318Z" level=info msg="ImageCreate event name:\"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:49.032977 containerd[1928]: time="2024-11-12T17:42:49.032870990Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:42:49.036405 containerd[1928]: time="2024-11-12T17:42:49.034875026Z" 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 5.719991033s" Nov 12 17:42:49.036405 containerd[1928]: time="2024-11-12T17:42:49.034964258Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 17:42:49.038326 containerd[1928]: time="2024-11-12T17:42:49.038239742Z" level=info msg="CreateContainer within sandbox \"6de83943535e9834028bf0cf4290aaa6abe5b691160db55fa5241e6210d9cf82\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 12 17:42:49.069208 containerd[1928]: time="2024-11-12T17:42:49.069093962Z" level=info msg="CreateContainer within sandbox \"6de83943535e9834028bf0cf4290aaa6abe5b691160db55fa5241e6210d9cf82\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b\"" Nov 12 17:42:49.070066 containerd[1928]: time="2024-11-12T17:42:49.069999566Z" level=info msg="StartContainer for \"9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b\"" Nov 12 17:42:49.120779 systemd[1]: run-containerd-runc-k8s.io-9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b-runc.Azihj9.mount: Deactivated successfully. Nov 12 17:42:49.138102 systemd[1]: Started cri-containerd-9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b.scope - libcontainer container 9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b. Nov 12 17:42:49.236193 containerd[1928]: time="2024-11-12T17:42:49.236106375Z" level=info msg="StartContainer for \"9fb4c8b5757f019528e0eaa7654c822b4c41172cdcf5ffc855f20c08ca73347b\" returns successfully" Nov 12 17:42:49.248447 kubelet[2342]: E1112 17:42:49.248384 2342 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:49.270935 kubelet[2342]: E1112 17:42:49.270845 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:49.526619 kubelet[2342]: I1112 17:42:49.526561 2342 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nginx-deployment-6d5f899847-mngzf" podStartSLOduration=1.805423043 podStartE2EDuration="7.52650616s" podCreationTimestamp="2024-11-12 17:42:42 +0000 UTC" firstStartedPulling="2024-11-12 17:42:43.314358933 +0000 UTC m=+15.361897589" lastFinishedPulling="2024-11-12 17:42:49.03544205 +0000 UTC m=+21.082980706" observedRunningTime="2024-11-12 17:42:49.526307548 +0000 UTC m=+21.573846216" watchObservedRunningTime="2024-11-12 17:42:49.52650616 +0000 UTC m=+21.574044816" Nov 12 17:42:50.271666 kubelet[2342]: E1112 17:42:50.271597 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:50.873277 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 12 17:42:51.272668 kubelet[2342]: E1112 17:42:51.272503 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:52.272757 kubelet[2342]: E1112 17:42:52.272680 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:53.273455 kubelet[2342]: E1112 17:42:53.273379 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:54.273634 kubelet[2342]: E1112 17:42:54.273559 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:55.274637 kubelet[2342]: E1112 17:42:55.274563 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:56.275937 kubelet[2342]: E1112 17:42:56.275829 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:57.276415 kubelet[2342]: E1112 17:42:57.276324 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:57.755989 kubelet[2342]: I1112 17:42:57.755114 2342 topology_manager.go:215] "Topology Admit Handler" podUID="9340e7e9-65b1-4490-be8d-38b14029658b" podNamespace="default" podName="nfs-server-provisioner-0" Nov 12 17:42:57.766326 systemd[1]: Created slice kubepods-besteffort-pod9340e7e9_65b1_4490_be8d_38b14029658b.slice - libcontainer container kubepods-besteffort-pod9340e7e9_65b1_4490_be8d_38b14029658b.slice. Nov 12 17:42:57.768664 kubelet[2342]: I1112 17:42:57.768383 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9340e7e9-65b1-4490-be8d-38b14029658b-data\") pod \"nfs-server-provisioner-0\" (UID: \"9340e7e9-65b1-4490-be8d-38b14029658b\") " pod="default/nfs-server-provisioner-0" Nov 12 17:42:57.768664 kubelet[2342]: I1112 17:42:57.768464 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zdfrb\" (UniqueName: \"kubernetes.io/projected/9340e7e9-65b1-4490-be8d-38b14029658b-kube-api-access-zdfrb\") pod \"nfs-server-provisioner-0\" (UID: \"9340e7e9-65b1-4490-be8d-38b14029658b\") " pod="default/nfs-server-provisioner-0" Nov 12 17:42:58.073771 containerd[1928]: time="2024-11-12T17:42:58.073043879Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9340e7e9-65b1-4490-be8d-38b14029658b,Namespace:default,Attempt:0,}" Nov 12 17:42:58.121510 (udev-worker)[3116]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:42:58.123275 systemd-networkd[1835]: veth19ab3684: Link UP Nov 12 17:42:58.126498 kernel: cni0: port 2(veth19ab3684) entered blocking state Nov 12 17:42:58.126583 kernel: cni0: port 2(veth19ab3684) entered disabled state Nov 12 17:42:58.129821 kernel: veth19ab3684: entered allmulticast mode Nov 12 17:42:58.130047 kernel: veth19ab3684: entered promiscuous mode Nov 12 17:42:58.148127 kernel: cni0: port 2(veth19ab3684) entered blocking state Nov 12 17:42:58.148298 kernel: cni0: port 2(veth19ab3684) entered forwarding state Nov 12 17:42:58.150392 systemd-networkd[1835]: veth19ab3684: Gained carrier Nov 12 17:42:58.156294 containerd[1928]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Nov 12 17:42:58.156294 containerd[1928]: delegateAdd: netconf sent to delegate plugin: Nov 12 17:42:58.195224 containerd[1928]: {"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-12T17:42:58.193871915Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 17:42:58.195224 containerd[1928]: time="2024-11-12T17:42:58.193976303Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 17:42:58.195224 containerd[1928]: time="2024-11-12T17:42:58.194012975Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:58.195224 containerd[1928]: time="2024-11-12T17:42:58.194158595Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:42:58.228619 systemd[1]: run-containerd-runc-k8s.io-aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada-runc.DHr6ou.mount: Deactivated successfully. Nov 12 17:42:58.242086 systemd[1]: Started cri-containerd-aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada.scope - libcontainer container aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada. Nov 12 17:42:58.277324 kubelet[2342]: E1112 17:42:58.277262 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:58.302229 containerd[1928]: time="2024-11-12T17:42:58.302055996Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9340e7e9-65b1-4490-be8d-38b14029658b,Namespace:default,Attempt:0,} returns sandbox id \"aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada\"" Nov 12 17:42:58.307023 containerd[1928]: time="2024-11-12T17:42:58.306891660Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 12 17:42:59.278858 kubelet[2342]: E1112 17:42:59.277812 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:42:59.383110 systemd-networkd[1835]: veth19ab3684: Gained IPv6LL Nov 12 17:43:00.278985 kubelet[2342]: E1112 17:43:00.278878 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:01.279984 kubelet[2342]: E1112 17:43:01.279916 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:01.831149 ntpd[1900]: Listen normally on 13 veth19ab3684 [fe80::b06a:1dff:fe96:4540%6]:123 Nov 12 17:43:01.831653 ntpd[1900]: 12 Nov 17:43:01 ntpd[1900]: Listen normally on 13 veth19ab3684 [fe80::b06a:1dff:fe96:4540%6]:123 Nov 12 17:43:02.281098 kubelet[2342]: E1112 17:43:02.280843 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:03.283013 kubelet[2342]: E1112 17:43:03.282885 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:03.331638 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4265227898.mount: Deactivated successfully. Nov 12 17:43:04.284058 kubelet[2342]: E1112 17:43:04.284014 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:05.224037 update_engine[1905]: I20241112 17:43:05.223328 1905 update_attempter.cc:509] Updating boot flags... Nov 12 17:43:05.285537 kubelet[2342]: E1112 17:43:05.285105 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:05.359945 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3252) Nov 12 17:43:05.788131 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3257) Nov 12 17:43:06.236813 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (3257) Nov 12 17:43:06.285344 kubelet[2342]: E1112 17:43:06.285236 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:07.285429 kubelet[2342]: E1112 17:43:07.285381 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:07.510500 containerd[1928]: time="2024-11-12T17:43:07.510413290Z" 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 17:43:07.516824 containerd[1928]: time="2024-11-12T17:43:07.516722770Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373623" Nov 12 17:43:07.528467 containerd[1928]: time="2024-11-12T17:43:07.528307966Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:43:07.549867 containerd[1928]: time="2024-11-12T17:43:07.549554566Z" 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 17:43:07.552948 containerd[1928]: time="2024-11-12T17:43:07.552183982Z" 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 9.245208046s" Nov 12 17:43:07.552948 containerd[1928]: time="2024-11-12T17:43:07.552295570Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 12 17:43:07.556941 containerd[1928]: time="2024-11-12T17:43:07.556856734Z" level=info msg="CreateContainer within sandbox \"aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 12 17:43:07.622724 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3232706338.mount: Deactivated successfully. Nov 12 17:43:07.663100 containerd[1928]: time="2024-11-12T17:43:07.662979946Z" level=info msg="CreateContainer within sandbox \"aeae941bdc6614beddad5988579b01c4be9f2ba263aadb32a7407e5233402ada\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d\"" Nov 12 17:43:07.664419 containerd[1928]: time="2024-11-12T17:43:07.664096534Z" level=info msg="StartContainer for \"98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d\"" Nov 12 17:43:07.734295 systemd[1]: run-containerd-runc-k8s.io-98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d-runc.wv6pyp.mount: Deactivated successfully. Nov 12 17:43:07.750141 systemd[1]: Started cri-containerd-98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d.scope - libcontainer container 98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d. Nov 12 17:43:07.822369 containerd[1928]: time="2024-11-12T17:43:07.821889083Z" level=info msg="StartContainer for \"98cfd791b0d944903d94e3a20c47f9681a5b12c26f01d518f7b05237b1430c1d\" returns successfully" Nov 12 17:43:08.287340 kubelet[2342]: E1112 17:43:08.287148 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:08.606913 kubelet[2342]: I1112 17:43:08.606836 2342 pod_startup_latency_tracker.go:102] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.359804941 podStartE2EDuration="11.606773867s" podCreationTimestamp="2024-11-12 17:42:57 +0000 UTC" firstStartedPulling="2024-11-12 17:42:58.305691108 +0000 UTC m=+30.353229764" lastFinishedPulling="2024-11-12 17:43:07.552660034 +0000 UTC m=+39.600198690" observedRunningTime="2024-11-12 17:43:08.606301571 +0000 UTC m=+40.653840251" watchObservedRunningTime="2024-11-12 17:43:08.606773867 +0000 UTC m=+40.654312547" Nov 12 17:43:09.247842 kubelet[2342]: E1112 17:43:09.247690 2342 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:09.287705 kubelet[2342]: E1112 17:43:09.287633 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:10.288766 kubelet[2342]: E1112 17:43:10.288689 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:11.289711 kubelet[2342]: E1112 17:43:11.289644 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:12.290224 kubelet[2342]: E1112 17:43:12.290125 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:13.291247 kubelet[2342]: E1112 17:43:13.291172 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:14.292352 kubelet[2342]: E1112 17:43:14.292280 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:15.293064 kubelet[2342]: E1112 17:43:15.292988 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:16.293814 kubelet[2342]: E1112 17:43:16.293726 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:17.294143 kubelet[2342]: E1112 17:43:17.294063 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:17.551412 kubelet[2342]: I1112 17:43:17.550967 2342 topology_manager.go:215] "Topology Admit Handler" podUID="0455679c-c993-4450-a8d1-d4e4c6d88125" podNamespace="default" podName="test-pod-1" Nov 12 17:43:17.561631 systemd[1]: Created slice kubepods-besteffort-pod0455679c_c993_4450_a8d1_d4e4c6d88125.slice - libcontainer container kubepods-besteffort-pod0455679c_c993_4450_a8d1_d4e4c6d88125.slice. Nov 12 17:43:17.604183 kubelet[2342]: I1112 17:43:17.604115 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4df20f35-7aba-4c4f-9afa-1e587da3730f\" (UniqueName: \"kubernetes.io/nfs/0455679c-c993-4450-a8d1-d4e4c6d88125-pvc-4df20f35-7aba-4c4f-9afa-1e587da3730f\") pod \"test-pod-1\" (UID: \"0455679c-c993-4450-a8d1-d4e4c6d88125\") " pod="default/test-pod-1" Nov 12 17:43:17.604351 kubelet[2342]: I1112 17:43:17.604204 2342 reconciler_common.go:258] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2gnk7\" (UniqueName: \"kubernetes.io/projected/0455679c-c993-4450-a8d1-d4e4c6d88125-kube-api-access-2gnk7\") pod \"test-pod-1\" (UID: \"0455679c-c993-4450-a8d1-d4e4c6d88125\") " pod="default/test-pod-1" Nov 12 17:43:17.744041 kernel: FS-Cache: Loaded Nov 12 17:43:17.788252 kernel: RPC: Registered named UNIX socket transport module. Nov 12 17:43:17.788346 kernel: RPC: Registered udp transport module. Nov 12 17:43:17.788415 kernel: RPC: Registered tcp transport module. Nov 12 17:43:17.789248 kernel: RPC: Registered tcp-with-tls transport module. Nov 12 17:43:17.790199 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 12 17:43:18.116151 kernel: NFS: Registering the id_resolver key type Nov 12 17:43:18.116250 kernel: Key type id_resolver registered Nov 12 17:43:18.116314 kernel: Key type id_legacy registered Nov 12 17:43:18.157427 nfsidmap[3663]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'us-west-2.compute.internal' Nov 12 17:43:18.164194 nfsidmap[3664]: 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 17:43:18.294641 kubelet[2342]: E1112 17:43:18.294575 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:18.468263 containerd[1928]: time="2024-11-12T17:43:18.468062684Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0455679c-c993-4450-a8d1-d4e4c6d88125,Namespace:default,Attempt:0,}" Nov 12 17:43:18.513276 (udev-worker)[3649]: Network interface NamePolicy= disabled on kernel command line. Nov 12 17:43:18.517884 kernel: cni0: port 3(vethe9d494f6) entered blocking state Nov 12 17:43:18.517945 kernel: cni0: port 3(vethe9d494f6) entered disabled state Nov 12 17:43:18.514593 systemd-networkd[1835]: vethe9d494f6: Link UP Nov 12 17:43:18.520602 kernel: vethe9d494f6: entered allmulticast mode Nov 12 17:43:18.522322 kernel: vethe9d494f6: entered promiscuous mode Nov 12 17:43:18.539364 kernel: cni0: port 3(vethe9d494f6) entered blocking state Nov 12 17:43:18.539574 kernel: cni0: port 3(vethe9d494f6) entered forwarding state Nov 12 17:43:18.539533 systemd-networkd[1835]: vethe9d494f6: Gained carrier Nov 12 17:43:18.543382 containerd[1928]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000948e8), "name":"cbr0", "type":"bridge"} Nov 12 17:43:18.543382 containerd[1928]: delegateAdd: netconf sent to delegate plugin: Nov 12 17:43:18.580583 containerd[1928]: {"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-12T17:43:18.580145853Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 12 17:43:18.580583 containerd[1928]: time="2024-11-12T17:43:18.580311537Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 12 17:43:18.580583 containerd[1928]: time="2024-11-12T17:43:18.580360341Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:43:18.581447 containerd[1928]: time="2024-11-12T17:43:18.581334621Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 12 17:43:18.614465 systemd[1]: Started cri-containerd-7b933dd4c419d01352e8d35dd1e0e407569f702b31bff76977f823f4a6f57a0f.scope - libcontainer container 7b933dd4c419d01352e8d35dd1e0e407569f702b31bff76977f823f4a6f57a0f. Nov 12 17:43:18.679829 containerd[1928]: time="2024-11-12T17:43:18.679439445Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0455679c-c993-4450-a8d1-d4e4c6d88125,Namespace:default,Attempt:0,} returns sandbox id \"7b933dd4c419d01352e8d35dd1e0e407569f702b31bff76977f823f4a6f57a0f\"" Nov 12 17:43:18.682880 containerd[1928]: time="2024-11-12T17:43:18.682831569Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 12 17:43:18.990153 containerd[1928]: time="2024-11-12T17:43:18.990065771Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 12 17:43:18.992225 containerd[1928]: time="2024-11-12T17:43:18.992118071Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 12 17:43:18.998530 containerd[1928]: time="2024-11-12T17:43:18.998369195Z" 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 315.190754ms" Nov 12 17:43:18.998530 containerd[1928]: time="2024-11-12T17:43:18.998439119Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7c0eba5979da7ca268ea9f656e46d98fc6cf2500e3e74da36ab1466b1f261396\"" Nov 12 17:43:19.001815 containerd[1928]: time="2024-11-12T17:43:19.001563871Z" level=info msg="CreateContainer within sandbox \"7b933dd4c419d01352e8d35dd1e0e407569f702b31bff76977f823f4a6f57a0f\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 12 17:43:19.034856 containerd[1928]: time="2024-11-12T17:43:19.034628071Z" level=info msg="CreateContainer within sandbox \"7b933dd4c419d01352e8d35dd1e0e407569f702b31bff76977f823f4a6f57a0f\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"acd6333794e4a179fd288d90adabb74505bbf9603dad6490282362f787a59352\"" Nov 12 17:43:19.036345 containerd[1928]: time="2024-11-12T17:43:19.036270655Z" level=info msg="StartContainer for \"acd6333794e4a179fd288d90adabb74505bbf9603dad6490282362f787a59352\"" Nov 12 17:43:19.095104 systemd[1]: Started cri-containerd-acd6333794e4a179fd288d90adabb74505bbf9603dad6490282362f787a59352.scope - libcontainer container acd6333794e4a179fd288d90adabb74505bbf9603dad6490282362f787a59352. Nov 12 17:43:19.148281 containerd[1928]: time="2024-11-12T17:43:19.148182979Z" level=info msg="StartContainer for \"acd6333794e4a179fd288d90adabb74505bbf9603dad6490282362f787a59352\" returns successfully" Nov 12 17:43:19.294977 kubelet[2342]: E1112 17:43:19.294798 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:19.927207 systemd-networkd[1835]: vethe9d494f6: Gained IPv6LL Nov 12 17:43:20.295487 kubelet[2342]: E1112 17:43:20.295285 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:21.295544 kubelet[2342]: E1112 17:43:21.295466 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:22.296594 kubelet[2342]: E1112 17:43:22.296528 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:22.831287 ntpd[1900]: Listen normally on 14 vethe9d494f6 [fe80::5032:29ff:fe3c:9598%7]:123 Nov 12 17:43:22.831790 ntpd[1900]: 12 Nov 17:43:22 ntpd[1900]: Listen normally on 14 vethe9d494f6 [fe80::5032:29ff:fe3c:9598%7]:123 Nov 12 17:43:23.297026 kubelet[2342]: E1112 17:43:23.296808 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:24.297846 kubelet[2342]: E1112 17:43:24.297706 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:25.298283 kubelet[2342]: E1112 17:43:25.298210 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:26.299081 kubelet[2342]: E1112 17:43:26.299004 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:27.299280 kubelet[2342]: E1112 17:43:27.299207 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:28.299425 kubelet[2342]: E1112 17:43:28.299346 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:29.248285 kubelet[2342]: E1112 17:43:29.248209 2342 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:29.300456 kubelet[2342]: E1112 17:43:29.300379 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:30.300639 kubelet[2342]: E1112 17:43:30.300542 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:31.300987 kubelet[2342]: E1112 17:43:31.300895 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:32.301603 kubelet[2342]: E1112 17:43:32.301517 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:33.301922 kubelet[2342]: E1112 17:43:33.301861 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:34.302575 kubelet[2342]: E1112 17:43:34.302503 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:35.303133 kubelet[2342]: E1112 17:43:35.303062 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:36.303926 kubelet[2342]: E1112 17:43:36.303863 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:37.304879 kubelet[2342]: E1112 17:43:37.304797 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:38.305299 kubelet[2342]: E1112 17:43:38.305215 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:39.306223 kubelet[2342]: E1112 17:43:39.306115 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:40.307199 kubelet[2342]: E1112 17:43:40.307130 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:40.705156 kubelet[2342]: E1112 17:43:40.704965 2342 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": context deadline exceeded" Nov 12 17:43:40.941947 kubelet[2342]: E1112 17:43:40.941839 2342 kubelet_node_status.go:544] "Error updating node status, will retry" err="failed to patch status \"{\\\"status\\\":{\\\"$setElementOrder/conditions\\\":[{\\\"type\\\":\\\"NetworkUnavailable\\\"},{\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"type\\\":\\\"DiskPressure\\\"},{\\\"type\\\":\\\"PIDPressure\\\"},{\\\"type\\\":\\\"Ready\\\"}],\\\"conditions\\\":[{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T17:43:30Z\\\",\\\"type\\\":\\\"MemoryPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T17:43:30Z\\\",\\\"type\\\":\\\"DiskPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T17:43:30Z\\\",\\\"type\\\":\\\"PIDPressure\\\"},{\\\"lastHeartbeatTime\\\":\\\"2024-11-12T17:43:30Z\\\",\\\"type\\\":\\\"Ready\\\"}],\\\"images\\\":[{\\\"names\\\":[\\\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\\\",\\\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\\\"],\\\"sizeBytes\\\":87371201},{\\\"names\\\":[\\\"ghcr.io/flatcar/nginx@sha256:d950b46ff6d7c8700af095c8169d9522a829656b00621f5b3f5156e87adbcab5\\\",\\\"ghcr.io/flatcar/nginx:latest\\\"],\\\"sizeBytes\\\":67683058},{\\\"names\\\":[\\\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\\\",\\\"docker.io/flannel/flannel:v0.22.0\\\"],\\\"sizeBytes\\\":26863435},{\\\"names\\\":[\\\"registry.k8s.io/kube-proxy@sha256:3c5ceb7942f21793d4cb5880bc0ed7ca7d7f93318fc3f0830816593b86aa19d8\\\",\\\"registry.k8s.io/kube-proxy:v1.29.10\\\"],\\\"sizeBytes\\\":25271248},{\\\"names\\\":[\\\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\\\",\\\"docker.io/flannel/flannel-cni-plugin:v1.1.2\\\"],\\\"sizeBytes\\\":3662650},{\\\"names\\\":[\\\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\\\",\\\"registry.k8s.io/pause:3.8\\\"],\\\"sizeBytes\\\":268403}]}}\" for node \"172.31.18.135\": Patch \"https://172.31.29.20:6443/api/v1/nodes/172.31.18.135/status?timeout=10s\": context deadline exceeded" Nov 12 17:43:41.308265 kubelet[2342]: E1112 17:43:41.308151 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:42.309367 kubelet[2342]: E1112 17:43:42.309292 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:43.309656 kubelet[2342]: E1112 17:43:43.309591 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:44.310145 kubelet[2342]: E1112 17:43:44.310080 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:45.310479 kubelet[2342]: E1112 17:43:45.310405 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:46.311374 kubelet[2342]: E1112 17:43:46.311241 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:47.312362 kubelet[2342]: E1112 17:43:47.312246 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:48.312880 kubelet[2342]: E1112 17:43:48.312800 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:49.248289 kubelet[2342]: E1112 17:43:49.248217 2342 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:49.313419 kubelet[2342]: E1112 17:43:49.313347 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:50.313957 kubelet[2342]: E1112 17:43:50.313888 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:50.705952 kubelet[2342]: E1112 17:43:50.705425 2342 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 17:43:50.943257 kubelet[2342]: E1112 17:43:50.943201 2342 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.18.135\": Get \"https://172.31.29.20:6443/api/v1/nodes/172.31.18.135?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 17:43:51.315095 kubelet[2342]: E1112 17:43:51.315025 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:52.315354 kubelet[2342]: E1112 17:43:52.315279 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:53.316150 kubelet[2342]: E1112 17:43:53.316087 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:54.317370 kubelet[2342]: E1112 17:43:54.317301 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:55.318369 kubelet[2342]: E1112 17:43:55.318280 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:56.319401 kubelet[2342]: E1112 17:43:56.319327 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:57.320114 kubelet[2342]: E1112 17:43:57.320036 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:58.320981 kubelet[2342]: E1112 17:43:58.320922 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:43:59.321694 kubelet[2342]: E1112 17:43:59.321620 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:00.321916 kubelet[2342]: E1112 17:44:00.321847 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:00.706915 kubelet[2342]: E1112 17:44:00.706659 2342 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 17:44:00.944419 kubelet[2342]: E1112 17:44:00.944354 2342 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.18.135\": Get \"https://172.31.29.20:6443/api/v1/nodes/172.31.18.135?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 17:44:01.323089 kubelet[2342]: E1112 17:44:01.323016 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:02.323298 kubelet[2342]: E1112 17:44:02.323214 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:03.323770 kubelet[2342]: E1112 17:44:03.323698 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:04.324237 kubelet[2342]: E1112 17:44:04.324177 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:05.325012 kubelet[2342]: E1112 17:44:05.324914 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:06.325627 kubelet[2342]: E1112 17:44:06.325553 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:07.326092 kubelet[2342]: E1112 17:44:07.326029 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:08.326869 kubelet[2342]: E1112 17:44:08.326726 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:09.247894 kubelet[2342]: E1112 17:44:09.247803 2342 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:09.327437 kubelet[2342]: E1112 17:44:09.327345 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:10.327572 kubelet[2342]: E1112 17:44:10.327512 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:10.707603 kubelet[2342]: E1112 17:44:10.707439 2342 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": net/http: request canceled (Client.Timeout exceeded while awaiting headers)" Nov 12 17:44:10.732421 kubelet[2342]: E1112 17:44:10.731721 2342 controller.go:195] "Failed to update lease" err="Put \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": unexpected EOF" Nov 12 17:44:10.732421 kubelet[2342]: I1112 17:44:10.731849 2342 controller.go:115] "failed to update lease using latest lease, fallback to ensure lease" err="failed 5 attempts to update lease" Nov 12 17:44:11.328278 kubelet[2342]: E1112 17:44:11.328177 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:11.737910 kubelet[2342]: E1112 17:44:11.737668 2342 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.18.135\": Get \"https://172.31.29.20:6443/api/v1/nodes/172.31.18.135?timeout=10s\": context deadline exceeded - error from a previous attempt: unexpected EOF" Nov 12 17:44:11.739176 kubelet[2342]: E1112 17:44:11.739093 2342 kubelet_node_status.go:544] "Error updating node status, will retry" err="error getting node \"172.31.18.135\": Get \"https://172.31.29.20:6443/api/v1/nodes/172.31.18.135?timeout=10s\": dial tcp 172.31.29.20:6443: connect: connection refused" Nov 12 17:44:11.739176 kubelet[2342]: E1112 17:44:11.739133 2342 kubelet_node_status.go:531] "Unable to update node status" err="update node status exceeds retry count" Nov 12 17:44:11.749070 kubelet[2342]: E1112 17:44:11.748980 2342 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": dial tcp 172.31.29.20:6443: connect: connection refused - error from a previous attempt: read tcp 172.31.18.135:37810->172.31.29.20:6443: read: connection reset by peer" interval="200ms" Nov 12 17:44:12.328833 kubelet[2342]: E1112 17:44:12.328733 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:13.329490 kubelet[2342]: E1112 17:44:13.329425 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:14.330512 kubelet[2342]: E1112 17:44:14.330433 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:15.331105 kubelet[2342]: E1112 17:44:15.331036 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:16.332353 kubelet[2342]: E1112 17:44:16.332260 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:17.332916 kubelet[2342]: E1112 17:44:17.332822 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:18.333922 kubelet[2342]: E1112 17:44:18.333828 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:19.334135 kubelet[2342]: E1112 17:44:19.334058 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:20.335361 kubelet[2342]: E1112 17:44:20.335276 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:21.336189 kubelet[2342]: E1112 17:44:21.336116 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:21.949901 kubelet[2342]: E1112 17:44:21.949830 2342 controller.go:145] "Failed to ensure lease exists, will retry" err="Get \"https://172.31.29.20:6443/apis/coordination.k8s.io/v1/namespaces/kube-node-lease/leases/172.31.18.135?timeout=10s\": dial tcp 172.31.29.20:6443: i/o timeout" interval="400ms" Nov 12 17:44:22.337291 kubelet[2342]: E1112 17:44:22.337214 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:23.338187 kubelet[2342]: E1112 17:44:23.338114 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:24.338527 kubelet[2342]: E1112 17:44:24.338473 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:25.339134 kubelet[2342]: E1112 17:44:25.339064 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:26.339388 kubelet[2342]: E1112 17:44:26.339298 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:27.340302 kubelet[2342]: E1112 17:44:27.340229 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:28.341152 kubelet[2342]: E1112 17:44:28.341061 2342 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 12 17:44:28.593489 systemd-logind[1904]: Power key pressed short. Nov 12 17:44:28.593517 systemd-logind[1904]: Powering off...