Aug 5 21:53:51.285111 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Aug 5 21:53:51.285164 kernel: Linux version 6.6.43-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT Mon Aug 5 20:37:57 -00 2024 Aug 5 21:53:51.285190 kernel: KASLR disabled due to lack of seed Aug 5 21:53:51.285206 kernel: efi: EFI v2.7 by EDK II Aug 5 21:53:51.285222 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7ac1aa98 MEMRESERVE=0x7852ee18 Aug 5 21:53:51.285238 kernel: ACPI: Early table checksum verification disabled Aug 5 21:53:51.285255 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Aug 5 21:53:51.285271 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Aug 5 21:53:51.285287 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Aug 5 21:53:51.285303 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Aug 5 21:53:51.285324 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Aug 5 21:53:51.285342 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Aug 5 21:53:51.285357 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Aug 5 21:53:51.285374 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Aug 5 21:53:51.285394 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Aug 5 21:53:51.285416 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Aug 5 21:53:51.285435 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Aug 5 21:53:51.285452 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Aug 5 21:53:51.285469 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Aug 5 21:53:51.285485 kernel: printk: bootconsole [uart0] enabled Aug 5 21:53:51.285502 kernel: NUMA: Failed to initialise from firmware Aug 5 21:53:51.285519 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Aug 5 21:53:51.285537 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Aug 5 21:53:51.285554 kernel: Zone ranges: Aug 5 21:53:51.285571 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Aug 5 21:53:51.285587 kernel: DMA32 empty Aug 5 21:53:51.285609 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Aug 5 21:53:51.285626 kernel: Movable zone start for each node Aug 5 21:53:51.287823 kernel: Early memory node ranges Aug 5 21:53:51.287848 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Aug 5 21:53:51.287867 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Aug 5 21:53:51.287884 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Aug 5 21:53:51.287901 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Aug 5 21:53:51.287919 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Aug 5 21:53:51.287935 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Aug 5 21:53:51.287954 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Aug 5 21:53:51.287970 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Aug 5 21:53:51.287987 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Aug 5 21:53:51.288022 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Aug 5 21:53:51.288039 kernel: psci: probing for conduit method from ACPI. Aug 5 21:53:51.288065 kernel: psci: PSCIv1.0 detected in firmware. Aug 5 21:53:51.288082 kernel: psci: Using standard PSCI v0.2 function IDs Aug 5 21:53:51.288102 kernel: psci: Trusted OS migration not required Aug 5 21:53:51.288125 kernel: psci: SMC Calling Convention v1.1 Aug 5 21:53:51.288143 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Aug 5 21:53:51.288162 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Aug 5 21:53:51.288181 kernel: pcpu-alloc: [0] 0 [0] 1 Aug 5 21:53:51.288201 kernel: Detected PIPT I-cache on CPU0 Aug 5 21:53:51.288220 kernel: CPU features: detected: GIC system register CPU interface Aug 5 21:53:51.288238 kernel: CPU features: detected: Spectre-v2 Aug 5 21:53:51.288256 kernel: CPU features: detected: Spectre-v3a Aug 5 21:53:51.288275 kernel: CPU features: detected: Spectre-BHB Aug 5 21:53:51.288294 kernel: CPU features: detected: ARM erratum 1742098 Aug 5 21:53:51.288312 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Aug 5 21:53:51.288336 kernel: alternatives: applying boot alternatives Aug 5 21:53:51.288359 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=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 21:53:51.288381 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 21:53:51.288399 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 5 21:53:51.288419 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 21:53:51.288438 kernel: Fallback order for Node 0: 0 Aug 5 21:53:51.288458 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Aug 5 21:53:51.288476 kernel: Policy zone: Normal Aug 5 21:53:51.288494 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 21:53:51.288513 kernel: software IO TLB: area num 2. Aug 5 21:53:51.288531 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Aug 5 21:53:51.288557 kernel: Memory: 3820536K/4030464K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 209928K reserved, 0K cma-reserved) Aug 5 21:53:51.288575 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Aug 5 21:53:51.288592 kernel: trace event string verifier disabled Aug 5 21:53:51.288610 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 21:53:51.288657 kernel: rcu: RCU event tracing is enabled. Aug 5 21:53:51.288685 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Aug 5 21:53:51.288704 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 21:53:51.288722 kernel: Tracing variant of Tasks RCU enabled. Aug 5 21:53:51.288740 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 21:53:51.288758 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Aug 5 21:53:51.288776 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 5 21:53:51.288803 kernel: GICv3: 96 SPIs implemented Aug 5 21:53:51.288821 kernel: GICv3: 0 Extended SPIs implemented Aug 5 21:53:51.288841 kernel: Root IRQ handler: gic_handle_irq Aug 5 21:53:51.288859 kernel: GICv3: GICv3 features: 16 PPIs Aug 5 21:53:51.288876 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Aug 5 21:53:51.288894 kernel: ITS [mem 0x10080000-0x1009ffff] Aug 5 21:53:51.288913 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000c0000 (indirect, esz 8, psz 64K, shr 1) Aug 5 21:53:51.288931 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000d0000 (flat, esz 8, psz 64K, shr 1) Aug 5 21:53:51.288949 kernel: GICv3: using LPI property table @0x00000004000e0000 Aug 5 21:53:51.288967 kernel: ITS: Using hypervisor restricted LPI range [128] Aug 5 21:53:51.288984 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000f0000 Aug 5 21:53:51.289002 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 21:53:51.289025 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Aug 5 21:53:51.289043 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Aug 5 21:53:51.289062 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Aug 5 21:53:51.289080 kernel: Console: colour dummy device 80x25 Aug 5 21:53:51.289101 kernel: printk: console [tty1] enabled Aug 5 21:53:51.289120 kernel: ACPI: Core revision 20230628 Aug 5 21:53:51.289142 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Aug 5 21:53:51.289161 kernel: pid_max: default: 32768 minimum: 301 Aug 5 21:53:51.289180 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 21:53:51.289199 kernel: SELinux: Initializing. Aug 5 21:53:51.289225 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 21:53:51.289244 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 21:53:51.289263 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 5 21:53:51.289281 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Aug 5 21:53:51.289300 kernel: rcu: Hierarchical SRCU implementation. Aug 5 21:53:51.289320 kernel: rcu: Max phase no-delay instances is 400. Aug 5 21:53:51.289340 kernel: Platform MSI: ITS@0x10080000 domain created Aug 5 21:53:51.289362 kernel: PCI/MSI: ITS@0x10080000 domain created Aug 5 21:53:51.289380 kernel: Remapping and enabling EFI services. Aug 5 21:53:51.289404 kernel: smp: Bringing up secondary CPUs ... Aug 5 21:53:51.289423 kernel: Detected PIPT I-cache on CPU1 Aug 5 21:53:51.289442 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Aug 5 21:53:51.289461 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000400100000 Aug 5 21:53:51.289479 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Aug 5 21:53:51.289497 kernel: smp: Brought up 1 node, 2 CPUs Aug 5 21:53:51.289516 kernel: SMP: Total of 2 processors activated. Aug 5 21:53:51.289535 kernel: CPU features: detected: 32-bit EL0 Support Aug 5 21:53:51.289554 kernel: CPU features: detected: 32-bit EL1 Support Aug 5 21:53:51.289578 kernel: CPU features: detected: CRC32 instructions Aug 5 21:53:51.289596 kernel: CPU: All CPU(s) started at EL1 Aug 5 21:53:51.289679 kernel: alternatives: applying system-wide alternatives Aug 5 21:53:51.289720 kernel: devtmpfs: initialized Aug 5 21:53:51.289740 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 21:53:51.289758 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Aug 5 21:53:51.289777 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 21:53:51.289796 kernel: SMBIOS 3.0.0 present. Aug 5 21:53:51.289815 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Aug 5 21:53:51.289839 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 21:53:51.289858 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 5 21:53:51.289877 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 5 21:53:51.289896 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 5 21:53:51.289915 kernel: audit: initializing netlink subsys (disabled) Aug 5 21:53:51.289934 kernel: audit: type=2000 audit(0.305:1): state=initialized audit_enabled=0 res=1 Aug 5 21:53:51.289953 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 21:53:51.289977 kernel: cpuidle: using governor menu Aug 5 21:53:51.289996 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 5 21:53:51.290015 kernel: ASID allocator initialised with 65536 entries Aug 5 21:53:51.290034 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 21:53:51.290054 kernel: Serial: AMBA PL011 UART driver Aug 5 21:53:51.290073 kernel: Modules: 17600 pages in range for non-PLT usage Aug 5 21:53:51.290093 kernel: Modules: 509120 pages in range for PLT usage Aug 5 21:53:51.290112 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 5 21:53:51.290132 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 5 21:53:51.290156 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 5 21:53:51.290175 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 5 21:53:51.290194 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 21:53:51.290212 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 21:53:51.290231 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 5 21:53:51.290251 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 5 21:53:51.290269 kernel: ACPI: Added _OSI(Module Device) Aug 5 21:53:51.290289 kernel: ACPI: Added _OSI(Processor Device) Aug 5 21:53:51.290307 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 21:53:51.290331 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 21:53:51.290350 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 21:53:51.290369 kernel: ACPI: Interpreter enabled Aug 5 21:53:51.290387 kernel: ACPI: Using GIC for interrupt routing Aug 5 21:53:51.290406 kernel: ACPI: MCFG table detected, 1 entries Aug 5 21:53:51.290425 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Aug 5 21:53:51.290931 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 5 21:53:51.291177 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 5 21:53:51.291419 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 5 21:53:51.291627 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Aug 5 21:53:51.294159 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Aug 5 21:53:51.294189 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Aug 5 21:53:51.294210 kernel: acpiphp: Slot [1] registered Aug 5 21:53:51.294229 kernel: acpiphp: Slot [2] registered Aug 5 21:53:51.294248 kernel: acpiphp: Slot [3] registered Aug 5 21:53:51.294267 kernel: acpiphp: Slot [4] registered Aug 5 21:53:51.294286 kernel: acpiphp: Slot [5] registered Aug 5 21:53:51.294330 kernel: acpiphp: Slot [6] registered Aug 5 21:53:51.294349 kernel: acpiphp: Slot [7] registered Aug 5 21:53:51.294368 kernel: acpiphp: Slot [8] registered Aug 5 21:53:51.294387 kernel: acpiphp: Slot [9] registered Aug 5 21:53:51.294405 kernel: acpiphp: Slot [10] registered Aug 5 21:53:51.294424 kernel: acpiphp: Slot [11] registered Aug 5 21:53:51.294443 kernel: acpiphp: Slot [12] registered Aug 5 21:53:51.294463 kernel: acpiphp: Slot [13] registered Aug 5 21:53:51.294481 kernel: acpiphp: Slot [14] registered Aug 5 21:53:51.294506 kernel: acpiphp: Slot [15] registered Aug 5 21:53:51.294525 kernel: acpiphp: Slot [16] registered Aug 5 21:53:51.294543 kernel: acpiphp: Slot [17] registered Aug 5 21:53:51.294561 kernel: acpiphp: Slot [18] registered Aug 5 21:53:51.294581 kernel: acpiphp: Slot [19] registered Aug 5 21:53:51.294600 kernel: acpiphp: Slot [20] registered Aug 5 21:53:51.294619 kernel: acpiphp: Slot [21] registered Aug 5 21:53:51.294667 kernel: acpiphp: Slot [22] registered Aug 5 21:53:51.294689 kernel: acpiphp: Slot [23] registered Aug 5 21:53:51.294707 kernel: acpiphp: Slot [24] registered Aug 5 21:53:51.294734 kernel: acpiphp: Slot [25] registered Aug 5 21:53:51.294753 kernel: acpiphp: Slot [26] registered Aug 5 21:53:51.294771 kernel: acpiphp: Slot [27] registered Aug 5 21:53:51.294791 kernel: acpiphp: Slot [28] registered Aug 5 21:53:51.294809 kernel: acpiphp: Slot [29] registered Aug 5 21:53:51.294828 kernel: acpiphp: Slot [30] registered Aug 5 21:53:51.294846 kernel: acpiphp: Slot [31] registered Aug 5 21:53:51.294882 kernel: PCI host bridge to bus 0000:00 Aug 5 21:53:51.295126 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Aug 5 21:53:51.295326 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 5 21:53:51.295516 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Aug 5 21:53:51.297988 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Aug 5 21:53:51.298338 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Aug 5 21:53:51.298679 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Aug 5 21:53:51.299610 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Aug 5 21:53:51.300555 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Aug 5 21:53:51.300885 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Aug 5 21:53:51.301103 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Aug 5 21:53:51.301336 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Aug 5 21:53:51.301555 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Aug 5 21:53:51.301846 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Aug 5 21:53:51.302083 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Aug 5 21:53:51.302322 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Aug 5 21:53:51.302533 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Aug 5 21:53:51.304008 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Aug 5 21:53:51.304302 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Aug 5 21:53:51.304517 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Aug 5 21:53:51.304791 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Aug 5 21:53:51.305035 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Aug 5 21:53:51.305245 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 5 21:53:51.305427 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Aug 5 21:53:51.305452 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 5 21:53:51.305472 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 5 21:53:51.305491 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 5 21:53:51.305510 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 5 21:53:51.305529 kernel: iommu: Default domain type: Translated Aug 5 21:53:51.305548 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 5 21:53:51.305577 kernel: efivars: Registered efivars operations Aug 5 21:53:51.305596 kernel: vgaarb: loaded Aug 5 21:53:51.305615 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 5 21:53:51.306702 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 21:53:51.306751 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 21:53:51.306771 kernel: pnp: PnP ACPI init Aug 5 21:53:51.307136 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Aug 5 21:53:51.307170 kernel: pnp: PnP ACPI: found 1 devices Aug 5 21:53:51.307206 kernel: NET: Registered PF_INET protocol family Aug 5 21:53:51.307227 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 5 21:53:51.307248 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 5 21:53:51.307269 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 21:53:51.307288 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 21:53:51.307308 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 5 21:53:51.307329 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 5 21:53:51.307351 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 21:53:51.307369 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 21:53:51.307397 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 21:53:51.307419 kernel: PCI: CLS 0 bytes, default 64 Aug 5 21:53:51.307438 kernel: kvm [1]: HYP mode not available Aug 5 21:53:51.307457 kernel: Initialise system trusted keyrings Aug 5 21:53:51.307478 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 5 21:53:51.307498 kernel: Key type asymmetric registered Aug 5 21:53:51.307519 kernel: Asymmetric key parser 'x509' registered Aug 5 21:53:51.307539 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 5 21:53:51.307558 kernel: io scheduler mq-deadline registered Aug 5 21:53:51.307583 kernel: io scheduler kyber registered Aug 5 21:53:51.307604 kernel: io scheduler bfq registered Aug 5 21:53:51.308221 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Aug 5 21:53:51.308253 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 5 21:53:51.308273 kernel: ACPI: button: Power Button [PWRB] Aug 5 21:53:51.308292 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Aug 5 21:53:51.308311 kernel: ACPI: button: Sleep Button [SLPB] Aug 5 21:53:51.308330 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 21:53:51.308357 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Aug 5 21:53:51.308563 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Aug 5 21:53:51.308589 kernel: printk: console [ttyS0] disabled Aug 5 21:53:51.308609 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Aug 5 21:53:51.308627 kernel: printk: console [ttyS0] enabled Aug 5 21:53:51.308750 kernel: printk: bootconsole [uart0] disabled Aug 5 21:53:51.308769 kernel: thunder_xcv, ver 1.0 Aug 5 21:53:51.308787 kernel: thunder_bgx, ver 1.0 Aug 5 21:53:51.308806 kernel: nicpf, ver 1.0 Aug 5 21:53:51.308824 kernel: nicvf, ver 1.0 Aug 5 21:53:51.309059 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 5 21:53:51.309254 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-08-05T21:53:50 UTC (1722894830) Aug 5 21:53:51.309281 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 5 21:53:51.309300 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Aug 5 21:53:51.309320 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 5 21:53:51.309340 kernel: watchdog: Hard watchdog permanently disabled Aug 5 21:53:51.309358 kernel: NET: Registered PF_INET6 protocol family Aug 5 21:53:51.309377 kernel: Segment Routing with IPv6 Aug 5 21:53:51.309408 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 21:53:51.309428 kernel: NET: Registered PF_PACKET protocol family Aug 5 21:53:51.309448 kernel: Key type dns_resolver registered Aug 5 21:53:51.309469 kernel: registered taskstats version 1 Aug 5 21:53:51.309488 kernel: Loading compiled-in X.509 certificates Aug 5 21:53:51.309511 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: 99cab5c9e2f0f3a5ca972c2df7b3d6ed64d627d4' Aug 5 21:53:51.309533 kernel: Key type .fscrypt registered Aug 5 21:53:51.309552 kernel: Key type fscrypt-provisioning registered Aug 5 21:53:51.309573 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 21:53:51.309602 kernel: ima: Allocated hash algorithm: sha1 Aug 5 21:53:51.309624 kernel: ima: No architecture policies found Aug 5 21:53:51.309725 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 5 21:53:51.309749 kernel: clk: Disabling unused clocks Aug 5 21:53:51.309771 kernel: Freeing unused kernel memory: 39040K Aug 5 21:53:51.309790 kernel: Run /init as init process Aug 5 21:53:51.309810 kernel: with arguments: Aug 5 21:53:51.309834 kernel: /init Aug 5 21:53:51.309853 kernel: with environment: Aug 5 21:53:51.309884 kernel: HOME=/ Aug 5 21:53:51.309905 kernel: TERM=linux Aug 5 21:53:51.309926 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 21:53:51.309958 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) Aug 5 21:53:51.309985 systemd[1]: Detected virtualization amazon. Aug 5 21:53:51.310007 systemd[1]: Detected architecture arm64. Aug 5 21:53:51.310030 systemd[1]: Running in initrd. Aug 5 21:53:51.310051 systemd[1]: No hostname configured, using default hostname. Aug 5 21:53:51.310079 systemd[1]: Hostname set to . Aug 5 21:53:51.310103 systemd[1]: Initializing machine ID from VM UUID. Aug 5 21:53:51.310125 systemd[1]: Queued start job for default target initrd.target. Aug 5 21:53:51.310148 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:53:51.310170 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:53:51.310194 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 21:53:51.310219 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 21:53:51.310250 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 21:53:51.310276 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 21:53:51.310301 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 21:53:51.310324 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 21:53:51.310346 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:53:51.310374 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:53:51.310397 systemd[1]: Reached target paths.target - Path Units. Aug 5 21:53:51.310425 systemd[1]: Reached target slices.target - Slice Units. Aug 5 21:53:51.310447 systemd[1]: Reached target swap.target - Swaps. Aug 5 21:53:51.310468 systemd[1]: Reached target timers.target - Timer Units. Aug 5 21:53:51.310491 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 21:53:51.310513 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 21:53:51.310535 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 21:53:51.310556 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 21:53:51.310577 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:53:51.310598 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 21:53:51.310625 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:53:51.310689 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 21:53:51.310711 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 21:53:51.310732 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 21:53:51.310754 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 21:53:51.310775 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 21:53:51.310795 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 21:53:51.310815 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 21:53:51.310843 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:53:51.310863 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 21:53:51.310904 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:53:51.310925 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 21:53:51.310991 systemd-journald[250]: Collecting audit messages is disabled. Aug 5 21:53:51.311041 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 21:53:51.311063 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 21:53:51.311082 kernel: Bridge firewalling registered Aug 5 21:53:51.311101 systemd-journald[250]: Journal started Aug 5 21:53:51.311143 systemd-journald[250]: Runtime Journal (/run/log/journal/ec27625b65687507c2ea7f529bd410a0) is 8.0M, max 75.3M, 67.3M free. Aug 5 21:53:51.251359 systemd-modules-load[251]: Inserted module 'overlay' Aug 5 21:53:51.321253 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 21:53:51.309936 systemd-modules-load[251]: Inserted module 'br_netfilter' Aug 5 21:53:51.326697 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 21:53:51.329743 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:53:51.341787 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 21:53:51.373100 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:53:51.381952 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 21:53:51.395051 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 21:53:51.425079 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 21:53:51.442788 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:53:51.454857 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:53:51.466822 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:53:51.480254 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 21:53:51.496352 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:53:51.513155 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 21:53:51.540712 dracut-cmdline[286]: dracut-dracut-053 Aug 5 21:53:51.549426 dracut-cmdline[286]: 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=4052403b8e39e55d48e6afcca927358798017aa0d33c868bc3038260a8d9be90 Aug 5 21:53:51.606111 systemd-resolved[288]: Positive Trust Anchors: Aug 5 21:53:51.606149 systemd-resolved[288]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 21:53:51.606212 systemd-resolved[288]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 21:53:51.723683 kernel: SCSI subsystem initialized Aug 5 21:53:51.733672 kernel: Loading iSCSI transport class v2.0-870. Aug 5 21:53:51.746688 kernel: iscsi: registered transport (tcp) Aug 5 21:53:51.771672 kernel: iscsi: registered transport (qla4xxx) Aug 5 21:53:51.771749 kernel: QLogic iSCSI HBA Driver Aug 5 21:53:51.840681 kernel: random: crng init done Aug 5 21:53:51.841137 systemd-resolved[288]: Defaulting to hostname 'linux'. Aug 5 21:53:51.844957 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 21:53:51.849348 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:53:51.878247 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 21:53:51.895277 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 21:53:51.940973 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 21:53:51.942618 kernel: device-mapper: uevent: version 1.0.3 Aug 5 21:53:51.942663 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 21:53:52.011701 kernel: raid6: neonx8 gen() 6754 MB/s Aug 5 21:53:52.028668 kernel: raid6: neonx4 gen() 6572 MB/s Aug 5 21:53:52.045667 kernel: raid6: neonx2 gen() 5460 MB/s Aug 5 21:53:52.062670 kernel: raid6: neonx1 gen() 3966 MB/s Aug 5 21:53:52.079672 kernel: raid6: int64x8 gen() 3817 MB/s Aug 5 21:53:52.096672 kernel: raid6: int64x4 gen() 3714 MB/s Aug 5 21:53:52.113679 kernel: raid6: int64x2 gen() 3594 MB/s Aug 5 21:53:52.131396 kernel: raid6: int64x1 gen() 2753 MB/s Aug 5 21:53:52.131450 kernel: raid6: using algorithm neonx8 gen() 6754 MB/s Aug 5 21:53:52.149355 kernel: raid6: .... xor() 4767 MB/s, rmw enabled Aug 5 21:53:52.149412 kernel: raid6: using neon recovery algorithm Aug 5 21:53:52.157682 kernel: xor: measuring software checksum speed Aug 5 21:53:52.159669 kernel: 8regs : 11034 MB/sec Aug 5 21:53:52.161685 kernel: 32regs : 11923 MB/sec Aug 5 21:53:52.163702 kernel: arm64_neon : 9608 MB/sec Aug 5 21:53:52.163737 kernel: xor: using function: 32regs (11923 MB/sec) Aug 5 21:53:52.253681 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 21:53:52.279927 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 21:53:52.289182 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:53:52.341039 systemd-udevd[470]: Using default interface naming scheme 'v255'. Aug 5 21:53:52.350725 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:53:52.364013 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 21:53:52.403798 dracut-pre-trigger[474]: rd.md=0: removing MD RAID activation Aug 5 21:53:52.470477 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 21:53:52.479049 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 21:53:52.611702 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:53:52.624925 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 21:53:52.691601 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 21:53:52.702104 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 21:53:52.704794 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:53:52.734071 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 21:53:52.756516 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 21:53:52.808240 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 5 21:53:52.808389 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Aug 5 21:53:52.845116 kernel: ena 0000:00:05.0: ENA device version: 0.10 Aug 5 21:53:52.845411 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Aug 5 21:53:52.845683 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:52:d0:ab:4d:89 Aug 5 21:53:52.818736 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 21:53:52.873433 (udev-worker)[537]: Network interface NamePolicy= disabled on kernel command line. Aug 5 21:53:52.898469 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 21:53:52.901087 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:53:52.913415 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:53:52.917980 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 21:53:52.918279 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:53:52.922940 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:53:52.941673 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Aug 5 21:53:52.944744 kernel: nvme nvme0: pci function 0000:00:04.0 Aug 5 21:53:52.944323 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:53:52.956668 kernel: nvme nvme0: 2/0/0 default/read/poll queues Aug 5 21:53:52.963776 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 5 21:53:52.963940 kernel: GPT:9289727 != 16777215 Aug 5 21:53:52.963967 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 5 21:53:52.964814 kernel: GPT:9289727 != 16777215 Aug 5 21:53:52.965681 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 5 21:53:52.965732 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 5 21:53:52.998825 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:53:53.015068 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:53:53.072554 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:53:53.098730 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (519) Aug 5 21:53:53.124877 kernel: BTRFS: device fsid 278882ec-4175-45f0-a12b-7fddc0d6d9a3 devid 1 transid 41 /dev/nvme0n1p3 scanned by (udev-worker) (520) Aug 5 21:53:53.149270 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Aug 5 21:53:53.254526 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Aug 5 21:53:53.287003 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Aug 5 21:53:53.302671 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Aug 5 21:53:53.307949 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Aug 5 21:53:53.328140 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 21:53:53.353320 disk-uuid[660]: Primary Header is updated. Aug 5 21:53:53.353320 disk-uuid[660]: Secondary Entries is updated. Aug 5 21:53:53.353320 disk-uuid[660]: Secondary Header is updated. Aug 5 21:53:53.362715 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 5 21:53:53.371685 kernel: GPT:disk_guids don't match. Aug 5 21:53:53.371765 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 5 21:53:53.371791 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 5 21:53:53.383680 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 5 21:53:54.381763 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Aug 5 21:53:54.384265 disk-uuid[661]: The operation has completed successfully. Aug 5 21:53:54.610776 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 21:53:54.611053 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 21:53:54.660319 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 21:53:54.684840 sh[1005]: Success Aug 5 21:53:54.714909 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 5 21:53:54.823036 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 21:53:54.843274 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 21:53:54.862807 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 21:53:54.890107 kernel: BTRFS info (device dm-0): first mount of filesystem 278882ec-4175-45f0-a12b-7fddc0d6d9a3 Aug 5 21:53:54.890218 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:53:54.890261 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 21:53:54.892976 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 21:53:54.893025 kernel: BTRFS info (device dm-0): using free space tree Aug 5 21:53:54.988705 kernel: BTRFS info (device dm-0): enabling ssd optimizations Aug 5 21:53:55.008459 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 21:53:55.012720 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 21:53:55.031039 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 21:53:55.037930 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 21:53:55.061511 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 21:53:55.061590 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:53:55.062706 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 5 21:53:55.067936 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 5 21:53:55.093095 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 21:53:55.095737 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 21:53:55.123265 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 21:53:55.136162 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 21:53:55.251908 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 21:53:55.276040 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 21:53:55.345959 systemd-networkd[1197]: lo: Link UP Aug 5 21:53:55.345975 systemd-networkd[1197]: lo: Gained carrier Aug 5 21:53:55.351945 systemd-networkd[1197]: Enumeration completed Aug 5 21:53:55.353777 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 21:53:55.357597 systemd[1]: Reached target network.target - Network. Aug 5 21:53:55.358680 systemd-networkd[1197]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:53:55.358688 systemd-networkd[1197]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 21:53:55.366917 systemd-networkd[1197]: eth0: Link UP Aug 5 21:53:55.366926 systemd-networkd[1197]: eth0: Gained carrier Aug 5 21:53:55.366958 systemd-networkd[1197]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:53:55.393778 systemd-networkd[1197]: eth0: DHCPv4 address 172.31.19.36/20, gateway 172.31.16.1 acquired from 172.31.16.1 Aug 5 21:53:55.869076 ignition[1118]: Ignition 2.18.0 Aug 5 21:53:55.869117 ignition[1118]: Stage: fetch-offline Aug 5 21:53:55.872699 ignition[1118]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:55.872750 ignition[1118]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:55.875324 ignition[1118]: Ignition finished successfully Aug 5 21:53:55.882068 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 21:53:55.901668 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Aug 5 21:53:55.930024 ignition[1208]: Ignition 2.18.0 Aug 5 21:53:55.930730 ignition[1208]: Stage: fetch Aug 5 21:53:55.931489 ignition[1208]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:55.931522 ignition[1208]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:55.931765 ignition[1208]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:55.942454 ignition[1208]: PUT result: OK Aug 5 21:53:55.945512 ignition[1208]: parsed url from cmdline: "" Aug 5 21:53:55.945529 ignition[1208]: no config URL provided Aug 5 21:53:55.945548 ignition[1208]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 21:53:55.945580 ignition[1208]: no config at "/usr/lib/ignition/user.ign" Aug 5 21:53:55.945621 ignition[1208]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:55.947402 ignition[1208]: PUT result: OK Aug 5 21:53:55.947603 ignition[1208]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Aug 5 21:53:55.948295 ignition[1208]: GET result: OK Aug 5 21:53:55.966297 unknown[1208]: fetched base config from "system" Aug 5 21:53:55.948383 ignition[1208]: parsing config with SHA512: 0c06926e6f674af379ae31ba9a99ef68d473e13eba91209677d6a80c15057fc023e6b82d01eb13aa384da12776ceede3d17c6b5d35c6f2479dd3b84ba3056f84 Aug 5 21:53:55.966316 unknown[1208]: fetched base config from "system" Aug 5 21:53:55.967737 ignition[1208]: fetch: fetch complete Aug 5 21:53:55.966330 unknown[1208]: fetched user config from "aws" Aug 5 21:53:55.967750 ignition[1208]: fetch: fetch passed Aug 5 21:53:55.967890 ignition[1208]: Ignition finished successfully Aug 5 21:53:55.979366 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Aug 5 21:53:55.999300 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 21:53:56.030543 ignition[1215]: Ignition 2.18.0 Aug 5 21:53:56.030576 ignition[1215]: Stage: kargs Aug 5 21:53:56.031589 ignition[1215]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:56.031616 ignition[1215]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:56.032023 ignition[1215]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:56.035952 ignition[1215]: PUT result: OK Aug 5 21:53:56.044239 ignition[1215]: kargs: kargs passed Aug 5 21:53:56.044812 ignition[1215]: Ignition finished successfully Aug 5 21:53:56.051771 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 21:53:56.064024 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 21:53:56.095112 ignition[1222]: Ignition 2.18.0 Aug 5 21:53:56.095150 ignition[1222]: Stage: disks Aug 5 21:53:56.097994 ignition[1222]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:56.098331 ignition[1222]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:56.098497 ignition[1222]: PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:56.102982 ignition[1222]: PUT result: OK Aug 5 21:53:56.109557 ignition[1222]: disks: disks passed Aug 5 21:53:56.109783 ignition[1222]: Ignition finished successfully Aug 5 21:53:56.113562 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 21:53:56.117945 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 21:53:56.120273 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 21:53:56.122657 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 21:53:56.124501 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 21:53:56.126394 systemd[1]: Reached target basic.target - Basic System. Aug 5 21:53:56.149490 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 21:53:56.200307 systemd-fsck[1231]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 5 21:53:56.208536 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 21:53:56.226065 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 21:53:56.310776 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 44c9fced-dca5-4347-a15f-96911c2e5e61 r/w with ordered data mode. Quota mode: none. Aug 5 21:53:56.311757 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 21:53:56.315484 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 21:53:56.336044 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 21:53:56.360016 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 21:53:56.364508 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 21:53:56.372935 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 21:53:56.382550 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1250) Aug 5 21:53:56.373015 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 21:53:56.388347 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 21:53:56.388554 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:53:56.387960 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 21:53:56.393281 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 5 21:53:56.400043 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 5 21:53:56.401132 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 21:53:56.408921 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 21:53:57.161583 initrd-setup-root[1275]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 21:53:57.181696 initrd-setup-root[1282]: cut: /sysroot/etc/group: No such file or directory Aug 5 21:53:57.191723 initrd-setup-root[1289]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 21:53:57.202246 initrd-setup-root[1296]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 21:53:57.277097 systemd-networkd[1197]: eth0: Gained IPv6LL Aug 5 21:53:57.723180 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 21:53:57.734811 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 21:53:57.738985 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 21:53:57.775435 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 21:53:57.777732 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 21:53:57.804996 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 21:53:57.833308 ignition[1365]: INFO : Ignition 2.18.0 Aug 5 21:53:57.833308 ignition[1365]: INFO : Stage: mount Aug 5 21:53:57.837274 ignition[1365]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:57.837274 ignition[1365]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:57.837274 ignition[1365]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:57.844238 ignition[1365]: INFO : PUT result: OK Aug 5 21:53:57.849310 ignition[1365]: INFO : mount: mount passed Aug 5 21:53:57.851425 ignition[1365]: INFO : Ignition finished successfully Aug 5 21:53:57.857699 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 21:53:57.868867 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 21:53:57.902051 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 21:53:57.920680 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1376) Aug 5 21:53:57.924400 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 47327e03-a391-4166-b35e-18ba93a1f298 Aug 5 21:53:57.924447 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:53:57.924474 kernel: BTRFS info (device nvme0n1p6): using free space tree Aug 5 21:53:57.929659 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Aug 5 21:53:57.933335 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 21:53:57.974165 ignition[1393]: INFO : Ignition 2.18.0 Aug 5 21:53:57.974165 ignition[1393]: INFO : Stage: files Aug 5 21:53:57.978751 ignition[1393]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:57.978751 ignition[1393]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:57.978751 ignition[1393]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:57.978751 ignition[1393]: INFO : PUT result: OK Aug 5 21:53:57.992779 ignition[1393]: DEBUG : files: compiled without relabeling support, skipping Aug 5 21:53:57.996002 ignition[1393]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 21:53:57.996002 ignition[1393]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 21:53:58.059000 ignition[1393]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 21:53:58.061727 ignition[1393]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 21:53:58.061727 ignition[1393]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 21:53:58.061319 unknown[1393]: wrote ssh authorized keys file for user: core Aug 5 21:53:58.103834 ignition[1393]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 21:53:58.107119 ignition[1393]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 21:53:58.107119 ignition[1393]: INFO : files: op(4): [started] processing unit "etcd-member.service" Aug 5 21:53:58.116322 ignition[1393]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: op(6): [started] setting preset to enabled for "etcd-member.service" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: op(6): [finished] setting preset to enabled for "etcd-member.service" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 21:53:58.120509 ignition[1393]: INFO : files: files passed Aug 5 21:53:58.120509 ignition[1393]: INFO : Ignition finished successfully Aug 5 21:53:58.142610 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 21:53:58.150982 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 21:53:58.165578 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 21:53:58.183688 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 21:53:58.186081 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 21:53:58.212136 initrd-setup-root-after-ignition[1422]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:53:58.216237 initrd-setup-root-after-ignition[1422]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:53:58.219228 initrd-setup-root-after-ignition[1426]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:53:58.224750 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 21:53:58.228395 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 21:53:58.240960 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 21:53:58.313244 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 21:53:58.314115 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 21:53:58.320326 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 21:53:58.324900 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 21:53:58.326847 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 21:53:58.337936 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 21:53:58.372061 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 21:53:58.384976 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 21:53:58.415457 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:53:58.420484 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:53:58.423174 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 21:53:58.428312 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 21:53:58.428762 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 21:53:58.435590 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 21:53:58.437966 systemd[1]: Stopped target basic.target - Basic System. Aug 5 21:53:58.441118 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 21:53:58.443695 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 21:53:58.447752 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 21:53:58.451744 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 21:53:58.458301 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 21:53:58.460719 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 21:53:58.463142 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 21:53:58.470350 systemd[1]: Stopped target swap.target - Swaps. Aug 5 21:53:58.473130 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 21:53:58.473483 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 21:53:58.481342 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:53:58.484613 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:53:58.487278 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 21:53:58.492605 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:53:58.498218 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 21:53:58.498512 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 21:53:58.503370 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 21:53:58.503782 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 21:53:58.512270 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 21:53:58.512548 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 21:53:58.526870 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 21:53:58.531076 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 21:53:58.532495 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:53:58.545126 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 21:53:58.547171 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 21:53:58.547510 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:53:58.557045 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 21:53:58.559442 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 21:53:58.578763 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 21:53:58.583066 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 21:53:58.596257 ignition[1446]: INFO : Ignition 2.18.0 Aug 5 21:53:58.596257 ignition[1446]: INFO : Stage: umount Aug 5 21:53:58.600464 ignition[1446]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:53:58.600464 ignition[1446]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Aug 5 21:53:58.600464 ignition[1446]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Aug 5 21:53:58.611557 ignition[1446]: INFO : PUT result: OK Aug 5 21:53:58.616240 ignition[1446]: INFO : umount: umount passed Aug 5 21:53:58.620018 ignition[1446]: INFO : Ignition finished successfully Aug 5 21:53:58.619101 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 21:53:58.624891 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 21:53:58.625251 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 21:53:58.634466 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 21:53:58.637052 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 21:53:58.640746 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 21:53:58.640889 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 21:53:58.643074 systemd[1]: ignition-fetch.service: Deactivated successfully. Aug 5 21:53:58.644922 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Aug 5 21:53:58.648597 systemd[1]: Stopped target network.target - Network. Aug 5 21:53:58.654492 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 21:53:58.654668 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 21:53:58.656862 systemd[1]: Stopped target paths.target - Path Units. Aug 5 21:53:58.658458 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 21:53:58.665340 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:53:58.667838 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 21:53:58.669712 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 21:53:58.671457 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 21:53:58.671547 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 21:53:58.673375 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 21:53:58.673448 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 21:53:58.675305 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 21:53:58.675397 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 21:53:58.677238 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 21:53:58.677318 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 21:53:58.679675 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 21:53:58.683439 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 21:53:58.695234 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 21:53:58.695445 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 21:53:58.696229 systemd-networkd[1197]: eth0: DHCPv6 lease lost Aug 5 21:53:58.701367 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 21:53:58.701520 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 21:53:58.705211 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 21:53:58.706742 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 21:53:58.711625 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 21:53:58.712010 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:53:58.734457 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 21:53:58.736651 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 21:53:58.736823 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 21:53:58.739777 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:53:58.745128 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 21:53:58.747128 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 21:53:58.797503 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 21:53:58.799530 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:53:58.804654 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 21:53:58.804803 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 21:53:58.806969 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 21:53:58.807073 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:53:58.810073 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 21:53:58.810346 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:53:58.813147 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 21:53:58.813313 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 21:53:58.817247 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 21:53:58.817369 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 21:53:58.822098 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 21:53:58.822181 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:53:58.824201 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 21:53:58.824792 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 21:53:58.828230 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 21:53:58.828327 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 21:53:58.855248 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 21:53:58.855386 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:53:58.875369 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 21:53:58.878794 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 21:53:58.878959 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:53:58.881692 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 21:53:58.881775 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:53:58.923976 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 21:53:58.925338 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 21:53:58.931731 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 21:53:58.942993 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 21:53:58.972753 systemd[1]: Switching root. Aug 5 21:53:59.033016 systemd-journald[250]: Journal stopped Aug 5 21:54:01.903265 systemd-journald[250]: Received SIGTERM from PID 1 (systemd). Aug 5 21:54:01.903537 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 21:54:01.903588 kernel: SELinux: policy capability open_perms=1 Aug 5 21:54:01.903623 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 21:54:01.903705 kernel: SELinux: policy capability always_check_network=0 Aug 5 21:54:01.903753 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 21:54:01.903785 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 21:54:01.903818 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 21:54:01.903850 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 21:54:01.903881 kernel: audit: type=1403 audit(1722894839.610:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 21:54:01.903915 systemd[1]: Successfully loaded SELinux policy in 73.158ms. Aug 5 21:54:01.903963 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 25.848ms. Aug 5 21:54:01.903998 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) Aug 5 21:54:01.904032 systemd[1]: Detected virtualization amazon. Aug 5 21:54:01.904067 systemd[1]: Detected architecture arm64. Aug 5 21:54:01.904103 systemd[1]: Detected first boot. Aug 5 21:54:01.904146 systemd[1]: Initializing machine ID from VM UUID. Aug 5 21:54:01.904181 zram_generator::config[1489]: No configuration found. Aug 5 21:54:01.904235 systemd[1]: Populated /etc with preset unit settings. Aug 5 21:54:01.904270 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 5 21:54:01.904301 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 5 21:54:01.904334 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 5 21:54:01.904383 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 21:54:01.904415 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 21:54:01.904444 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 21:54:01.904481 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 21:54:01.904514 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 21:54:01.904549 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 21:54:01.904586 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 21:54:01.904620 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 21:54:01.904678 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:54:01.904715 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:54:01.904747 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 21:54:01.904776 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 21:54:01.904810 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 21:54:01.904841 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 21:54:01.904877 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Aug 5 21:54:01.904912 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:54:01.904945 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 5 21:54:01.904976 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 5 21:54:01.905009 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 5 21:54:01.905044 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 21:54:01.905077 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:54:01.905109 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 21:54:01.905146 systemd[1]: Reached target slices.target - Slice Units. Aug 5 21:54:01.905182 systemd[1]: Reached target swap.target - Swaps. Aug 5 21:54:01.905218 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 21:54:01.905251 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 21:54:01.905283 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:54:01.905319 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 21:54:01.905352 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:54:01.905391 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 21:54:01.905425 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 21:54:01.905468 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 21:54:01.905499 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 21:54:01.905534 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 21:54:01.905567 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 21:54:01.905602 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 21:54:01.918767 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). Aug 5 21:54:01.918879 systemd[1]: Reached target machines.target - Containers. Aug 5 21:54:01.918913 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 21:54:01.918950 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:54:01.919000 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 21:54:01.919036 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 21:54:01.919067 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:54:01.919098 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 21:54:01.919131 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:54:01.919163 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 21:54:01.919204 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:54:01.919235 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 21:54:01.919271 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 5 21:54:01.919304 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 5 21:54:01.919334 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 5 21:54:01.919363 systemd[1]: Stopped systemd-fsck-usr.service. Aug 5 21:54:01.919399 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 21:54:01.919428 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 21:54:01.919459 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 21:54:01.919491 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 21:54:01.919525 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 21:54:01.919562 systemd[1]: verity-setup.service: Deactivated successfully. Aug 5 21:54:01.919592 systemd[1]: Stopped verity-setup.service. Aug 5 21:54:01.919627 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 21:54:01.919717 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 21:54:01.919748 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 21:54:01.919778 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 21:54:01.919808 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 21:54:01.919838 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 21:54:01.919875 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:54:01.919905 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 21:54:01.919935 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 21:54:01.919964 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:54:01.919995 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:54:01.920031 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:54:01.920061 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:54:01.920092 kernel: fuse: init (API version 7.39) Aug 5 21:54:01.920122 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 21:54:01.920152 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 21:54:01.920184 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 21:54:01.920215 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 21:54:01.920244 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 21:54:01.920275 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 21:54:01.920309 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 21:54:01.920340 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 21:54:01.920371 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 21:54:01.920403 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 21:54:01.920434 kernel: loop: module loaded Aug 5 21:54:01.920467 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 21:54:01.920499 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 21:54:01.920529 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:54:01.920559 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 21:54:01.927786 systemd-journald[1570]: Collecting audit messages is disabled. Aug 5 21:54:01.927889 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 21:54:01.927928 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 21:54:01.927969 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 21:54:01.928001 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:54:01.928031 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:54:01.928062 kernel: ACPI: bus type drm_connector registered Aug 5 21:54:01.928090 systemd-journald[1570]: Journal started Aug 5 21:54:01.928143 systemd-journald[1570]: Runtime Journal (/run/log/journal/ec27625b65687507c2ea7f529bd410a0) is 8.0M, max 75.3M, 67.3M free. Aug 5 21:54:01.076797 systemd[1]: Queued start job for default target multi-user.target. Aug 5 21:54:01.157419 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Aug 5 21:54:01.158516 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 5 21:54:01.935834 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 21:54:01.937656 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 21:54:01.938662 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 21:54:01.941434 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 21:54:01.944328 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 21:54:01.945963 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 21:54:01.949724 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 21:54:02.054005 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 21:54:02.056271 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 21:54:02.062362 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 21:54:02.065519 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 21:54:02.068371 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 21:54:02.074520 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 21:54:02.086955 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 21:54:02.094986 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 21:54:02.134275 systemd-journald[1570]: Time spent on flushing to /var/log/journal/ec27625b65687507c2ea7f529bd410a0 is 151.385ms for 893 entries. Aug 5 21:54:02.134275 systemd-journald[1570]: System Journal (/var/log/journal/ec27625b65687507c2ea7f529bd410a0) is 8.0M, max 195.6M, 187.6M free. Aug 5 21:54:02.367177 systemd-journald[1570]: Received client request to flush runtime journal. Aug 5 21:54:02.367374 kernel: loop0: detected capacity change from 0 to 59688 Aug 5 21:54:02.367452 kernel: block loop0: the capability attribute has been deprecated. Aug 5 21:54:02.219445 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:54:02.232535 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 21:54:02.278412 udevadm[1627]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 5 21:54:02.298155 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:54:02.322200 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 21:54:02.337040 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 21:54:02.366447 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 21:54:02.372890 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 21:54:02.397592 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 21:54:02.414842 systemd-tmpfiles[1630]: ACLs are not supported, ignoring. Aug 5 21:54:02.414885 systemd-tmpfiles[1630]: ACLs are not supported, ignoring. Aug 5 21:54:02.426163 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:54:02.475684 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 21:54:02.526544 kernel: loop1: detected capacity change from 0 to 113672 Aug 5 21:54:02.614851 kernel: loop2: detected capacity change from 0 to 51896 Aug 5 21:54:02.716088 kernel: loop3: detected capacity change from 0 to 59688 Aug 5 21:54:02.732761 kernel: loop4: detected capacity change from 0 to 113672 Aug 5 21:54:02.750713 kernel: loop5: detected capacity change from 0 to 51896 Aug 5 21:54:02.759678 (sd-merge)[1642]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-ami'. Aug 5 21:54:02.760904 (sd-merge)[1642]: Merged extensions into '/usr'. Aug 5 21:54:02.771940 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 21:54:02.792438 systemd[1]: Starting ensure-sysext.service... Aug 5 21:54:02.810955 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 21:54:02.872245 systemd[1]: Reloading requested from client PID 1644 ('systemctl') (unit ensure-sysext.service)... Aug 5 21:54:02.872291 systemd[1]: Reloading... Aug 5 21:54:02.913444 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 21:54:02.919305 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 21:54:02.924432 systemd-tmpfiles[1645]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 21:54:02.927325 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 5 21:54:02.927467 systemd-tmpfiles[1645]: ACLs are not supported, ignoring. Aug 5 21:54:02.946185 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 21:54:02.948780 systemd-tmpfiles[1645]: Skipping /boot Aug 5 21:54:03.010149 systemd-tmpfiles[1645]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 21:54:03.011346 systemd-tmpfiles[1645]: Skipping /boot Aug 5 21:54:03.130710 zram_generator::config[1675]: No configuration found. Aug 5 21:54:03.453622 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. Aug 5 21:54:03.590244 systemd[1]: Reloading finished in 717 ms. Aug 5 21:54:03.637591 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:54:03.664241 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 21:54:03.691948 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 21:54:03.707207 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 21:54:03.720237 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 21:54:03.729101 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 21:54:03.749762 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:54:03.763987 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:54:03.781357 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:54:03.793825 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:54:03.796530 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:54:03.825067 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 21:54:03.833045 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:54:03.833494 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:54:03.845867 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:54:03.859149 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 21:54:03.861918 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:54:03.862432 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 21:54:03.889194 systemd[1]: Finished ensure-sysext.service. Aug 5 21:54:03.893172 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 21:54:03.972785 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:54:03.973848 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:54:03.978000 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 21:54:04.000795 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 21:54:04.009591 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 21:54:04.009974 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 21:54:04.015367 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 21:54:04.023357 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:54:04.023745 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:54:04.030535 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). Aug 5 21:54:04.041053 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:54:04.041416 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:54:04.045400 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 21:54:04.061967 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 21:54:04.081490 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:54:04.103848 augenrules[1760]: No rules Aug 5 21:54:04.104433 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 21:54:04.116770 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 21:54:04.171715 systemd-udevd[1758]: Using default interface naming scheme 'v255'. Aug 5 21:54:04.225051 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:54:04.249607 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 21:54:04.349101 systemd-resolved[1734]: Positive Trust Anchors: Aug 5 21:54:04.353694 systemd-resolved[1734]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 21:54:04.353767 systemd-resolved[1734]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa corp home internal intranet lan local private test Aug 5 21:54:04.381168 systemd-resolved[1734]: Defaulting to hostname 'linux'. Aug 5 21:54:04.386453 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 21:54:04.388796 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:54:04.428722 systemd-networkd[1775]: lo: Link UP Aug 5 21:54:04.428748 systemd-networkd[1775]: lo: Gained carrier Aug 5 21:54:04.429996 systemd-networkd[1775]: Enumeration completed Aug 5 21:54:04.431807 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 21:54:04.434151 systemd[1]: Reached target network.target - Network. Aug 5 21:54:04.456854 (udev-worker)[1779]: Network interface NamePolicy= disabled on kernel command line. Aug 5 21:54:04.503883 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 21:54:04.533343 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Aug 5 21:54:04.534543 ldconfig[1591]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 21:54:04.558665 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1785) Aug 5 21:54:04.580247 systemd-networkd[1775]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:54:04.580271 systemd-networkd[1775]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 21:54:04.581249 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 21:54:04.585210 systemd-networkd[1775]: eth0: Link UP Aug 5 21:54:04.585573 systemd-networkd[1775]: eth0: Gained carrier Aug 5 21:54:04.585610 systemd-networkd[1775]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:54:04.598964 systemd-networkd[1775]: eth0: DHCPv4 address 172.31.19.36/20, gateway 172.31.16.1 acquired from 172.31.16.1 Aug 5 21:54:04.600502 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 21:54:04.664701 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 21:54:04.772731 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1780) Aug 5 21:54:04.876281 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:54:05.065259 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Aug 5 21:54:05.076162 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 21:54:05.079743 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 21:54:05.091119 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 21:54:05.095146 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:54:05.140807 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 21:54:05.144304 lvm[1897]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 21:54:05.181799 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 21:54:05.185118 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:54:05.187328 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 21:54:05.189765 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 21:54:05.192185 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 21:54:05.195140 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 21:54:05.197493 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 21:54:05.199792 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 21:54:05.202016 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 21:54:05.202083 systemd[1]: Reached target paths.target - Path Units. Aug 5 21:54:05.203697 systemd[1]: Reached target timers.target - Timer Units. Aug 5 21:54:05.206915 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 21:54:05.213460 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 21:54:05.226878 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 21:54:05.239286 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 21:54:05.245291 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 21:54:05.248483 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 21:54:05.251441 systemd[1]: Reached target basic.target - Basic System. Aug 5 21:54:05.253313 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 21:54:05.253365 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 21:54:05.261032 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 21:54:05.269868 lvm[1904]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 21:54:05.274286 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Aug 5 21:54:05.289212 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 21:54:05.296288 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 21:54:05.310058 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 21:54:05.312582 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). Aug 5 21:54:05.315120 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 21:54:05.321996 systemd[1]: Started ntpd.service - Network Time Service. Aug 5 21:54:05.329876 systemd[1]: Starting setup-oem.service - Setup OEM... Aug 5 21:54:05.333830 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 21:54:05.340682 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 21:54:05.351007 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 21:54:05.355139 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 21:54:05.366144 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 21:54:05.369040 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 21:54:05.378114 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 21:54:05.427061 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 21:54:05.427479 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 21:54:05.440170 jq[1908]: false Aug 5 21:54:05.468186 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 21:54:05.468747 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 21:54:05.469568 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 21:54:05.494624 (ntainerd)[1924]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 21:54:05.562226 jq[1917]: true Aug 5 21:54:05.588929 dbus-daemon[1907]: [system] SELinux support is enabled Aug 5 21:54:05.592184 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 21:54:05.599779 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). Aug 5 21:54:05.599867 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 21:54:05.602418 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). Aug 5 21:54:05.602465 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 21:54:05.614996 dbus-daemon[1907]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1775 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Aug 5 21:54:05.634388 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Aug 5 21:54:05.643672 extend-filesystems[1909]: Found loop3 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found loop4 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found loop5 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p1 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p2 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p3 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found usr Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p4 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p6 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p7 Aug 5 21:54:05.643672 extend-filesystems[1909]: Found nvme0n1p9 Aug 5 21:54:05.643672 extend-filesystems[1909]: Checking size of /dev/nvme0n1p9 Aug 5 21:54:05.687781 ntpd[1911]: ntpd 4.2.8p17@1.4004-o Mon Aug 5 19:55:32 UTC 2024 (1): Starting Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: ntpd 4.2.8p17@1.4004-o Mon Aug 5 19:55:32 UTC 2024 (1): Starting Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: ---------------------------------------------------- Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: ntp-4 is maintained by Network Time Foundation, Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: corporation. Support and training for ntp-4 are Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: available at https://www.nwtime.org/support Aug 5 21:54:05.691470 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: ---------------------------------------------------- Aug 5 21:54:05.687837 ntpd[1911]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Aug 5 21:54:05.687858 ntpd[1911]: ---------------------------------------------------- Aug 5 21:54:05.687877 ntpd[1911]: ntp-4 is maintained by Network Time Foundation, Aug 5 21:54:05.687896 ntpd[1911]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Aug 5 21:54:05.697560 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: proto: precision = 0.096 usec (-23) Aug 5 21:54:05.687922 ntpd[1911]: corporation. Support and training for ntp-4 are Aug 5 21:54:05.687941 ntpd[1911]: available at https://www.nwtime.org/support Aug 5 21:54:05.687959 ntpd[1911]: ---------------------------------------------------- Aug 5 21:54:05.694007 ntpd[1911]: proto: precision = 0.096 usec (-23) Aug 5 21:54:05.699179 ntpd[1911]: basedate set to 2024-07-24 Aug 5 21:54:05.713958 jq[1934]: true Aug 5 21:54:05.714397 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: basedate set to 2024-07-24 Aug 5 21:54:05.714397 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: gps base set to 2024-07-28 (week 2325) Aug 5 21:54:05.699224 ntpd[1911]: gps base set to 2024-07-28 (week 2325) Aug 5 21:54:05.715179 ntpd[1911]: Listen and drop on 0 v6wildcard [::]:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listen and drop on 0 v6wildcard [::]:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listen normally on 2 lo 127.0.0.1:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listen normally on 3 eth0 172.31.19.36:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listen normally on 4 lo [::1]:123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: bind(21) AF_INET6 fe80::452:d0ff:feab:4d89%2#123 flags 0x11 failed: Cannot assign requested address Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: unable to create socket on eth0 (5) for fe80::452:d0ff:feab:4d89%2#123 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: failed to init interface for address fe80::452:d0ff:feab:4d89%2 Aug 5 21:54:05.721276 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: Listening on routing socket on fd #21 for interface updates Aug 5 21:54:05.715296 ntpd[1911]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 5 21:54:05.717357 ntpd[1911]: Listen normally on 2 lo 127.0.0.1:123 Aug 5 21:54:05.717509 ntpd[1911]: Listen normally on 3 eth0 172.31.19.36:123 Aug 5 21:54:05.717597 ntpd[1911]: Listen normally on 4 lo [::1]:123 Aug 5 21:54:05.717729 ntpd[1911]: bind(21) AF_INET6 fe80::452:d0ff:feab:4d89%2#123 flags 0x11 failed: Cannot assign requested address Aug 5 21:54:05.717772 ntpd[1911]: unable to create socket on eth0 (5) for fe80::452:d0ff:feab:4d89%2#123 Aug 5 21:54:05.717802 ntpd[1911]: failed to init interface for address fe80::452:d0ff:feab:4d89%2 Aug 5 21:54:05.717871 ntpd[1911]: Listening on routing socket on fd #21 for interface updates Aug 5 21:54:05.723691 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 5 21:54:05.740391 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 21:54:05.745075 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 5 21:54:05.745075 ntpd[1911]: 5 Aug 21:54:05 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 5 21:54:05.723754 ntpd[1911]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 5 21:54:05.743804 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 21:54:05.777424 systemd[1]: Finished setup-oem.service - Setup OEM. Aug 5 21:54:05.791994 update_engine[1916]: I0805 21:54:05.790005 1916 main.cc:92] Flatcar Update Engine starting Aug 5 21:54:05.801269 extend-filesystems[1909]: Resized partition /dev/nvme0n1p9 Aug 5 21:54:05.805345 extend-filesystems[1958]: resize2fs 1.47.0 (5-Feb-2023) Aug 5 21:54:05.811287 systemd[1]: Started update-engine.service - Update Engine. Aug 5 21:54:05.830177 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Aug 5 21:54:05.830423 update_engine[1916]: I0805 21:54:05.828522 1916 update_check_scheduler.cc:74] Next update check in 6m35s Aug 5 21:54:05.827010 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 21:54:05.873723 coreos-metadata[1906]: Aug 05 21:54:05.869 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Aug 5 21:54:05.873723 coreos-metadata[1906]: Aug 05 21:54:05.872 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.873 INFO Fetch successful Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.873 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.874 INFO Fetch successful Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.875 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.876 INFO Fetch successful Aug 5 21:54:05.876751 coreos-metadata[1906]: Aug 05 21:54:05.876 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.878 INFO Fetch successful Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.878 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.880 INFO Fetch failed with 404: resource not found Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.880 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.880 INFO Fetch successful Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.881 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.881 INFO Fetch successful Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.882 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.882 INFO Fetch successful Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.883 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.883 INFO Fetch successful Aug 5 21:54:05.881356 coreos-metadata[1906]: Aug 05 21:54:05.883 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Aug 5 21:54:05.896882 coreos-metadata[1906]: Aug 05 21:54:05.885 INFO Fetch successful Aug 5 21:54:05.943349 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Aug 5 21:54:06.027912 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (1795) Aug 5 21:54:05.954083 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Aug 5 21:54:05.959788 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 21:54:06.051794 extend-filesystems[1958]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Aug 5 21:54:06.051794 extend-filesystems[1958]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 5 21:54:06.051794 extend-filesystems[1958]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Aug 5 21:54:06.041065 systemd-logind[1915]: Watching system buttons on /dev/input/event0 (Power Button) Aug 5 21:54:06.085393 bash[1990]: Updated "/home/core/.ssh/authorized_keys" Aug 5 21:54:06.085581 extend-filesystems[1909]: Resized filesystem in /dev/nvme0n1p9 Aug 5 21:54:06.041102 systemd-logind[1915]: Watching system buttons on /dev/input/event1 (Sleep Button) Aug 5 21:54:06.044477 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 21:54:06.047785 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 21:54:06.053931 systemd-logind[1915]: New seat seat0. Aug 5 21:54:06.072612 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 21:54:06.089125 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 21:54:06.145159 systemd[1]: Starting sshkeys.service... Aug 5 21:54:06.208291 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Aug 5 21:54:06.223000 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Aug 5 21:54:06.245981 locksmithd[1959]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 5 21:54:06.393584 dbus-daemon[1907]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 5 21:54:06.393861 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Aug 5 21:54:06.400971 dbus-daemon[1907]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=1943 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Aug 5 21:54:06.441691 systemd[1]: Starting polkit.service - Authorization Manager... Aug 5 21:54:06.495670 coreos-metadata[2018]: Aug 05 21:54:06.495 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Aug 5 21:54:06.502021 coreos-metadata[2018]: Aug 05 21:54:06.501 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Aug 5 21:54:06.508063 coreos-metadata[2018]: Aug 05 21:54:06.508 INFO Fetch successful Aug 5 21:54:06.508332 coreos-metadata[2018]: Aug 05 21:54:06.508 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Aug 5 21:54:06.512889 coreos-metadata[2018]: Aug 05 21:54:06.512 INFO Fetch successful Aug 5 21:54:06.515215 polkitd[2047]: Started polkitd version 121 Aug 5 21:54:06.536233 polkitd[2047]: Loading rules from directory /etc/polkit-1/rules.d Aug 5 21:54:06.543498 systemd[1]: Started polkit.service - Authorization Manager. Aug 5 21:54:06.536386 polkitd[2047]: Loading rules from directory /usr/share/polkit-1/rules.d Aug 5 21:54:06.539702 polkitd[2047]: Finished loading, compiling and executing 2 rules Aug 5 21:54:06.543174 dbus-daemon[1907]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 5 21:54:06.545777 polkitd[2047]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Aug 5 21:54:06.555103 unknown[2018]: wrote ssh authorized keys file for user: core Aug 5 21:54:06.556981 systemd-networkd[1775]: eth0: Gained IPv6LL Aug 5 21:54:06.568681 containerd[1924]: time="2024-08-05T21:54:06.566605991Z" level=info msg="starting containerd" revision=1fbfc07f8d28210e62bdbcbf7b950bac8028afbf version=v1.7.17 Aug 5 21:54:06.576245 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 21:54:06.643177 update-ssh-keys[2081]: Updated "/home/core/.ssh/authorized_keys" Aug 5 21:54:06.643002 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Aug 5 21:54:06.653534 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 21:54:06.664936 systemd-hostnamed[1943]: Hostname set to (transient) Aug 5 21:54:06.666797 systemd-resolved[1734]: System hostname changed to 'ip-172-31-19-36'. Aug 5 21:54:06.687753 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Aug 5 21:54:06.699247 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Aug 5 21:54:06.709182 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 21:54:06.722832 systemd[1]: Finished sshkeys.service. Aug 5 21:54:06.755767 (-wrapper)[2095]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Aug 5 21:54:06.907824 containerd[1924]: time="2024-08-05T21:54:06.904249825Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 21:54:06.907824 containerd[1924]: time="2024-08-05T21:54:06.904407517Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.916265 containerd[1924]: time="2024-08-05T21:54:06.916143517Z" 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.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 21:54:06.916265 containerd[1924]: time="2024-08-05T21:54:06.916246105Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.916867 containerd[1924]: time="2024-08-05T21:54:06.916808845Z" 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 Aug 5 21:54:06.916946 containerd[1924]: time="2024-08-05T21:54:06.916865761Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 21:54:06.917120 containerd[1924]: time="2024-08-05T21:54:06.917078041Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.917253 containerd[1924]: time="2024-08-05T21:54:06.917211277Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 21:54:06.917307 containerd[1924]: time="2024-08-05T21:54:06.917254177Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.917459 containerd[1924]: time="2024-08-05T21:54:06.917417437Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.922528 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 21:54:06.927664 containerd[1924]: time="2024-08-05T21:54:06.925066201Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.927664 containerd[1924]: time="2024-08-05T21:54:06.925148845Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 21:54:06.927664 containerd[1924]: time="2024-08-05T21:54:06.925178341Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:54:06.929005 containerd[1924]: time="2024-08-05T21:54:06.928195669Z" 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 Aug 5 21:54:06.929005 containerd[1924]: time="2024-08-05T21:54:06.928272589Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 21:54:06.929005 containerd[1924]: time="2024-08-05T21:54:06.928508665Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 21:54:06.929005 containerd[1924]: time="2024-08-05T21:54:06.928604125Z" level=info msg="metadata content store policy set" policy=shared Aug 5 21:54:06.957560 amazon-ssm-agent[2093]: Initializing new seelog logger Aug 5 21:54:06.959804 amazon-ssm-agent[2093]: New Seelog Logger Creation Complete Aug 5 21:54:06.959804 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.959804 amazon-ssm-agent[2093]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.959804 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 processing appconfig overrides Aug 5 21:54:06.975236 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO Proxy environment variables: Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975493837Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975566857Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975600229Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975706741Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975747145Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975774685Z" level=info msg="NRI interface is disabled by configuration." Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.975805417Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976228861Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976280401Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976319485Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976354273Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976389457Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976435897Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977222 containerd[1924]: time="2024-08-05T21:54:06.976468861Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976501873Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976537009Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976570321Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976601257Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976660849Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.976934845Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.977405773Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.977476081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.977510581Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 21:54:06.977976 containerd[1924]: time="2024-08-05T21:54:06.977562097Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 21:54:06.983776 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.983776 amazon-ssm-agent[2093]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.983776 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 processing appconfig overrides Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.981872389Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.981958501Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.981992677Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982046269Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982081933Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982135849Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982168417Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982221877Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.982264933Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.983262889Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.983568901Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.984008 containerd[1924]: time="2024-08-05T21:54:06.983609533Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.985977 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.985977 amazon-ssm-agent[2093]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.985977 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 processing appconfig overrides Aug 5 21:54:06.986272 containerd[1924]: time="2024-08-05T21:54:06.985777045Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.986272 containerd[1924]: time="2024-08-05T21:54:06.985933021Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.986272 containerd[1924]: time="2024-08-05T21:54:06.986076997Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.986272 containerd[1924]: time="2024-08-05T21:54:06.986139085Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.986272 containerd[1924]: time="2024-08-05T21:54:06.986174209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 21:54:06.987761 containerd[1924]: time="2024-08-05T21:54:06.987054529Z" 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}" Aug 5 21:54:06.988140 containerd[1924]: time="2024-08-05T21:54:06.987768373Z" level=info msg="Connect containerd service" Aug 5 21:54:06.991075 containerd[1924]: time="2024-08-05T21:54:06.989193541Z" level=info msg="using legacy CRI server" Aug 5 21:54:06.991075 containerd[1924]: time="2024-08-05T21:54:06.989302729Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 21:54:06.991075 containerd[1924]: time="2024-08-05T21:54:06.990831733Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 21:54:06.996687 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.996687 amazon-ssm-agent[2093]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Aug 5 21:54:06.996687 amazon-ssm-agent[2093]: 2024/08/05 21:54:06 processing appconfig overrides Aug 5 21:54:07.001026 containerd[1924]: time="2024-08-05T21:54:07.000788277Z" 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" Aug 5 21:54:07.002945 containerd[1924]: time="2024-08-05T21:54:07.002717937Z" level=info msg="Start subscribing containerd event" Aug 5 21:54:07.003119 containerd[1924]: time="2024-08-05T21:54:07.002868693Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 21:54:07.003236 containerd[1924]: time="2024-08-05T21:54:07.003129129Z" 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 Aug 5 21:54:07.003291 containerd[1924]: time="2024-08-05T21:54:07.003163737Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 21:54:07.003340 containerd[1924]: time="2024-08-05T21:54:07.003312177Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 21:54:07.003847 containerd[1924]: time="2024-08-05T21:54:07.003224025Z" level=info msg="Start recovering state" Aug 5 21:54:07.012396 containerd[1924]: time="2024-08-05T21:54:07.010818802Z" level=info msg="Start event monitor" Aug 5 21:54:07.012396 containerd[1924]: time="2024-08-05T21:54:07.010949446Z" level=info msg="Start snapshots syncer" Aug 5 21:54:07.012396 containerd[1924]: time="2024-08-05T21:54:07.010997446Z" level=info msg="Start cni network conf syncer for default" Aug 5 21:54:07.012396 containerd[1924]: time="2024-08-05T21:54:07.011028922Z" level=info msg="Start streaming server" Aug 5 21:54:07.017155 containerd[1924]: time="2024-08-05T21:54:07.017081986Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 21:54:07.021826 containerd[1924]: time="2024-08-05T21:54:07.018783382Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 21:54:07.021826 containerd[1924]: time="2024-08-05T21:54:07.018952498Z" level=info msg="containerd successfully booted in 0.508328s" Aug 5 21:54:07.020272 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 21:54:07.082665 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO https_proxy: Aug 5 21:54:07.181385 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO http_proxy: Aug 5 21:54:07.239153 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 5 21:54:07.244105 (dockerd)[2131]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Aug 5 21:54:07.280754 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO no_proxy: Aug 5 21:54:07.378986 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO Checking if agent identity type OnPrem can be assumed Aug 5 21:54:07.479698 amazon-ssm-agent[2093]: 2024-08-05 21:54:06 INFO Checking if agent identity type EC2 can be assumed Aug 5 21:54:07.581696 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO Agent will take identity from EC2 Aug 5 21:54:07.672000 sshd_keygen[1949]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 21:54:07.678664 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 5 21:54:07.739694 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 21:54:07.759413 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 21:54:07.778911 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 5 21:54:07.812014 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 21:54:07.812483 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 21:54:07.830268 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 21:54:07.877434 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] using named pipe channel for IPC Aug 5 21:54:07.910319 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 21:54:07.924837 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 21:54:07.937456 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Aug 5 21:54:07.942357 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 21:54:07.976654 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Aug 5 21:54:08.054789 dockerd[2131]: time="2024-08-05T21:54:08.053714411Z" level=info msg="Starting up" Aug 5 21:54:08.077701 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Aug 5 21:54:08.171561 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport2945731898-merged.mount: Deactivated successfully. Aug 5 21:54:08.178319 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] Starting Core Agent Aug 5 21:54:08.194342 systemd[1]: var-lib-docker-metacopy\x2dcheck72803596-merged.mount: Deactivated successfully. Aug 5 21:54:08.213617 dockerd[2131]: time="2024-08-05T21:54:08.213536687Z" level=info msg="Loading containers: start." Aug 5 21:54:08.278779 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [amazon-ssm-agent] registrar detected. Attempting registration Aug 5 21:54:08.379602 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [Registrar] Starting registrar module Aug 5 21:54:08.480245 amazon-ssm-agent[2093]: 2024-08-05 21:54:07 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Aug 5 21:54:08.519685 kernel: Initializing XFRM netlink socket Aug 5 21:54:08.546300 amazon-ssm-agent[2093]: 2024-08-05 21:54:08 INFO [EC2Identity] EC2 registration was successful. Aug 5 21:54:08.546823 amazon-ssm-agent[2093]: 2024-08-05 21:54:08 INFO [CredentialRefresher] credentialRefresher has started Aug 5 21:54:08.547030 amazon-ssm-agent[2093]: 2024-08-05 21:54:08 INFO [CredentialRefresher] Starting credentials refresher loop Aug 5 21:54:08.547030 amazon-ssm-agent[2093]: 2024-08-05 21:54:08 INFO EC2RoleProvider Successfully connected with instance profile role credentials Aug 5 21:54:08.581013 amazon-ssm-agent[2093]: 2024-08-05 21:54:08 INFO [CredentialRefresher] Next credential rotation will be in 32.066644341166665 minutes Aug 5 21:54:08.617292 (udev-worker)[1792]: Network interface NamePolicy= disabled on kernel command line. Aug 5 21:54:08.723264 systemd-networkd[1775]: docker0: Link UP Aug 5 21:54:08.752745 dockerd[2131]: time="2024-08-05T21:54:08.752660318Z" level=info msg="Loading containers: done." Aug 5 21:54:08.945246 dockerd[2131]: time="2024-08-05T21:54:08.945164247Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Aug 5 21:54:08.945735 dockerd[2131]: time="2024-08-05T21:54:08.945675687Z" level=info msg="Docker daemon" commit=fca702de7f71362c8d103073c7e4a1d0a467fadd graphdriver=overlay2 version=24.0.9 Aug 5 21:54:08.946197 dockerd[2131]: time="2024-08-05T21:54:08.946131951Z" level=info msg="Daemon has completed initialization" Aug 5 21:54:09.042483 dockerd[2131]: time="2024-08-05T21:54:09.042168636Z" level=info msg="API listen on /run/docker.sock" Aug 5 21:54:09.043046 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 5 21:54:09.047058 etcd-wrapper[2108]: Error response from daemon: No such container: etcd-member Aug 5 21:54:09.078408 etcd-wrapper[2263]: Error response from daemon: No such container: etcd-member Aug 5 21:54:09.140879 etcd-wrapper[2278]: Unable to find image 'quay.io/coreos/etcd:v3.5.0-arm64' locally Aug 5 21:54:09.153055 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2082108260-merged.mount: Deactivated successfully. Aug 5 21:54:09.577436 amazon-ssm-agent[2093]: 2024-08-05 21:54:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Aug 5 21:54:09.678449 amazon-ssm-agent[2093]: 2024-08-05 21:54:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2284) started Aug 5 21:54:09.778986 amazon-ssm-agent[2093]: 2024-08-05 21:54:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Aug 5 21:54:10.413370 etcd-wrapper[2278]: v3.5.0-arm64: Pulling from coreos/etcd Aug 5 21:54:10.413370 etcd-wrapper[2278]: 6022c6b01d76: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 1d97b1ec77fc: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 99ef4b690837: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 435b7bf0967c: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 17e4a5f7c1b5: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 5b0292999756: Pulling fs layer Aug 5 21:54:10.413370 etcd-wrapper[2278]: 435b7bf0967c: Waiting Aug 5 21:54:10.413370 etcd-wrapper[2278]: 17e4a5f7c1b5: Waiting Aug 5 21:54:10.413370 etcd-wrapper[2278]: 5b0292999756: Waiting Aug 5 21:54:10.848735 etcd-wrapper[2278]: 99ef4b690837: Verifying Checksum Aug 5 21:54:10.848735 etcd-wrapper[2278]: 99ef4b690837: Download complete Aug 5 21:54:11.009766 etcd-wrapper[2278]: 1d97b1ec77fc: Verifying Checksum Aug 5 21:54:11.009766 etcd-wrapper[2278]: 1d97b1ec77fc: Download complete Aug 5 21:54:11.033358 etcd-wrapper[2278]: 6022c6b01d76: Verifying Checksum Aug 5 21:54:11.033358 etcd-wrapper[2278]: 6022c6b01d76: Download complete Aug 5 21:54:11.305673 etcd-wrapper[2278]: 435b7bf0967c: Verifying Checksum Aug 5 21:54:11.305916 etcd-wrapper[2278]: 435b7bf0967c: Download complete Aug 5 21:54:11.351152 etcd-wrapper[2278]: 17e4a5f7c1b5: Verifying Checksum Aug 5 21:54:11.351152 etcd-wrapper[2278]: 17e4a5f7c1b5: Download complete Aug 5 21:54:11.378090 etcd-wrapper[2278]: 5b0292999756: Verifying Checksum Aug 5 21:54:11.378090 etcd-wrapper[2278]: 5b0292999756: Download complete Aug 5 21:54:11.688972 ntpd[1911]: Listen normally on 6 eth0 [fe80::452:d0ff:feab:4d89%2]:123 Aug 5 21:54:11.691249 ntpd[1911]: 5 Aug 21:54:11 ntpd[1911]: Listen normally on 6 eth0 [fe80::452:d0ff:feab:4d89%2]:123 Aug 5 21:54:12.628806 etcd-wrapper[2278]: 6022c6b01d76: Pull complete Aug 5 21:54:13.173081 systemd-resolved[1734]: Clock change detected. Flushing caches. Aug 5 21:54:13.427970 systemd[1]: var-lib-docker-overlay2-563e95c44255da3da9d19505a0537d4c125cfc2cbe605d6e064c7ae6b46c2169-merged.mount: Deactivated successfully. Aug 5 21:54:13.593578 etcd-wrapper[2278]: 1d97b1ec77fc: Pull complete Aug 5 21:54:13.657406 kernel: overlayfs: lowerdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior. Aug 5 21:54:13.876269 systemd[1]: var-lib-docker-overlay2-a68d9d484c469efca2dbf702a36165a33d30201a9497496119d7756aa2d64d58-merged.mount: Deactivated successfully. Aug 5 21:54:15.362771 etcd-wrapper[2278]: 99ef4b690837: Pull complete Aug 5 21:54:15.459456 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 21:54:15.466169 systemd[1]: Started sshd@0-172.31.19.36:22-147.75.109.163:33622.service - OpenSSH per-connection server daemon (147.75.109.163:33622). Aug 5 21:54:15.606611 systemd[1]: var-lib-docker-overlay2-b01bd7561236d5bfc34e2ef77814289b31fd4f2dd1f048c18eb2f575282b9459-merged.mount: Deactivated successfully. Aug 5 21:54:15.875313 sshd[2321]: Accepted publickey for core from 147.75.109.163 port 33622 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:15.879798 sshd[2321]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:15.899442 systemd-logind[1915]: New session 1 of user core. Aug 5 21:54:15.903149 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 21:54:15.908061 etcd-wrapper[2278]: 435b7bf0967c: Pull complete Aug 5 21:54:15.909909 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 21:54:15.936439 kernel: overlayfs: lowerdir is in-use as upperdir/workdir of another mount, accessing files from both mounts will result in undefined behavior. Aug 5 21:54:15.953798 systemd[1]: var-lib-docker-overlay2-91370bf0e3bd68073ca8653cba1687a0b252cda7a5556451962c07a4716c684d-merged.mount: Deactivated successfully. Aug 5 21:54:15.969040 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 21:54:15.981452 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 21:54:15.989144 etcd-wrapper[2278]: 17e4a5f7c1b5: Pull complete Aug 5 21:54:16.005153 (systemd)[2329]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:16.053337 etcd-wrapper[2278]: 5b0292999756: Pull complete Aug 5 21:54:16.070068 etcd-wrapper[2278]: Digest: sha256:96255d1ad67e7afcea46b4dc1b7e74024b9f6971193cb7316b093b3b5671efb0 Aug 5 21:54:16.077238 etcd-wrapper[2278]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0-arm64 Aug 5 21:54:16.285021 systemd[2329]: Queued start job for default target default.target. Aug 5 21:54:16.298487 systemd[2329]: Created slice app.slice - User Application Slice. Aug 5 21:54:16.298822 systemd[2329]: Reached target paths.target - Paths. Aug 5 21:54:16.298859 systemd[2329]: Reached target timers.target - Timers. Aug 5 21:54:16.301981 systemd[2329]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 21:54:16.337663 systemd[2329]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 21:54:16.337940 systemd[2329]: Reached target sockets.target - Sockets. Aug 5 21:54:16.337989 systemd[2329]: Reached target basic.target - Basic System. Aug 5 21:54:16.338103 systemd[2329]: Reached target default.target - Main User Target. Aug 5 21:54:16.338172 systemd[2329]: Startup finished in 312ms. Aug 5 21:54:16.338378 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 21:54:16.345773 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 21:54:16.522561 systemd[1]: Started sshd@1-172.31.19.36:22-147.75.109.163:33624.service - OpenSSH per-connection server daemon (147.75.109.163:33624). Aug 5 21:54:16.578274 containerd[1924]: time="2024-08-05T21:54:16.578112636Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 21:54:16.578274 containerd[1924]: time="2024-08-05T21:54:16.578222976Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 21:54:16.579428 containerd[1924]: time="2024-08-05T21:54:16.579258516Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 21:54:16.579428 containerd[1924]: time="2024-08-05T21:54:16.579329544Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 21:54:16.616851 systemd[1]: run-docker-runtime\x2drunc-moby-4c0f44cd8280318c8ecd202536aaddb963dc15206ab8cfc47dd3da95cc0191fa-runc.3qVAVQ.mount: Deactivated successfully. Aug 5 21:54:16.632697 systemd[1]: Started docker-4c0f44cd8280318c8ecd202536aaddb963dc15206ab8cfc47dd3da95cc0191fa.scope - libcontainer container 4c0f44cd8280318c8ecd202536aaddb963dc15206ab8cfc47dd3da95cc0191fa. Aug 5 21:54:16.729600 sshd[2352]: Accepted publickey for core from 147.75.109.163 port 33624 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:16.735393 sshd[2352]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:16.748654 systemd-logind[1915]: New session 2 of user core. Aug 5 21:54:16.753725 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"info","ts":1722894856.774128,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"info","ts":1722894856.7742774,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec27625b65687507c2ea7f529bd410a0"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"warn","ts":1722894856.7746696,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"warn","ts":1722894856.774718,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0-arm64"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"warn","ts":1722894856.7747512,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"warn","ts":1722894856.774767,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Aug 5 21:54:16.775154 etcd-wrapper[2278]: {"level":"warn","ts":1722894856.7747867,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} Aug 5 21:54:16.777043 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:16.774Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.31.19.36:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.31.19.36:2380","--advertise-client-urls=http://172.31.19.36:2379","--discovery=https://discovery.etcd.io/73a070ab52778eee7be5daebd2a5096c"]} Aug 5 21:54:16.777043 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:16.775Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.31.19.36:2380"]} Aug 5 21:54:16.777043 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:16.775Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Aug 5 21:54:16.777506 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:16.776Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"arm64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"ec27625b65687507c2ea7f529bd410a0","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://172.31.19.36:2380"],"listen-peer-urls":["http://172.31.19.36:2380"],"advertise-client-urls":["http://172.31.19.36:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec27625b65687507c2ea7f529bd410a0=http://172.31.19.36:2380","initial-cluster-state":"new","initial-cluster-token":"https://discovery.etcd.io/73a070ab52778eee7be5daebd2a5096c","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"https://discovery.etcd.io/73a070ab52778eee7be5daebd2a5096c","discovery-proxy":"","downgrade-check-interval":"5s"} Aug 5 21:54:16.780485 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:16.780Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.812572ms"} Aug 5 21:54:16.895470 sshd[2352]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:16.904758 systemd[1]: sshd@1-172.31.19.36:22-147.75.109.163:33624.service: Deactivated successfully. Aug 5 21:54:16.909691 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 21:54:16.911102 systemd-logind[1915]: Session 2 logged out. Waiting for processes to exit. Aug 5 21:54:16.913910 systemd-logind[1915]: Removed session 2. Aug 5 21:54:16.931917 systemd[1]: Started sshd@2-172.31.19.36:22-147.75.109.163:33626.service - OpenSSH per-connection server daemon (147.75.109.163:33626). Aug 5 21:54:17.111878 sshd[2401]: Accepted publickey for core from 147.75.109.163 port 33626 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:17.115497 sshd[2401]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:17.127208 systemd-logind[1915]: New session 3 of user core. Aug 5 21:54:17.138781 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 21:54:17.272895 sshd[2401]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:17.279603 systemd-logind[1915]: Session 3 logged out. Waiting for processes to exit. Aug 5 21:54:17.280715 systemd[1]: sshd@2-172.31.19.36:22-147.75.109.163:33626.service: Deactivated successfully. Aug 5 21:54:17.285329 systemd[1]: session-3.scope: Deactivated successfully. Aug 5 21:54:17.290406 systemd-logind[1915]: Removed session 3. Aug 5 21:54:17.480870 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.479Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"c05589b534eccee0"} Aug 5 21:54:17.480870 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.479Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"b0c26e2090932620"} Aug 5 21:54:17.480870 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.479Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"https://discovery.etcd.io","self":"41d96a41d19e77f4"} Aug 5 21:54:17.480870 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.479Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"https://discovery.etcd.io","found-peers":3} Aug 5 21:54:17.495969 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.495Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"41d96a41d19e77f4","cluster-id":"3a29351bdf85fd17"} Aug 5 21:54:17.496708 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=()"} Aug 5 21:54:17.496708 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became follower at term 0"} Aug 5 21:54:17.496708 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 41d96a41d19e77f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Aug 5 21:54:17.497193 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became follower at term 1"} Aug 5 21:54:17.497375 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228)"} Aug 5 21:54:17.497516 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228 12736863782300755488)"} Aug 5 21:54:17.497768 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228 12736863782300755488 13859134839672590048)"} Aug 5 21:54:17.500685 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:17.500Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Aug 5 21:54:17.503109 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.502Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Aug 5 21:54:17.504852 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.504Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Aug 5 21:54:17.506458 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.506Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.506458 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.506Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.511548 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.511Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.512828 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.511Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.512828 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.512Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.512828 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.512Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620","remote-peer-urls":["http://172.31.17.102:2380"]} Aug 5 21:54:17.512828 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.512Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.512828 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.512Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.516027 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.513Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.516027 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.513Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.516663 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.515Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.516663 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.515Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.519268 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.518Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.521421 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.518Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-urls":["http://172.31.19.84:2380"]} Aug 5 21:54:17.521421 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.519Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"41d96a41d19e77f4","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Aug 5 21:54:17.521421 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.521Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.522867 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.522Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.525505 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.524Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Aug 5 21:54:17.527058 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.526Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"41d96a41d19e77f4","initial-advertise-peer-urls":["http://172.31.19.36:2380"],"listen-peer-urls":["http://172.31.19.36:2380"],"advertise-client-urls":["http://172.31.19.36:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Aug 5 21:54:17.528984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.526Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.31.19.36:2380"} Aug 5 21:54:17.528984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.527Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.31.19.36:2380"} Aug 5 21:54:17.528984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.528Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"41d96a41d19e77f4","to":"b0c26e2090932620","stream-type":"stream Message"} Aug 5 21:54:17.528984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.528Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"b0c26e2090932620"} Aug 5 21:54:17.528984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.528Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.532856 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.529Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"41d96a41d19e77f4","to":"c05589b534eccee0","stream-type":"stream MsgApp v2"} Aug 5 21:54:17.532856 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.529Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"c05589b534eccee0"} Aug 5 21:54:17.532856 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.529Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.532856 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.530Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"41d96a41d19e77f4","to":"c05589b534eccee0","stream-type":"stream Message"} Aug 5 21:54:17.532856 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.530Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.535052 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.531Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"41d96a41d19e77f4","to":"b0c26e2090932620","stream-type":"stream MsgApp v2"} Aug 5 21:54:17.535052 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.531Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.535852 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.533Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.535852 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.534Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:17.535852 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.535Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.537128 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228 12736863782300755488 13859134839672590048)"} Aug 5 21:54:17.537128 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.536Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:17.538308 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.537Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"3a29351bdf85fd17","local-member-id":"41d96a41d19e77f4","added-peer-id":"41d96a41d19e77f4","added-peer-peer-urls":["http://172.31.19.36:2380"]} Aug 5 21:54:17.538308 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228 12736863782300755488 13859134839672590048)"} Aug 5 21:54:17.538658 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.538Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"3a29351bdf85fd17","local-member-id":"41d96a41d19e77f4","added-peer-id":"b0c26e2090932620","added-peer-peer-urls":["http://172.31.17.102:2380"]} Aug 5 21:54:17.539250 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 switched to configuration voters=(4744940513333770228 12736863782300755488 13859134839672590048)"} Aug 5 21:54:17.539984 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.539Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"3a29351bdf85fd17","local-member-id":"41d96a41d19e77f4","added-peer-id":"c05589b534eccee0","added-peer-peer-urls":["http://172.31.19.84:2380"]} Aug 5 21:54:17.576708 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:17.576Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"41d96a41d19e77f4","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Aug 5 21:54:18.100056 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 1"} Aug 5 21:54:18.100056 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 1"} Aug 5 21:54:18.100056 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 1"} Aug 5 21:54:18.100056 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 1, index: 3] sent MsgPreVote request to b0c26e2090932620 at term 1"} Aug 5 21:54:18.100056 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 1, index: 3] sent MsgPreVote request to c05589b534eccee0 at term 1"} Aug 5 21:54:18.101543 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from c05589b534eccee0 at term 1"} Aug 5 21:54:18.102191 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 has received 2 MsgPreVoteResp votes and 0 vote rejections"} Aug 5 21:54:18.102191 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became candidate at term 2"} Aug 5 21:54:18.102191 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:54:18.102191 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 1, index: 3] sent MsgVote request to b0c26e2090932620 at term 2"} Aug 5 21:54:18.102191 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 1, index: 3] sent MsgVote request to c05589b534eccee0 at term 2"} Aug 5 21:54:18.103986 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgVoteResp from c05589b534eccee0 at term 2"} Aug 5 21:54:18.103986 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 has received 2 MsgVoteResp votes and 0 vote rejections"} Aug 5 21:54:18.104896 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became leader at term 2"} Aug 5 21:54:18.104896 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41d96a41d19e77f4 elected leader 41d96a41d19e77f4 at term 2"} Aug 5 21:54:18.110402 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.108Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"41d96a41d19e77f4","local-member-attributes":"{Name:ec27625b65687507c2ea7f529bd410a0 ClientURLs:[http://172.31.19.36:2379]}","request-path":"/0/members/41d96a41d19e77f4/attributes","cluster-id":"3a29351bdf85fd17","publish-timeout":"7s"} Aug 5 21:54:18.110402 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.108Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Aug 5 21:54:18.113695 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.109Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Aug 5 21:54:18.113695 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.110Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Aug 5 21:54:18.113695 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.111Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Aug 5 21:54:18.111021 systemd[1]: Started etcd-member.service - etcd (System Application Container). Aug 5 21:54:18.112338 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 21:54:18.117231 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.114Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Aug 5 21:54:18.114485 systemd[1]: Startup finished in 1.323s (kernel) + 8.781s (initrd) + 18.091s (userspace) = 28.196s. Aug 5 21:54:18.119775 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.118Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"3a29351bdf85fd17","local-member-id":"41d96a41d19e77f4","cluster-version":"3.5"} Aug 5 21:54:18.120497 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.119Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Aug 5 21:54:18.120820 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:18.119Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"} Aug 5 21:54:27.326028 systemd[1]: Started sshd@3-172.31.19.36:22-147.75.109.163:34182.service - OpenSSH per-connection server daemon (147.75.109.163:34182). Aug 5 21:54:27.497636 sshd[2410]: Accepted publickey for core from 147.75.109.163 port 34182 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:27.500702 sshd[2410]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:27.511264 systemd-logind[1915]: New session 4 of user core. Aug 5 21:54:27.522764 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 21:54:27.660300 sshd[2410]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:27.666292 systemd[1]: sshd@3-172.31.19.36:22-147.75.109.163:34182.service: Deactivated successfully. Aug 5 21:54:27.669961 systemd[1]: session-4.scope: Deactivated successfully. Aug 5 21:54:27.674672 systemd-logind[1915]: Session 4 logged out. Waiting for processes to exit. Aug 5 21:54:27.676915 systemd-logind[1915]: Removed session 4. Aug 5 21:54:27.699460 systemd[1]: Started sshd@4-172.31.19.36:22-147.75.109.163:34196.service - OpenSSH per-connection server daemon (147.75.109.163:34196). Aug 5 21:54:27.882598 sshd[2417]: Accepted publickey for core from 147.75.109.163 port 34196 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:27.885889 sshd[2417]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:27.896964 systemd-logind[1915]: New session 5 of user core. Aug 5 21:54:27.907712 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 21:54:28.032489 sshd[2417]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:28.040066 systemd-logind[1915]: Session 5 logged out. Waiting for processes to exit. Aug 5 21:54:28.041238 systemd[1]: sshd@4-172.31.19.36:22-147.75.109.163:34196.service: Deactivated successfully. Aug 5 21:54:28.046753 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 21:54:28.050876 systemd-logind[1915]: Removed session 5. Aug 5 21:54:28.074950 systemd[1]: Started sshd@5-172.31.19.36:22-147.75.109.163:34208.service - OpenSSH per-connection server daemon (147.75.109.163:34208). Aug 5 21:54:28.238162 sshd[2424]: Accepted publickey for core from 147.75.109.163 port 34208 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:28.240902 sshd[2424]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:28.249238 systemd-logind[1915]: New session 6 of user core. Aug 5 21:54:28.262009 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 5 21:54:28.394862 sshd[2424]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:28.403205 systemd[1]: session-6.scope: Deactivated successfully. Aug 5 21:54:28.404775 systemd[1]: sshd@5-172.31.19.36:22-147.75.109.163:34208.service: Deactivated successfully. Aug 5 21:54:28.414051 systemd-logind[1915]: Session 6 logged out. Waiting for processes to exit. Aug 5 21:54:28.445009 systemd[1]: Started sshd@6-172.31.19.36:22-147.75.109.163:34222.service - OpenSSH per-connection server daemon (147.75.109.163:34222). Aug 5 21:54:28.447855 systemd-logind[1915]: Removed session 6. Aug 5 21:54:28.614105 sshd[2431]: Accepted publickey for core from 147.75.109.163 port 34222 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:28.617672 sshd[2431]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:28.628889 systemd-logind[1915]: New session 7 of user core. Aug 5 21:54:28.637653 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 5 21:54:28.768189 sudo[2434]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Aug 5 21:54:28.770153 sudo[2434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:54:28.788829 sudo[2434]: pam_unix(sudo:session): session closed for user root Aug 5 21:54:28.813118 sshd[2431]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:28.822774 systemd[1]: sshd@6-172.31.19.36:22-147.75.109.163:34222.service: Deactivated successfully. Aug 5 21:54:28.827796 systemd[1]: session-7.scope: Deactivated successfully. Aug 5 21:54:28.830201 systemd-logind[1915]: Session 7 logged out. Waiting for processes to exit. Aug 5 21:54:28.852114 systemd-logind[1915]: Removed session 7. Aug 5 21:54:28.862949 systemd[1]: Started sshd@7-172.31.19.36:22-147.75.109.163:34226.service - OpenSSH per-connection server daemon (147.75.109.163:34226). Aug 5 21:54:29.036471 sshd[2439]: Accepted publickey for core from 147.75.109.163 port 34226 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:29.040031 sshd[2439]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:29.050809 systemd-logind[1915]: New session 8 of user core. Aug 5 21:54:29.057031 systemd[1]: Started session-8.scope - Session 8 of User core. Aug 5 21:54:29.165607 sudo[2443]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Aug 5 21:54:29.166160 sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:54:29.173293 sudo[2443]: pam_unix(sudo:session): session closed for user root Aug 5 21:54:29.185269 sudo[2442]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Aug 5 21:54:29.186418 sudo[2442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:54:29.211047 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Aug 5 21:54:29.232712 auditctl[2446]: No rules Aug 5 21:54:29.233648 systemd[1]: audit-rules.service: Deactivated successfully. Aug 5 21:54:29.234079 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Aug 5 21:54:29.247249 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 21:54:29.309874 augenrules[2464]: No rules Aug 5 21:54:29.313070 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 21:54:29.316613 sudo[2442]: pam_unix(sudo:session): session closed for user root Aug 5 21:54:29.340099 sshd[2439]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:29.351693 systemd[1]: sshd@7-172.31.19.36:22-147.75.109.163:34226.service: Deactivated successfully. Aug 5 21:54:29.357788 systemd[1]: session-8.scope: Deactivated successfully. Aug 5 21:54:29.359766 systemd-logind[1915]: Session 8 logged out. Waiting for processes to exit. Aug 5 21:54:29.376498 systemd-logind[1915]: Removed session 8. Aug 5 21:54:29.385057 systemd[1]: Started sshd@8-172.31.19.36:22-147.75.109.163:34232.service - OpenSSH per-connection server daemon (147.75.109.163:34232). Aug 5 21:54:29.563087 sshd[2472]: Accepted publickey for core from 147.75.109.163 port 34232 ssh2: RSA SHA256:wCWVMkJZxcY0ESuxWRGGxvacdmS2jITy+nqYuVzP2ZU Aug 5 21:54:29.566224 sshd[2472]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:54:29.574303 systemd-logind[1915]: New session 9 of user core. Aug 5 21:54:29.587962 systemd[1]: Started session-9.scope - Session 9 of User core. Aug 5 21:54:29.951944 sshd[2472]: pam_unix(sshd:session): session closed for user core Aug 5 21:54:29.959575 systemd[1]: sshd@8-172.31.19.36:22-147.75.109.163:34232.service: Deactivated successfully. Aug 5 21:54:29.965989 systemd[1]: session-9.scope: Deactivated successfully. Aug 5 21:54:29.971182 systemd-logind[1915]: Session 9 logged out. Waiting for processes to exit. Aug 5 21:54:29.974146 systemd-logind[1915]: Removed session 9. Aug 5 21:54:33.779199 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:33.777Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","error":"EOF"} Aug 5 21:54:33.779199 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:33.777Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","error":"EOF"} Aug 5 21:54:33.785527 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:33.784Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"c05589b534eccee0","error":"failed to dial c05589b534eccee0 on stream Message (read tcp 172.31.19.36:47596->172.31.19.84:2380: read: connection reset by peer)"} Aug 5 21:54:34.099909 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:34.098Z","caller":"rafthttp/stream.go:223","msg":"lost TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:34.122788 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:34.121Z","caller":"etcdserver/cluster_util.go:288","msg":"failed to reach the peer URL","address":"http://172.31.19.84:2380/version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: connect: connection refused"} Aug 5 21:54:34.122788 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:34.121Z","caller":"etcdserver/cluster_util.go:155","msg":"failed to get version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: connect: connection refused"} Aug 5 21:54:37.177272 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 5 21:54:38.123038 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:38.122Z","caller":"etcdserver/cluster_util.go:288","msg":"failed to reach the peer URL","address":"http://172.31.19.84:2380/version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: connect: connection refused"} Aug 5 21:54:38.123847 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:38.122Z","caller":"etcdserver/cluster_util.go:155","msg":"failed to get version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: connect: connection refused"} Aug 5 21:54:39.183549 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:39.182Z","caller":"rafthttp/stream.go:194","msg":"lost TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0"} Aug 5 21:54:44.126460 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:44.125Z","caller":"etcdserver/cluster_util.go:288","msg":"failed to reach the peer URL","address":"http://172.31.19.84:2380/version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:44.126460 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:44.126Z","caller":"etcdserver/cluster_util.go:155","msg":"failed to get version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:49.299455 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:49.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:49.799785 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:49.799Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:49.899061 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:49.898Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:49.999331 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:49.998Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.099178 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.098Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.129539 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.129Z","caller":"etcdserver/cluster_util.go:288","msg":"failed to reach the peer URL","address":"http://172.31.19.84:2380/version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:50.129539 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.129Z","caller":"etcdserver/cluster_util.go:155","msg":"failed to get version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:50.199649 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.198Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.299311 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.399310 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.398Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.500007 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.499Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.599599 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.599Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.699024 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.698Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.799848 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.799Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:50.899950 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.899Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.000075 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:50.999Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.099690 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.099Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.199236 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.198Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.299742 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.799245 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.798Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.898941 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.898Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:51.927422 update_engine[1916]: I0805 21:54:51.926512 1916 update_attempter.cc:509] Updating boot flags... Aug 5 21:54:52.003607 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:51.999Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.024810 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (2502) Aug 5 21:54:52.099331 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.098Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.199010 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.198Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.299814 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.316433 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (2506) Aug 5 21:54:52.398949 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.398Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.501472 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.500Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.528425 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.525Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:52.528425 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.525Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:52.604708 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.602Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.641480 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 41 scanned by (udev-worker) (2506) Aug 5 21:54:52.699648 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.698Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.800026 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.798Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:52.899485 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.898Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.000304 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:52.999Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.099970 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.099Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.199056 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.198Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.299965 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.799230 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.798Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.900074 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.899Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:53.999629 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:53.998Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.099593 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.098Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.199801 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.199Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.300101 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.299Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.399798 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.398Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.499186 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.498Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.599133 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.598Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.699945 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.699Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.799573 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.798Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.841862 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.841Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620","error":"EOF"} Aug 5 21:54:54.844082 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.843Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620","error":"EOF"} Aug 5 21:54:54.859950 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.859Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"b0c26e2090932620","error":"failed to dial b0c26e2090932620 on stream MsgApp v2 (peer b0c26e2090932620 failed to find local node 41d96a41d19e77f4)"} Aug 5 21:54:54.899708 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.899Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:54.998949 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:54.998Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.099015 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.098Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.199255 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.198Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.199255 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.198Z","caller":"rafthttp/stream.go:223","msg":"lost TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:54:55.299528 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.298Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.799719 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.798Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.899600 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.898Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:55.999197 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:55.998Z","caller":"rafthttp/peer.go:267","msg":"dropped internal Raft message since sending buffer is full (overloaded network)","message-type":"MsgHeartbeat","local-member-id":"41d96a41d19e77f4","from":"41d96a41d19e77f4","remote-peer-id":"c05589b534eccee0","remote-peer-name":"pipeline","remote-peer-active":false} Aug 5 21:54:56.099643 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:56.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"41d96a41d19e77f4 stepped down to follower since quorum is not active"} Aug 5 21:54:56.099643 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:56.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became follower at term 2"} Aug 5 21:54:56.099643 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:56.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41d96a41d19e77f4 lost leader 41d96a41d19e77f4 at term 2"} Aug 5 21:54:56.133589 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:56.133Z","caller":"etcdserver/cluster_util.go:288","msg":"failed to reach the peer URL","address":"http://172.31.19.84:2380/version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:56.133589 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:56.133Z","caller":"etcdserver/cluster_util.go:155","msg":"failed to get version","remote-member-id":"c05589b534eccee0","error":"Get \"http://172.31.19.84:2380/version\": dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:57.526911 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:57.526Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:57.526911 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:54:57.526Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:54:57.800273 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:57.798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:54:57.800273 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:57.798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:54:57.800273 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:57.798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:54:57.800273 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:57.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:54:57.800273 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:57.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:54:59.499338 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:59.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:54:59.499338 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:59.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:54:59.499338 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:59.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:54:59.499338 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:59.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:54:59.499338 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:54:59.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:00.846152 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:00.845Z","caller":"rafthttp/stream.go:194","msg":"lost TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"41d96a41d19e77f4","remote-peer-id":"b0c26e2090932620"} Aug 5 21:55:01.199888 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:01.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:01.199888 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:01.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:01.199888 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:01.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:01.199888 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:01.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:01.199888 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:01.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:02.527156 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:02.526Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:02.527156 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:02.526Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:02.899552 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:02.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:02.899552 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:02.899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:02.899552 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:02.899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:02.899552 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:02.899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:02.899552 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:02.899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:04.599678 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:04.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:04.599678 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:04.599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:04.599678 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:04.599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:04.599678 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:04.599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:04.599678 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:04.599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:06.299713 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:06.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:06.299713 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:06.299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:06.299713 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:06.299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:06.299713 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:06.299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:06.299713 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:06.299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:07.527517 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:07.527Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:07.527517 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:07.527Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:07.999478 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:07.998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:08.000070 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:07.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:08.000070 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:07.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:08.000070 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:07.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:08.000070 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:07.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:09.699875 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:09.699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:09.699875 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:09.699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:09.699875 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:09.699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:09.699875 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:09.699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:09.699875 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:09.699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:11.399763 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:11.398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:11.399763 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:11.398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:11.399763 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:11.398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:11.399763 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:11.398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:11.399763 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:11.398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:12.528954 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:12.528Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:12.528954 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:12.528Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:13.099561 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:13.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:13.099561 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:13.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:13.099561 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:13.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:13.099561 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:13.098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:13.099561 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:13.099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:14.800412 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:14.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:14.800412 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:14.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:14.800412 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:14.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:14.801637 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:14.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:14.801637 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:14.799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:16.499266 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:16.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:16.499266 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:16.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:16.499266 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:16.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:16.499266 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:16.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:16.499266 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:16.498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:17.529996 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:17.529Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:17.529996 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:17.529Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: i/o timeout"} Aug 5 21:55:18.199105 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:18.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:18.199105 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:18.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:18.199105 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:18.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:18.199105 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:18.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:18.199105 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:18.198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:19.899464 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:19.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:19.899464 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:19.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:19.899464 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:19.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:19.899464 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:19.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:19.899464 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:19.898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:21.599046 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:21.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:21.599046 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:21.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:21.599046 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:21.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:21.599046 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:21.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:21.599046 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:21.598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:22.515226 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:22.514Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"b0c26e2090932620","rtt":"683.699µs","error":"dial tcp 172.31.17.102:2380: i/o timeout"} Aug 5 21:55:22.516312 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:22.516Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"b0c26e2090932620","rtt":"930.864µs","error":"dial tcp 172.31.17.102:2380: i/o timeout"} Aug 5 21:55:22.530590 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:22.530Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_RAFT_MESSAGE","remote-peer-id":"c05589b534eccee0","rtt":"439.974µs","error":"dial tcp 172.31.19.84:2380: connect: no route to host"} Aug 5 21:55:22.530867 etcd-wrapper[2278]: {"level":"warn","ts":"2024-08-05T21:55:22.530Z","caller":"rafthttp/probing_status.go:68","msg":"prober detected unhealthy status","round-tripper-name":"ROUND_TRIPPER_SNAPSHOT","remote-peer-id":"c05589b534eccee0","rtt":"305.032µs","error":"dial tcp 172.31.19.84:2380: connect: no route to host"} Aug 5 21:55:23.300041 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:23.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:23.300041 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:23.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:23.300041 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:23.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:23.300041 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:23.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:23.300041 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:23.298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"} Aug 5 21:55:25.000531 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:24.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 is starting a new election at term 2"} Aug 5 21:55:25.000531 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:24.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 became pre-candidate at term 2"} Aug 5 21:55:25.000531 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:24.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 received MsgPreVoteResp from 41d96a41d19e77f4 at term 2"} Aug 5 21:55:25.000531 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:24.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to b0c26e2090932620 at term 2"} Aug 5 21:55:25.000531 etcd-wrapper[2278]: {"level":"info","ts":"2024-08-05T21:55:24.999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41d96a41d19e77f4 [logterm: 2, index: 11] sent MsgPreVote request to c05589b534eccee0 at term 2"}