May 14 23:49:42.227825 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] May 14 23:49:42.227871 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Wed May 14 22:22:56 -00 2025 May 14 23:49:42.227897 kernel: KASLR disabled due to lack of seed May 14 23:49:42.227913 kernel: efi: EFI v2.7 by EDK II May 14 23:49:42.227930 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7a733a98 MEMRESERVE=0x78557598 May 14 23:49:42.227945 kernel: secureboot: Secure boot disabled May 14 23:49:42.227963 kernel: ACPI: Early table checksum verification disabled May 14 23:49:42.227978 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) May 14 23:49:42.227994 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) May 14 23:49:42.228011 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) May 14 23:49:42.228031 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) May 14 23:49:42.228047 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) May 14 23:49:42.228063 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) May 14 23:49:42.228079 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) May 14 23:49:42.228098 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) May 14 23:49:42.228119 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) May 14 23:49:42.228135 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) May 14 23:49:42.228152 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) May 14 23:49:42.228168 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 May 14 23:49:42.228185 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') May 14 23:49:42.228201 kernel: printk: bootconsole [uart0] enabled May 14 23:49:42.228218 kernel: NUMA: Failed to initialise from firmware May 14 23:49:42.228234 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] May 14 23:49:42.228250 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] May 14 23:49:42.228267 kernel: Zone ranges: May 14 23:49:42.228283 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] May 14 23:49:42.228303 kernel: DMA32 empty May 14 23:49:42.228320 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] May 14 23:49:42.228336 kernel: Movable zone start for each node May 14 23:49:42.228352 kernel: Early memory node ranges May 14 23:49:42.228368 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] May 14 23:49:42.228384 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] May 14 23:49:42.228400 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] May 14 23:49:42.228417 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] May 14 23:49:42.228434 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] May 14 23:49:42.233866 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] May 14 23:49:42.233908 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] May 14 23:49:42.233928 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] May 14 23:49:42.233957 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] May 14 23:49:42.233975 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges May 14 23:49:42.233999 kernel: psci: probing for conduit method from ACPI. May 14 23:49:42.234017 kernel: psci: PSCIv1.0 detected in firmware. May 14 23:49:42.234034 kernel: psci: Using standard PSCI v0.2 function IDs May 14 23:49:42.234055 kernel: psci: Trusted OS migration not required May 14 23:49:42.234073 kernel: psci: SMC Calling Convention v1.1 May 14 23:49:42.234090 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 14 23:49:42.234107 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 14 23:49:42.234125 kernel: pcpu-alloc: [0] 0 [0] 1 May 14 23:49:42.234142 kernel: Detected PIPT I-cache on CPU0 May 14 23:49:42.234159 kernel: CPU features: detected: GIC system register CPU interface May 14 23:49:42.234176 kernel: CPU features: detected: Spectre-v2 May 14 23:49:42.234193 kernel: CPU features: detected: Spectre-v3a May 14 23:49:42.234210 kernel: CPU features: detected: Spectre-BHB May 14 23:49:42.234227 kernel: CPU features: detected: ARM erratum 1742098 May 14 23:49:42.234244 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 May 14 23:49:42.234265 kernel: alternatives: applying boot alternatives May 14 23:49:42.234285 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=bfa141d6f8686d8fe96245516ecbaee60c938beef41636c397e3939a2c9a6ed9 May 14 23:49:42.234304 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 14 23:49:42.234322 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 14 23:49:42.234339 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 14 23:49:42.234356 kernel: Fallback order for Node 0: 0 May 14 23:49:42.234374 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 May 14 23:49:42.234391 kernel: Policy zone: Normal May 14 23:49:42.234408 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 14 23:49:42.234424 kernel: software IO TLB: area num 2. May 14 23:49:42.234446 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) May 14 23:49:42.234515 kernel: Memory: 3821176K/4030464K available (10368K kernel code, 2186K rwdata, 8100K rodata, 38336K init, 897K bss, 209288K reserved, 0K cma-reserved) May 14 23:49:42.234534 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 14 23:49:42.234551 kernel: rcu: Preemptible hierarchical RCU implementation. May 14 23:49:42.234570 kernel: rcu: RCU event tracing is enabled. May 14 23:49:42.234588 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 14 23:49:42.234606 kernel: Trampoline variant of Tasks RCU enabled. May 14 23:49:42.234637 kernel: Tracing variant of Tasks RCU enabled. May 14 23:49:42.234661 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 14 23:49:42.234679 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 14 23:49:42.234696 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 14 23:49:42.234723 kernel: GICv3: 96 SPIs implemented May 14 23:49:42.234741 kernel: GICv3: 0 Extended SPIs implemented May 14 23:49:42.234758 kernel: Root IRQ handler: gic_handle_irq May 14 23:49:42.234775 kernel: GICv3: GICv3 features: 16 PPIs May 14 23:49:42.234792 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 May 14 23:49:42.234809 kernel: ITS [mem 0x10080000-0x1009ffff] May 14 23:49:42.234827 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) May 14 23:49:42.234845 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) May 14 23:49:42.234863 kernel: GICv3: using LPI property table @0x00000004000d0000 May 14 23:49:42.234880 kernel: ITS: Using hypervisor restricted LPI range [128] May 14 23:49:42.234897 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 May 14 23:49:42.234914 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 14 23:49:42.234936 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). May 14 23:49:42.234954 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns May 14 23:49:42.234971 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns May 14 23:49:42.234989 kernel: Console: colour dummy device 80x25 May 14 23:49:42.235006 kernel: printk: console [tty1] enabled May 14 23:49:42.235024 kernel: ACPI: Core revision 20230628 May 14 23:49:42.235041 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) May 14 23:49:42.235059 kernel: pid_max: default: 32768 minimum: 301 May 14 23:49:42.235077 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 14 23:49:42.235099 kernel: landlock: Up and running. May 14 23:49:42.235118 kernel: SELinux: Initializing. May 14 23:49:42.235136 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 23:49:42.235154 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 23:49:42.235172 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 14 23:49:42.235190 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 14 23:49:42.235209 kernel: rcu: Hierarchical SRCU implementation. May 14 23:49:42.235227 kernel: rcu: Max phase no-delay instances is 400. May 14 23:49:42.235245 kernel: Platform MSI: ITS@0x10080000 domain created May 14 23:49:42.235266 kernel: PCI/MSI: ITS@0x10080000 domain created May 14 23:49:42.235284 kernel: Remapping and enabling EFI services. May 14 23:49:42.235302 kernel: smp: Bringing up secondary CPUs ... May 14 23:49:42.235320 kernel: Detected PIPT I-cache on CPU1 May 14 23:49:42.235338 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 May 14 23:49:42.235356 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 May 14 23:49:42.235373 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] May 14 23:49:42.235391 kernel: smp: Brought up 1 node, 2 CPUs May 14 23:49:42.235408 kernel: SMP: Total of 2 processors activated. May 14 23:49:42.235430 kernel: CPU features: detected: 32-bit EL0 Support May 14 23:49:42.237515 kernel: CPU features: detected: 32-bit EL1 Support May 14 23:49:42.237567 kernel: CPU features: detected: CRC32 instructions May 14 23:49:42.237606 kernel: CPU: All CPU(s) started at EL1 May 14 23:49:42.237631 kernel: alternatives: applying system-wide alternatives May 14 23:49:42.237651 kernel: devtmpfs: initialized May 14 23:49:42.237671 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 14 23:49:42.237690 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 14 23:49:42.237710 kernel: pinctrl core: initialized pinctrl subsystem May 14 23:49:42.237729 kernel: SMBIOS 3.0.0 present. May 14 23:49:42.237752 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 May 14 23:49:42.237772 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 14 23:49:42.237791 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 14 23:49:42.237811 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 14 23:49:42.237830 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 14 23:49:42.237850 kernel: audit: initializing netlink subsys (disabled) May 14 23:49:42.237870 kernel: audit: type=2000 audit(0.232:1): state=initialized audit_enabled=0 res=1 May 14 23:49:42.237894 kernel: thermal_sys: Registered thermal governor 'step_wise' May 14 23:49:42.237913 kernel: cpuidle: using governor menu May 14 23:49:42.237933 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 14 23:49:42.237952 kernel: ASID allocator initialised with 65536 entries May 14 23:49:42.237971 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 14 23:49:42.237990 kernel: Serial: AMBA PL011 UART driver May 14 23:49:42.238009 kernel: Modules: 17744 pages in range for non-PLT usage May 14 23:49:42.238029 kernel: Modules: 509264 pages in range for PLT usage May 14 23:49:42.238049 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 14 23:49:42.238075 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 14 23:49:42.238096 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 14 23:49:42.238115 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 14 23:49:42.238134 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 14 23:49:42.238153 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 14 23:49:42.238172 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 14 23:49:42.238191 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 14 23:49:42.238210 kernel: ACPI: Added _OSI(Module Device) May 14 23:49:42.238230 kernel: ACPI: Added _OSI(Processor Device) May 14 23:49:42.238257 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 14 23:49:42.238278 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 14 23:49:42.238300 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 14 23:49:42.238321 kernel: ACPI: Interpreter enabled May 14 23:49:42.238341 kernel: ACPI: Using GIC for interrupt routing May 14 23:49:42.238363 kernel: ACPI: MCFG table detected, 1 entries May 14 23:49:42.238383 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) May 14 23:49:42.238842 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 14 23:49:42.239154 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 14 23:49:42.239401 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 14 23:49:42.243782 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 May 14 23:49:42.244009 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] May 14 23:49:42.244036 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] May 14 23:49:42.244058 kernel: acpiphp: Slot [1] registered May 14 23:49:42.244077 kernel: acpiphp: Slot [2] registered May 14 23:49:42.244095 kernel: acpiphp: Slot [3] registered May 14 23:49:42.244126 kernel: acpiphp: Slot [4] registered May 14 23:49:42.244145 kernel: acpiphp: Slot [5] registered May 14 23:49:42.244163 kernel: acpiphp: Slot [6] registered May 14 23:49:42.244181 kernel: acpiphp: Slot [7] registered May 14 23:49:42.244199 kernel: acpiphp: Slot [8] registered May 14 23:49:42.244217 kernel: acpiphp: Slot [9] registered May 14 23:49:42.244235 kernel: acpiphp: Slot [10] registered May 14 23:49:42.244253 kernel: acpiphp: Slot [11] registered May 14 23:49:42.244271 kernel: acpiphp: Slot [12] registered May 14 23:49:42.244289 kernel: acpiphp: Slot [13] registered May 14 23:49:42.244312 kernel: acpiphp: Slot [14] registered May 14 23:49:42.244330 kernel: acpiphp: Slot [15] registered May 14 23:49:42.244348 kernel: acpiphp: Slot [16] registered May 14 23:49:42.244366 kernel: acpiphp: Slot [17] registered May 14 23:49:42.244384 kernel: acpiphp: Slot [18] registered May 14 23:49:42.244402 kernel: acpiphp: Slot [19] registered May 14 23:49:42.244420 kernel: acpiphp: Slot [20] registered May 14 23:49:42.244438 kernel: acpiphp: Slot [21] registered May 14 23:49:42.244497 kernel: acpiphp: Slot [22] registered May 14 23:49:42.244526 kernel: acpiphp: Slot [23] registered May 14 23:49:42.244546 kernel: acpiphp: Slot [24] registered May 14 23:49:42.244565 kernel: acpiphp: Slot [25] registered May 14 23:49:42.244585 kernel: acpiphp: Slot [26] registered May 14 23:49:42.244603 kernel: acpiphp: Slot [27] registered May 14 23:49:42.244623 kernel: acpiphp: Slot [28] registered May 14 23:49:42.244641 kernel: acpiphp: Slot [29] registered May 14 23:49:42.244659 kernel: acpiphp: Slot [30] registered May 14 23:49:42.244679 kernel: acpiphp: Slot [31] registered May 14 23:49:42.244697 kernel: PCI host bridge to bus 0000:00 May 14 23:49:42.244942 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] May 14 23:49:42.245132 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 14 23:49:42.245314 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] May 14 23:49:42.249135 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] May 14 23:49:42.249432 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 May 14 23:49:42.249751 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 May 14 23:49:42.250020 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] May 14 23:49:42.250299 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 May 14 23:49:42.250588 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] May 14 23:49:42.250881 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold May 14 23:49:42.251155 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 May 14 23:49:42.251393 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] May 14 23:49:42.251783 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] May 14 23:49:42.252024 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] May 14 23:49:42.252294 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold May 14 23:49:42.252557 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] May 14 23:49:42.252774 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] May 14 23:49:42.253004 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] May 14 23:49:42.253229 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] May 14 23:49:42.256677 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] May 14 23:49:42.256990 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] May 14 23:49:42.257192 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 14 23:49:42.257386 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] May 14 23:49:42.257414 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 14 23:49:42.257434 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 14 23:49:42.257481 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 14 23:49:42.257503 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 14 23:49:42.257523 kernel: iommu: Default domain type: Translated May 14 23:49:42.257554 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 14 23:49:42.257573 kernel: efivars: Registered efivars operations May 14 23:49:42.257592 kernel: vgaarb: loaded May 14 23:49:42.257611 kernel: clocksource: Switched to clocksource arch_sys_counter May 14 23:49:42.257630 kernel: VFS: Disk quotas dquot_6.6.0 May 14 23:49:42.257648 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 14 23:49:42.257667 kernel: pnp: PnP ACPI init May 14 23:49:42.257928 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved May 14 23:49:42.257966 kernel: pnp: PnP ACPI: found 1 devices May 14 23:49:42.257985 kernel: NET: Registered PF_INET protocol family May 14 23:49:42.258003 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 14 23:49:42.258022 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 14 23:49:42.258042 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 14 23:49:42.258061 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 14 23:49:42.258079 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 14 23:49:42.258098 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 14 23:49:42.258116 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 23:49:42.258139 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 23:49:42.258158 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 14 23:49:42.258176 kernel: PCI: CLS 0 bytes, default 64 May 14 23:49:42.258194 kernel: kvm [1]: HYP mode not available May 14 23:49:42.258213 kernel: Initialise system trusted keyrings May 14 23:49:42.258231 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 14 23:49:42.258250 kernel: Key type asymmetric registered May 14 23:49:42.258268 kernel: Asymmetric key parser 'x509' registered May 14 23:49:42.258286 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 14 23:49:42.258310 kernel: io scheduler mq-deadline registered May 14 23:49:42.258328 kernel: io scheduler kyber registered May 14 23:49:42.258347 kernel: io scheduler bfq registered May 14 23:49:42.263129 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered May 14 23:49:42.263181 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 14 23:49:42.263202 kernel: ACPI: button: Power Button [PWRB] May 14 23:49:42.263221 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 May 14 23:49:42.263242 kernel: ACPI: button: Sleep Button [SLPB] May 14 23:49:42.263270 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 14 23:49:42.263290 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 May 14 23:49:42.263670 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) May 14 23:49:42.263711 kernel: printk: console [ttyS0] disabled May 14 23:49:42.263731 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A May 14 23:49:42.263750 kernel: printk: console [ttyS0] enabled May 14 23:49:42.263768 kernel: printk: bootconsole [uart0] disabled May 14 23:49:42.263786 kernel: thunder_xcv, ver 1.0 May 14 23:49:42.263805 kernel: thunder_bgx, ver 1.0 May 14 23:49:42.263833 kernel: nicpf, ver 1.0 May 14 23:49:42.263852 kernel: nicvf, ver 1.0 May 14 23:49:42.264098 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 14 23:49:42.264300 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-14T23:49:41 UTC (1747266581) May 14 23:49:42.264326 kernel: hid: raw HID events driver (C) Jiri Kosina May 14 23:49:42.264345 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available May 14 23:49:42.264364 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 14 23:49:42.264382 kernel: watchdog: Hard watchdog permanently disabled May 14 23:49:42.264409 kernel: NET: Registered PF_INET6 protocol family May 14 23:49:42.264427 kernel: Segment Routing with IPv6 May 14 23:49:42.264446 kernel: In-situ OAM (IOAM) with IPv6 May 14 23:49:42.264514 kernel: NET: Registered PF_PACKET protocol family May 14 23:49:42.264534 kernel: Key type dns_resolver registered May 14 23:49:42.264553 kernel: registered taskstats version 1 May 14 23:49:42.264572 kernel: Loading compiled-in X.509 certificates May 14 23:49:42.264591 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: cdb7ce3984a1665183e8a6ab3419833bc5e4e7f4' May 14 23:49:42.264610 kernel: Key type .fscrypt registered May 14 23:49:42.264636 kernel: Key type fscrypt-provisioning registered May 14 23:49:42.264657 kernel: ima: No TPM chip found, activating TPM-bypass! May 14 23:49:42.264676 kernel: ima: Allocated hash algorithm: sha1 May 14 23:49:42.264695 kernel: ima: No architecture policies found May 14 23:49:42.264715 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 14 23:49:42.264734 kernel: clk: Disabling unused clocks May 14 23:49:42.264753 kernel: Freeing unused kernel memory: 38336K May 14 23:49:42.264772 kernel: Run /init as init process May 14 23:49:42.264791 kernel: with arguments: May 14 23:49:42.264810 kernel: /init May 14 23:49:42.264834 kernel: with environment: May 14 23:49:42.264853 kernel: HOME=/ May 14 23:49:42.264872 kernel: TERM=linux May 14 23:49:42.264892 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 14 23:49:42.264915 systemd[1]: Successfully made /usr/ read-only. May 14 23:49:42.264942 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 14 23:49:42.264965 systemd[1]: Detected virtualization amazon. May 14 23:49:42.264991 systemd[1]: Detected architecture arm64. May 14 23:49:42.265013 systemd[1]: Running in initrd. May 14 23:49:42.265038 systemd[1]: No hostname configured, using default hostname. May 14 23:49:42.265061 systemd[1]: Hostname set to . May 14 23:49:42.265082 systemd[1]: Initializing machine ID from VM UUID. May 14 23:49:42.265104 systemd[1]: Queued start job for default target initrd.target. May 14 23:49:42.265126 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:49:42.265148 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:49:42.265178 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 14 23:49:42.265203 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 23:49:42.265224 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 14 23:49:42.265247 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 14 23:49:42.265272 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 14 23:49:42.265293 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 14 23:49:42.265315 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:49:42.265342 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 23:49:42.265363 systemd[1]: Reached target paths.target - Path Units. May 14 23:49:42.265384 systemd[1]: Reached target slices.target - Slice Units. May 14 23:49:42.265404 systemd[1]: Reached target swap.target - Swaps. May 14 23:49:42.265425 systemd[1]: Reached target timers.target - Timer Units. May 14 23:49:42.265544 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 14 23:49:42.265582 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 23:49:42.265603 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 14 23:49:42.265624 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 14 23:49:42.265654 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 23:49:42.265674 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 23:49:42.265695 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:49:42.265715 systemd[1]: Reached target sockets.target - Socket Units. May 14 23:49:42.265735 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 14 23:49:42.265756 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 23:49:42.265776 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 14 23:49:42.265796 systemd[1]: Starting systemd-fsck-usr.service... May 14 23:49:42.265821 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 23:49:42.265842 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 23:49:42.265863 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:49:42.265883 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 14 23:49:42.265903 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:49:42.265926 systemd[1]: Finished systemd-fsck-usr.service. May 14 23:49:42.266016 systemd-journald[251]: Collecting audit messages is disabled. May 14 23:49:42.266062 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 23:49:42.266084 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 23:49:42.266110 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 23:49:42.266131 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:49:42.266151 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 14 23:49:42.266172 systemd-journald[251]: Journal started May 14 23:49:42.266210 systemd-journald[251]: Runtime Journal (/run/log/journal/ec2b0092edc7383be82a716f58e91122) is 8M, max 75.3M, 67.3M free. May 14 23:49:42.207986 systemd-modules-load[252]: Inserted module 'overlay' May 14 23:49:42.271507 kernel: Bridge firewalling registered May 14 23:49:42.271525 systemd-modules-load[252]: Inserted module 'br_netfilter' May 14 23:49:42.282913 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:49:42.286436 systemd[1]: Started systemd-journald.service - Journal Service. May 14 23:49:42.287357 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 23:49:42.292254 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:49:42.309972 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 23:49:42.318659 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 23:49:42.344555 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:49:42.355801 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 14 23:49:42.363526 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 23:49:42.368296 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:49:42.397109 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 23:49:42.421792 dracut-cmdline[286]: dracut-dracut-053 May 14 23:49:42.431512 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=bfa141d6f8686d8fe96245516ecbaee60c938beef41636c397e3939a2c9a6ed9 May 14 23:49:42.481384 systemd-resolved[290]: Positive Trust Anchors: May 14 23:49:42.481423 systemd-resolved[290]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 23:49:42.481556 systemd-resolved[290]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 14 23:49:42.615505 kernel: SCSI subsystem initialized May 14 23:49:42.624485 kernel: Loading iSCSI transport class v2.0-870. May 14 23:49:42.636499 kernel: iscsi: registered transport (tcp) May 14 23:49:42.660215 kernel: iscsi: registered transport (qla4xxx) May 14 23:49:42.660295 kernel: QLogic iSCSI HBA Driver May 14 23:49:42.732489 kernel: random: crng init done May 14 23:49:42.732841 systemd-resolved[290]: Defaulting to hostname 'linux'. May 14 23:49:42.736718 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 23:49:42.741758 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 23:49:42.771557 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 14 23:49:42.783810 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 14 23:49:42.813217 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 14 23:49:42.813310 kernel: device-mapper: uevent: version 1.0.3 May 14 23:49:42.813338 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 14 23:49:42.882517 kernel: raid6: neonx8 gen() 6520 MB/s May 14 23:49:42.899524 kernel: raid6: neonx4 gen() 6468 MB/s May 14 23:49:42.916520 kernel: raid6: neonx2 gen() 5414 MB/s May 14 23:49:42.933518 kernel: raid6: neonx1 gen() 3890 MB/s May 14 23:49:42.950514 kernel: raid6: int64x8 gen() 3613 MB/s May 14 23:49:42.967517 kernel: raid6: int64x4 gen() 3689 MB/s May 14 23:49:42.984508 kernel: raid6: int64x2 gen() 3599 MB/s May 14 23:49:43.002421 kernel: raid6: int64x1 gen() 2734 MB/s May 14 23:49:43.002544 kernel: raid6: using algorithm neonx8 gen() 6520 MB/s May 14 23:49:43.020395 kernel: raid6: .... xor() 4642 MB/s, rmw enabled May 14 23:49:43.020498 kernel: raid6: using neon recovery algorithm May 14 23:49:43.029334 kernel: xor: measuring software checksum speed May 14 23:49:43.029414 kernel: 8regs : 12967 MB/sec May 14 23:49:43.030506 kernel: 32regs : 11946 MB/sec May 14 23:49:43.032710 kernel: arm64_neon : 8236 MB/sec May 14 23:49:43.032804 kernel: xor: using function: 8regs (12967 MB/sec) May 14 23:49:43.120523 kernel: Btrfs loaded, zoned=no, fsverity=no May 14 23:49:43.142439 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 14 23:49:43.155789 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:49:43.204406 systemd-udevd[473]: Using default interface naming scheme 'v255'. May 14 23:49:43.216171 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:49:43.228838 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 14 23:49:43.271444 dracut-pre-trigger[478]: rd.md=0: removing MD RAID activation May 14 23:49:43.338312 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 14 23:49:43.350857 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 23:49:43.479892 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:49:43.501951 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 14 23:49:43.540902 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 14 23:49:43.547616 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 14 23:49:43.552674 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:49:43.555051 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 23:49:43.571870 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 14 23:49:43.617727 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 14 23:49:43.709177 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 14 23:49:43.709248 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) May 14 23:49:43.717119 kernel: ena 0000:00:05.0: ENA device version: 0.10 May 14 23:49:43.717528 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 May 14 23:49:43.727537 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:fd:9c:25:2a:11 May 14 23:49:43.733222 (udev-worker)[528]: Network interface NamePolicy= disabled on kernel command line. May 14 23:49:43.745553 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 23:49:43.748596 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:49:43.752082 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:49:43.754426 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 23:49:43.754614 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:49:43.757892 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:49:43.784927 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:49:43.788075 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 23:49:43.815505 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 May 14 23:49:43.818851 kernel: nvme nvme0: pci function 0000:00:04.0 May 14 23:49:43.819301 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:49:43.830337 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 23:49:43.839436 kernel: nvme nvme0: 2/0/0 default/read/poll queues May 14 23:49:43.846768 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 14 23:49:43.848094 kernel: GPT:9289727 != 16777215 May 14 23:49:43.848123 kernel: GPT:Alternate GPT header not at the end of the disk. May 14 23:49:43.848148 kernel: GPT:9289727 != 16777215 May 14 23:49:43.850569 kernel: GPT: Use GNU Parted to correct GPT errors. May 14 23:49:43.850682 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 14 23:49:43.882986 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:49:43.983327 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (527) May 14 23:49:44.001505 kernel: BTRFS: device fsid 369506fd-904a-45c2-a4ab-2d03e7866799 devid 1 transid 44 /dev/nvme0n1p3 scanned by (udev-worker) (537) May 14 23:49:44.085940 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. May 14 23:49:44.130760 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. May 14 23:49:44.170494 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 14 23:49:44.192725 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. May 14 23:49:44.195493 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. May 14 23:49:44.216768 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 14 23:49:44.230687 disk-uuid[662]: Primary Header is updated. May 14 23:49:44.230687 disk-uuid[662]: Secondary Entries is updated. May 14 23:49:44.230687 disk-uuid[662]: Secondary Header is updated. May 14 23:49:44.243497 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 14 23:49:45.259525 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 14 23:49:45.264758 disk-uuid[663]: The operation has completed successfully. May 14 23:49:45.471150 systemd[1]: disk-uuid.service: Deactivated successfully. May 14 23:49:45.473208 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 14 23:49:45.580761 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 14 23:49:45.592497 sh[923]: Success May 14 23:49:45.618528 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 14 23:49:45.736653 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 14 23:49:45.753711 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 14 23:49:45.760147 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 14 23:49:45.795960 kernel: BTRFS info (device dm-0): first mount of filesystem 369506fd-904a-45c2-a4ab-2d03e7866799 May 14 23:49:45.796039 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 14 23:49:45.797891 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 14 23:49:45.799351 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 14 23:49:45.800526 kernel: BTRFS info (device dm-0): using free space tree May 14 23:49:45.886490 kernel: BTRFS info (device dm-0): enabling ssd optimizations May 14 23:49:45.903888 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 14 23:49:45.908359 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 14 23:49:45.920715 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 14 23:49:45.926679 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 14 23:49:45.975532 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 02f9d4a0-2ee9-4834-b15d-b55399b9ff01 May 14 23:49:45.975623 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 14 23:49:45.977488 kernel: BTRFS info (device nvme0n1p6): using free space tree May 14 23:49:45.984683 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 14 23:49:45.993592 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 02f9d4a0-2ee9-4834-b15d-b55399b9ff01 May 14 23:49:45.997038 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 14 23:49:46.004799 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 14 23:49:46.122139 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 23:49:46.136921 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 23:49:46.206132 systemd-networkd[1113]: lo: Link UP May 14 23:49:46.206157 systemd-networkd[1113]: lo: Gained carrier May 14 23:49:46.212614 systemd-networkd[1113]: Enumeration completed May 14 23:49:46.214283 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 23:49:46.218875 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:49:46.218896 systemd-networkd[1113]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 23:49:46.218901 systemd[1]: Reached target network.target - Network. May 14 23:49:46.230357 systemd-networkd[1113]: eth0: Link UP May 14 23:49:46.230378 systemd-networkd[1113]: eth0: Gained carrier May 14 23:49:46.230396 systemd-networkd[1113]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:49:46.247585 systemd-networkd[1113]: eth0: DHCPv4 address 172.31.21.175/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 14 23:49:46.352077 ignition[1027]: Ignition 2.20.0 May 14 23:49:46.353661 ignition[1027]: Stage: fetch-offline May 14 23:49:46.354210 ignition[1027]: no configs at "/usr/lib/ignition/base.d" May 14 23:49:46.354236 ignition[1027]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:46.354867 ignition[1027]: Ignition finished successfully May 14 23:49:46.365531 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 14 23:49:46.379755 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 14 23:49:46.404329 ignition[1123]: Ignition 2.20.0 May 14 23:49:46.404353 ignition[1123]: Stage: fetch May 14 23:49:46.406216 ignition[1123]: no configs at "/usr/lib/ignition/base.d" May 14 23:49:46.406256 ignition[1123]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:46.407568 ignition[1123]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:46.421399 ignition[1123]: PUT result: OK May 14 23:49:46.425256 ignition[1123]: parsed url from cmdline: "" May 14 23:49:46.425496 ignition[1123]: no config URL provided May 14 23:49:46.426959 ignition[1123]: reading system config file "/usr/lib/ignition/user.ign" May 14 23:49:46.427021 ignition[1123]: no config at "/usr/lib/ignition/user.ign" May 14 23:49:46.427093 ignition[1123]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:46.431074 ignition[1123]: PUT result: OK May 14 23:49:46.431193 ignition[1123]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 May 14 23:49:46.436580 ignition[1123]: GET result: OK May 14 23:49:46.436698 ignition[1123]: parsing config with SHA512: 00eda1a2bd6516f2f992bb3c8e33547d4d3898d93ee9dc16bd8af0c8ae32369bb886a3ed55056a94aa2c1cfceec9d008b2f58d09e10958316ae37c4bd37caae9 May 14 23:49:46.458500 unknown[1123]: fetched base config from "system" May 14 23:49:46.458531 unknown[1123]: fetched base config from "system" May 14 23:49:46.459026 ignition[1123]: fetch: fetch complete May 14 23:49:46.458547 unknown[1123]: fetched user config from "aws" May 14 23:49:46.459041 ignition[1123]: fetch: fetch passed May 14 23:49:46.467774 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 14 23:49:46.459164 ignition[1123]: Ignition finished successfully May 14 23:49:46.480844 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 14 23:49:46.516759 ignition[1129]: Ignition 2.20.0 May 14 23:49:46.516789 ignition[1129]: Stage: kargs May 14 23:49:46.518438 ignition[1129]: no configs at "/usr/lib/ignition/base.d" May 14 23:49:46.518490 ignition[1129]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:46.518883 ignition[1129]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:46.524290 ignition[1129]: PUT result: OK May 14 23:49:46.531129 ignition[1129]: kargs: kargs passed May 14 23:49:46.531277 ignition[1129]: Ignition finished successfully May 14 23:49:46.536703 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 14 23:49:46.547763 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 14 23:49:46.582941 ignition[1135]: Ignition 2.20.0 May 14 23:49:46.582976 ignition[1135]: Stage: disks May 14 23:49:46.584367 ignition[1135]: no configs at "/usr/lib/ignition/base.d" May 14 23:49:46.584398 ignition[1135]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:46.584628 ignition[1135]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:46.586740 ignition[1135]: PUT result: OK May 14 23:49:46.597118 ignition[1135]: disks: disks passed May 14 23:49:46.597548 ignition[1135]: Ignition finished successfully May 14 23:49:46.602792 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 14 23:49:46.606226 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 14 23:49:46.611236 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 14 23:49:46.613968 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 23:49:46.614085 systemd[1]: Reached target sysinit.target - System Initialization. May 14 23:49:46.614881 systemd[1]: Reached target basic.target - Basic System. May 14 23:49:46.637787 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 14 23:49:46.689097 systemd-fsck[1143]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 14 23:49:46.694099 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 14 23:49:46.711113 systemd[1]: Mounting sysroot.mount - /sysroot... May 14 23:49:46.811498 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 737cda88-7069-47ce-b2bc-d891099a68fb r/w with ordered data mode. Quota mode: none. May 14 23:49:46.813324 systemd[1]: Mounted sysroot.mount - /sysroot. May 14 23:49:46.817647 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 14 23:49:46.837626 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 23:49:46.844670 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 14 23:49:46.847011 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 14 23:49:46.847108 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 14 23:49:46.847167 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 14 23:49:46.874479 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1162) May 14 23:49:46.879063 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 02f9d4a0-2ee9-4834-b15d-b55399b9ff01 May 14 23:49:46.879142 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 14 23:49:46.881366 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 14 23:49:46.884737 kernel: BTRFS info (device nvme0n1p6): using free space tree May 14 23:49:46.894753 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 14 23:49:46.902525 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 14 23:49:46.906565 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 23:49:47.187965 initrd-setup-root[1186]: cut: /sysroot/etc/passwd: No such file or directory May 14 23:49:47.197285 initrd-setup-root[1193]: cut: /sysroot/etc/group: No such file or directory May 14 23:49:47.205508 initrd-setup-root[1200]: cut: /sysroot/etc/shadow: No such file or directory May 14 23:49:47.214263 initrd-setup-root[1207]: cut: /sysroot/etc/gshadow: No such file or directory May 14 23:49:47.401837 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 14 23:49:47.411683 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 14 23:49:47.423814 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 14 23:49:47.441016 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 14 23:49:47.445892 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 02f9d4a0-2ee9-4834-b15d-b55399b9ff01 May 14 23:49:47.483168 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 14 23:49:47.490506 ignition[1275]: INFO : Ignition 2.20.0 May 14 23:49:47.490506 ignition[1275]: INFO : Stage: mount May 14 23:49:47.495431 ignition[1275]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:49:47.495431 ignition[1275]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:47.495431 ignition[1275]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:47.495431 ignition[1275]: INFO : PUT result: OK May 14 23:49:47.510659 ignition[1275]: INFO : mount: mount passed May 14 23:49:47.510659 ignition[1275]: INFO : Ignition finished successfully May 14 23:49:47.506912 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 14 23:49:47.525766 systemd[1]: Starting ignition-files.service - Ignition (files)... May 14 23:49:47.822914 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 23:49:47.854508 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1287) May 14 23:49:47.858126 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 02f9d4a0-2ee9-4834-b15d-b55399b9ff01 May 14 23:49:47.858200 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 14 23:49:47.858226 kernel: BTRFS info (device nvme0n1p6): using free space tree May 14 23:49:47.864490 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 14 23:49:47.868533 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 23:49:47.902906 ignition[1304]: INFO : Ignition 2.20.0 May 14 23:49:47.902906 ignition[1304]: INFO : Stage: files May 14 23:49:47.906365 ignition[1304]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:49:47.906365 ignition[1304]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:47.911225 ignition[1304]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:47.913717 ignition[1304]: INFO : PUT result: OK May 14 23:49:47.919064 ignition[1304]: DEBUG : files: compiled without relabeling support, skipping May 14 23:49:47.922998 ignition[1304]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 14 23:49:47.922998 ignition[1304]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 14 23:49:47.933682 systemd-networkd[1113]: eth0: Gained IPv6LL May 14 23:49:47.936598 ignition[1304]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 14 23:49:47.939394 ignition[1304]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 14 23:49:47.942637 unknown[1304]: wrote ssh authorized keys file for user: core May 14 23:49:47.945236 ignition[1304]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 14 23:49:47.949935 ignition[1304]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 14 23:49:47.953494 ignition[1304]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 14 23:49:47.953494 ignition[1304]: INFO : files: op(4): [started] processing unit "flannel-docker-opts.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(4): op(5): [started] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "retry.conf" at "/sysroot/etc/systemd/system/flannel-docker-opts.service.d/retry.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(4): [finished] processing unit "flannel-docker-opts.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(6): [started] processing unit "docker.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(6): [finished] processing unit "docker.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(7): [started] processing unit "flanneld.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(7): op(8): [started] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(7): op(8): [finished] writing systemd drop-in "50-network-config.conf" at "/sysroot/etc/systemd/system/flanneld.service.d/50-network-config.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(7): [finished] processing unit "flanneld.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(9): [started] processing unit "etcd-member.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(9): op(a): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(9): op(a): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(9): [finished] processing unit "etcd-member.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(b): [started] setting preset to enabled for "docker.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(b): [finished] setting preset to enabled for "docker.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(c): [started] setting preset to enabled for "etcd-member.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(c): [finished] setting preset to enabled for "etcd-member.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(d): [started] setting preset to enabled for "flanneld.service" May 14 23:49:47.960337 ignition[1304]: INFO : files: op(d): [finished] setting preset to enabled for "flanneld.service" May 14 23:49:48.029742 ignition[1304]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" May 14 23:49:48.029742 ignition[1304]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" May 14 23:49:48.029742 ignition[1304]: INFO : files: files passed May 14 23:49:48.029742 ignition[1304]: INFO : Ignition finished successfully May 14 23:49:48.003437 systemd[1]: Finished ignition-files.service - Ignition (files). May 14 23:49:48.031958 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 14 23:49:48.041279 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 14 23:49:48.064026 systemd[1]: ignition-quench.service: Deactivated successfully. May 14 23:49:48.064230 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 14 23:49:48.097089 initrd-setup-root-after-ignition[1332]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 23:49:48.097089 initrd-setup-root-after-ignition[1332]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 14 23:49:48.106021 initrd-setup-root-after-ignition[1336]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 23:49:48.111560 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 23:49:48.114844 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 14 23:49:48.128814 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 14 23:49:48.192354 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 14 23:49:48.193369 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 14 23:49:48.198203 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 14 23:49:48.201427 systemd[1]: Reached target initrd.target - Initrd Default Target. May 14 23:49:48.203854 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 14 23:49:48.219486 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 14 23:49:48.249528 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 23:49:48.263738 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 14 23:49:48.288935 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 14 23:49:48.293709 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:49:48.298446 systemd[1]: Stopped target timers.target - Timer Units. May 14 23:49:48.302127 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 14 23:49:48.303859 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 23:49:48.307002 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 14 23:49:48.309333 systemd[1]: Stopped target basic.target - Basic System. May 14 23:49:48.311409 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 14 23:49:48.314577 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 14 23:49:48.326005 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 14 23:49:48.330391 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 14 23:49:48.332710 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 14 23:49:48.335895 systemd[1]: Stopped target sysinit.target - System Initialization. May 14 23:49:48.343898 systemd[1]: Stopped target local-fs.target - Local File Systems. May 14 23:49:48.348605 systemd[1]: Stopped target swap.target - Swaps. May 14 23:49:48.350399 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 14 23:49:48.350713 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 14 23:49:48.356916 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 14 23:49:48.363240 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:49:48.366221 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 14 23:49:48.374768 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:49:48.377288 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 14 23:49:48.377573 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 14 23:49:48.386302 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 14 23:49:48.386656 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 23:49:48.391486 systemd[1]: ignition-files.service: Deactivated successfully. May 14 23:49:48.391775 systemd[1]: Stopped ignition-files.service - Ignition (files). May 14 23:49:48.408907 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 14 23:49:48.410843 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 14 23:49:48.411287 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:49:48.421223 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 14 23:49:48.425949 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 14 23:49:48.428479 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:49:48.434088 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 14 23:49:48.436808 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 14 23:49:48.452637 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 14 23:49:48.455010 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 14 23:49:48.466115 ignition[1356]: INFO : Ignition 2.20.0 May 14 23:49:48.466115 ignition[1356]: INFO : Stage: umount May 14 23:49:48.478250 ignition[1356]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 23:49:48.478250 ignition[1356]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 14 23:49:48.478250 ignition[1356]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 14 23:49:48.478250 ignition[1356]: INFO : PUT result: OK May 14 23:49:48.478250 ignition[1356]: INFO : umount: umount passed May 14 23:49:48.478250 ignition[1356]: INFO : Ignition finished successfully May 14 23:49:48.482572 systemd[1]: ignition-mount.service: Deactivated successfully. May 14 23:49:48.483254 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 14 23:49:48.489338 systemd[1]: ignition-disks.service: Deactivated successfully. May 14 23:49:48.489591 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 14 23:49:48.503836 systemd[1]: ignition-kargs.service: Deactivated successfully. May 14 23:49:48.503959 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 14 23:49:48.506823 systemd[1]: ignition-fetch.service: Deactivated successfully. May 14 23:49:48.506926 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 14 23:49:48.509804 systemd[1]: Stopped target network.target - Network. May 14 23:49:48.511644 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 14 23:49:48.511770 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 14 23:49:48.514170 systemd[1]: Stopped target paths.target - Path Units. May 14 23:49:48.515958 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 14 23:49:48.521738 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:49:48.526664 systemd[1]: Stopped target slices.target - Slice Units. May 14 23:49:48.528494 systemd[1]: Stopped target sockets.target - Socket Units. May 14 23:49:48.531698 systemd[1]: iscsid.socket: Deactivated successfully. May 14 23:49:48.531789 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 14 23:49:48.533863 systemd[1]: iscsiuio.socket: Deactivated successfully. May 14 23:49:48.533950 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 23:49:48.536757 systemd[1]: ignition-setup.service: Deactivated successfully. May 14 23:49:48.536876 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 14 23:49:48.541782 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 14 23:49:48.542044 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 14 23:49:48.547613 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 14 23:49:48.578888 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 14 23:49:48.584089 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 14 23:49:48.586035 systemd[1]: systemd-networkd.service: Deactivated successfully. May 14 23:49:48.587575 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 14 23:49:48.596227 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 14 23:49:48.596793 systemd[1]: systemd-resolved.service: Deactivated successfully. May 14 23:49:48.597051 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 14 23:49:48.619086 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 14 23:49:48.620354 systemd[1]: sysroot-boot.service: Deactivated successfully. May 14 23:49:48.623138 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 14 23:49:48.637311 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 14 23:49:48.637440 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 14 23:49:48.645286 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 14 23:49:48.645404 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 14 23:49:48.673519 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 14 23:49:48.679301 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 14 23:49:48.679469 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 23:49:48.683952 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 14 23:49:48.684071 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 14 23:49:48.690726 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 14 23:49:48.690835 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 14 23:49:48.699597 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 14 23:49:48.699710 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:49:48.704156 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:49:48.725006 systemd[1]: systemd-udevd.service: Deactivated successfully. May 14 23:49:48.725489 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:49:48.735351 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 14 23:49:48.735946 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 14 23:49:48.742025 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 14 23:49:48.742111 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:49:48.744268 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 14 23:49:48.744368 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 14 23:49:48.747248 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 14 23:49:48.747353 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 14 23:49:48.750214 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 23:49:48.750302 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 23:49:48.772339 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 14 23:49:48.774437 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 14 23:49:48.774585 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:49:48.779771 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 23:49:48.779876 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:49:48.790236 systemd[1]: network-cleanup.service: Deactivated successfully. May 14 23:49:48.790484 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 14 23:49:48.806750 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 14 23:49:48.807137 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 14 23:49:48.817895 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 14 23:49:48.818014 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 14 23:49:48.818115 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 23:49:48.818203 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 14 23:49:48.818433 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 14 23:49:48.836805 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 14 23:49:48.856615 systemd[1]: Switching root. May 14 23:49:48.898833 systemd-journald[251]: Journal stopped May 14 23:49:50.908417 systemd-journald[251]: Received SIGTERM from PID 1 (systemd). May 14 23:49:50.908618 kernel: SELinux: policy capability network_peer_controls=1 May 14 23:49:50.908681 kernel: SELinux: policy capability open_perms=1 May 14 23:49:50.908726 kernel: SELinux: policy capability extended_socket_class=1 May 14 23:49:50.908759 kernel: SELinux: policy capability always_check_network=0 May 14 23:49:50.908793 kernel: SELinux: policy capability cgroup_seclabel=1 May 14 23:49:50.908825 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 14 23:49:50.908857 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 14 23:49:50.908904 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 14 23:49:50.908939 kernel: audit: type=1403 audit(1747266589.037:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 14 23:49:50.908992 systemd[1]: Successfully loaded SELinux policy in 50.205ms. May 14 23:49:50.909046 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 25.978ms. May 14 23:49:50.909082 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 14 23:49:50.909115 systemd[1]: Detected virtualization amazon. May 14 23:49:50.909148 systemd[1]: Detected architecture arm64. May 14 23:49:50.909183 systemd[1]: Detected first boot. May 14 23:49:50.909221 systemd[1]: Initializing machine ID from VM UUID. May 14 23:49:50.909257 zram_generator::config[1401]: No configuration found. May 14 23:49:50.909297 kernel: NET: Registered PF_VSOCK protocol family May 14 23:49:50.909351 systemd[1]: Populated /etc with preset unit settings. May 14 23:49:50.909388 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 14 23:49:50.909423 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 14 23:49:50.916807 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 14 23:49:50.916889 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 14 23:49:50.916928 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 14 23:49:50.916965 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 14 23:49:50.916998 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 14 23:49:50.917039 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 14 23:49:50.917073 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 14 23:49:50.917107 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 14 23:49:50.917140 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 14 23:49:50.917171 systemd[1]: Created slice user.slice - User and Session Slice. May 14 23:49:50.917214 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 23:49:50.917248 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 23:49:50.917279 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 14 23:49:50.917308 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 14 23:49:50.917344 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 14 23:49:50.917379 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 23:49:50.917410 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 14 23:49:50.917442 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 23:49:50.917512 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 14 23:49:50.917546 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 14 23:49:50.917579 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 14 23:49:50.917610 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 14 23:49:50.917647 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 23:49:50.917680 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 23:49:50.917710 systemd[1]: Reached target slices.target - Slice Units. May 14 23:49:50.917741 systemd[1]: Reached target swap.target - Swaps. May 14 23:49:50.917770 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 14 23:49:50.917803 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 14 23:49:50.917835 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 14 23:49:50.917865 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 23:49:50.917896 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 23:49:50.917931 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 23:49:50.917960 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 14 23:49:50.917991 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 14 23:49:50.918022 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 14 23:49:50.918053 systemd[1]: Mounting media.mount - External Media Directory... May 14 23:49:50.918083 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 14 23:49:50.918117 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 14 23:49:50.918146 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 14 23:49:50.918177 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). May 14 23:49:50.918213 systemd[1]: Reached target machines.target - Containers. May 14 23:49:50.918248 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 14 23:49:50.918278 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:49:50.918311 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 23:49:50.918340 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 14 23:49:50.918372 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 23:49:50.918403 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 23:49:50.918432 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 23:49:50.922653 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 14 23:49:50.922709 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 23:49:50.922747 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 14 23:49:50.922782 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 14 23:49:50.922814 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 14 23:49:50.922847 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 14 23:49:50.922880 systemd[1]: Stopped systemd-fsck-usr.service. May 14 23:49:50.922913 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 23:49:50.922952 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 23:49:50.922983 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 23:49:50.923014 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 23:49:50.923048 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 14 23:49:50.923081 kernel: ACPI: bus type drm_connector registered May 14 23:49:50.923114 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 14 23:49:50.923145 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 23:49:50.923184 systemd[1]: verity-setup.service: Deactivated successfully. May 14 23:49:50.923215 systemd[1]: Stopped verity-setup.service. May 14 23:49:50.923247 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 14 23:49:50.923281 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 14 23:49:50.923312 kernel: fuse: init (API version 7.39) May 14 23:49:50.923341 systemd[1]: Mounted media.mount - External Media Directory. May 14 23:49:50.923374 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 14 23:49:50.923410 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 14 23:49:50.923440 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 14 23:49:50.923500 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 23:49:50.923540 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 14 23:49:50.923574 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 14 23:49:50.923605 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 23:49:50.923640 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 23:49:50.923671 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 23:49:50.923699 kernel: loop: module loaded May 14 23:49:50.923727 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 23:49:50.923757 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 23:49:50.923788 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 23:49:50.923817 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 14 23:49:50.923846 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 14 23:49:50.923889 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 23:49:50.923925 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 23:49:50.923955 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 23:49:50.923988 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 23:49:50.924018 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 14 23:49:50.924049 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 23:49:50.924080 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 14 23:49:50.924110 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 14 23:49:50.924141 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 23:49:50.924180 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 23:49:50.924214 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 14 23:49:50.924247 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 14 23:49:50.924281 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 14 23:49:50.924319 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 14 23:49:50.924351 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 23:49:50.924382 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 14 23:49:50.924412 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 14 23:49:50.933565 systemd-journald[1480]: Collecting audit messages is disabled. May 14 23:49:50.933661 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 14 23:49:50.933700 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:49:50.933733 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 14 23:49:50.933774 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 23:49:50.933807 systemd-journald[1480]: Journal started May 14 23:49:50.933857 systemd-journald[1480]: Runtime Journal (/run/log/journal/ec2b0092edc7383be82a716f58e91122) is 8M, max 75.3M, 67.3M free. May 14 23:49:50.182817 systemd[1]: Queued start job for default target multi-user.target. May 14 23:49:50.199397 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. May 14 23:49:50.200331 systemd[1]: systemd-journald.service: Deactivated successfully. May 14 23:49:50.946311 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 14 23:49:50.953811 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 14 23:49:50.953908 systemd[1]: Started systemd-journald.service - Journal Service. May 14 23:49:50.965159 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 14 23:49:50.978643 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 14 23:49:51.064809 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 14 23:49:51.083928 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 14 23:49:51.087584 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 23:49:51.091597 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 14 23:49:51.100256 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 14 23:49:51.117253 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 14 23:49:51.131592 kernel: loop0: detected capacity change from 0 to 123192 May 14 23:49:51.150690 systemd-journald[1480]: Time spent on flushing to /var/log/journal/ec2b0092edc7383be82a716f58e91122 is 104.442ms for 918 entries. May 14 23:49:51.150690 systemd-journald[1480]: System Journal (/var/log/journal/ec2b0092edc7383be82a716f58e91122) is 8M, max 195.6M, 187.6M free. May 14 23:49:51.268295 systemd-journald[1480]: Received client request to flush runtime journal. May 14 23:49:51.268403 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 14 23:49:51.268539 kernel: loop1: detected capacity change from 0 to 113512 May 14 23:49:51.229651 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 23:49:51.245977 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 14 23:49:51.282962 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 14 23:49:51.295159 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 14 23:49:51.304301 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 14 23:49:51.346847 udevadm[1551]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 14 23:49:51.357978 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 14 23:49:51.363523 kernel: loop2: detected capacity change from 0 to 53784 May 14 23:49:51.371129 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 23:49:51.471552 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. May 14 23:49:51.474570 systemd-tmpfiles[1559]: ACLs are not supported, ignoring. May 14 23:49:51.500565 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 23:49:51.510504 kernel: loop3: detected capacity change from 0 to 123192 May 14 23:49:51.552502 kernel: loop4: detected capacity change from 0 to 113512 May 14 23:49:51.593483 kernel: loop5: detected capacity change from 0 to 53784 May 14 23:49:51.611079 (sd-merge)[1563]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-ami'. May 14 23:49:51.616653 (sd-merge)[1563]: Merged extensions into '/usr'. May 14 23:49:51.627291 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 14 23:49:51.644827 systemd[1]: Starting ensure-sysext.service... May 14 23:49:51.656023 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 23:49:51.683661 systemd[1]: Reload requested from client PID 1565 ('systemctl') (unit ensure-sysext.service)... May 14 23:49:51.683703 systemd[1]: Reloading... May 14 23:49:51.792963 systemd-tmpfiles[1566]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 14 23:49:51.793595 systemd-tmpfiles[1566]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 14 23:49:51.796498 systemd-tmpfiles[1566]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 14 23:49:51.797163 systemd-tmpfiles[1566]: ACLs are not supported, ignoring. May 14 23:49:51.797338 systemd-tmpfiles[1566]: ACLs are not supported, ignoring. May 14 23:49:51.828624 systemd-tmpfiles[1566]: Detected autofs mount point /boot during canonicalization of boot. May 14 23:49:51.829066 systemd-tmpfiles[1566]: Skipping /boot May 14 23:49:51.886502 zram_generator::config[1595]: No configuration found. May 14 23:49:51.961631 systemd-tmpfiles[1566]: Detected autofs mount point /boot during canonicalization of boot. May 14 23:49:51.961660 systemd-tmpfiles[1566]: Skipping /boot May 14 23:49:52.148354 ldconfig[1511]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 23:49:52.269796 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. May 14 23:49:52.438436 systemd[1]: Reloading finished in 754 ms. May 14 23:49:52.463563 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 14 23:49:52.496665 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 23:49:52.525809 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 23:49:52.532180 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 14 23:49:52.539821 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 14 23:49:52.546945 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 23:49:52.555828 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 14 23:49:52.567850 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:49:52.578138 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 23:49:52.584401 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 23:49:52.591968 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 23:49:52.594316 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:49:52.594667 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 23:49:52.605699 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:49:52.606119 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:49:52.606338 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 23:49:52.631574 systemd[1]: Finished ensure-sysext.service. May 14 23:49:52.640624 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 23:49:52.650093 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 23:49:52.652926 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 23:49:52.653057 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 23:49:52.653194 systemd[1]: Reached target time-set.target - System Time Set. May 14 23:49:52.674961 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 14 23:49:52.678240 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 14 23:49:52.692184 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 23:49:52.693940 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 23:49:52.708025 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 23:49:52.711638 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 23:49:52.728245 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 23:49:52.731539 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 23:49:52.735061 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 23:49:52.763445 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 23:49:52.764004 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 23:49:52.767222 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 23:49:52.803228 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 14 23:49:52.830210 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 14 23:49:52.847848 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 23:49:52.861868 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 14 23:49:52.864916 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 14 23:49:52.869059 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). May 14 23:49:52.880839 augenrules[1690]: No rules May 14 23:49:52.886311 systemd[1]: audit-rules.service: Deactivated successfully. May 14 23:49:52.887729 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 23:49:52.931352 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 14 23:49:52.934264 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 14 23:49:52.964484 systemd-udevd[1686]: Using default interface naming scheme 'v255'. May 14 23:49:53.023563 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 23:49:53.040847 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 23:49:53.146050 systemd-resolved[1653]: Positive Trust Anchors: May 14 23:49:53.146090 systemd-resolved[1653]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 23:49:53.146152 systemd-resolved[1653]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 14 23:49:53.159186 systemd-resolved[1653]: Defaulting to hostname 'linux'. May 14 23:49:53.165114 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 23:49:53.167702 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 23:49:53.258059 systemd-networkd[1703]: lo: Link UP May 14 23:49:53.258081 systemd-networkd[1703]: lo: Gained carrier May 14 23:49:53.260022 systemd-networkd[1703]: Enumeration completed May 14 23:49:53.260254 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 23:49:53.262756 systemd[1]: Reached target network.target - Network. May 14 23:49:53.265237 (udev-worker)[1708]: Network interface NamePolicy= disabled on kernel command line. May 14 23:49:53.318302 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 14 23:49:53.333925 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 14 23:49:53.336852 systemd-networkd[1703]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:49:53.336861 systemd-networkd[1703]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 23:49:53.339856 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 14 23:49:53.342945 systemd-networkd[1703]: eth0: Link UP May 14 23:49:53.343383 systemd-networkd[1703]: eth0: Gained carrier May 14 23:49:53.343444 systemd-networkd[1703]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 23:49:53.359292 systemd-networkd[1703]: eth0: DHCPv4 address 172.31.21.175/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 14 23:49:53.387119 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 14 23:49:53.479542 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (1710) May 14 23:49:53.790394 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 23:49:53.853067 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 14 23:49:53.857563 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 14 23:49:53.872986 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 14 23:49:53.889937 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 14 23:49:53.907298 lvm[1825]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 14 23:49:53.943396 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 14 23:49:53.952738 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 14 23:49:53.955965 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 23:49:53.966990 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 14 23:49:53.973673 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 23:49:53.977137 systemd[1]: Reached target sysinit.target - System Initialization. May 14 23:49:53.979685 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 14 23:49:53.982328 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 14 23:49:53.985269 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 14 23:49:53.987876 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 14 23:49:53.990664 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 14 23:49:53.993305 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 14 23:49:53.993392 systemd[1]: Reached target paths.target - Path Units. May 14 23:49:53.995507 systemd[1]: Reached target timers.target - Timer Units. May 14 23:49:54.000799 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 14 23:49:54.007986 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 23:49:54.015502 lvm[1832]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 14 23:49:54.015948 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 14 23:49:54.019028 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 14 23:49:54.021726 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 14 23:49:54.038078 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 14 23:49:54.041647 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 14 23:49:54.047713 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 23:49:54.050245 systemd[1]: Reached target sockets.target - Socket Units. May 14 23:49:54.052735 systemd[1]: Reached target basic.target - Basic System. May 14 23:49:54.055338 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 14 23:49:54.055407 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 14 23:49:54.066721 systemd[1]: Starting containerd.service - containerd container runtime... May 14 23:49:54.073838 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 14 23:49:54.080200 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 14 23:49:54.093717 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 14 23:49:54.101864 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 14 23:49:54.104081 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). May 14 23:49:54.109022 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 14 23:49:54.116843 systemd[1]: Started ntpd.service - Network Time Service. May 14 23:49:54.128499 jq[1839]: false May 14 23:49:54.123763 systemd[1]: Starting setup-oem.service - Setup OEM... May 14 23:49:54.130706 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 14 23:49:54.138752 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 14 23:49:54.158934 systemd[1]: Starting systemd-logind.service - User Login Management... May 14 23:49:54.165695 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 14 23:49:54.167781 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 14 23:49:54.171433 systemd[1]: Starting update-engine.service - Update Engine... May 14 23:49:54.179248 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 14 23:49:54.185743 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 14 23:49:54.191683 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 14 23:49:54.192619 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 14 23:49:54.254055 extend-filesystems[1840]: Found loop3 May 14 23:49:54.263787 extend-filesystems[1840]: Found loop4 May 14 23:49:54.266628 extend-filesystems[1840]: Found loop5 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p1 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p2 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p3 May 14 23:49:54.266628 extend-filesystems[1840]: Found usr May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p4 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p6 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p7 May 14 23:49:54.266628 extend-filesystems[1840]: Found nvme0n1p9 May 14 23:49:54.266628 extend-filesystems[1840]: Checking size of /dev/nvme0n1p9 May 14 23:49:54.311107 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 14 23:49:54.311716 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 14 23:49:54.328254 update_engine[1850]: I20250514 23:49:54.324411 1850 main.cc:92] Flatcar Update Engine starting May 14 23:49:54.350445 (ntainerd)[1863]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 14 23:49:54.357434 jq[1851]: true May 14 23:49:54.375589 systemd[1]: motdgen.service: Deactivated successfully. May 14 23:49:54.376179 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 14 23:49:54.403499 dbus-daemon[1838]: [system] SELinux support is enabled May 14 23:49:54.404283 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 14 23:49:54.415391 extend-filesystems[1840]: Resized partition /dev/nvme0n1p9 May 14 23:49:54.417234 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). May 14 23:49:54.417304 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 14 23:49:54.419928 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). May 14 23:49:54.419967 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 14 23:49:54.425003 jq[1874]: true May 14 23:49:54.445959 ntpd[1843]: ntpd 4.2.8p17@1.4004-o Wed May 14 21:39:21 UTC 2025 (1): Starting May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: ntpd 4.2.8p17@1.4004-o Wed May 14 21:39:21 UTC 2025 (1): Starting May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: ---------------------------------------------------- May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: ntp-4 is maintained by Network Time Foundation, May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: corporation. Support and training for ntp-4 are May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: available at https://www.nwtime.org/support May 14 23:49:54.447970 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: ---------------------------------------------------- May 14 23:49:54.446021 ntpd[1843]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 14 23:49:54.446043 ntpd[1843]: ---------------------------------------------------- May 14 23:49:54.458847 extend-filesystems[1882]: resize2fs 1.47.1 (20-May-2024) May 14 23:49:54.446063 ntpd[1843]: ntp-4 is maintained by Network Time Foundation, May 14 23:49:54.468771 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: proto: precision = 0.096 usec (-23) May 14 23:49:54.446081 ntpd[1843]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 14 23:49:54.446099 ntpd[1843]: corporation. Support and training for ntp-4 are May 14 23:49:54.446117 ntpd[1843]: available at https://www.nwtime.org/support May 14 23:49:54.446136 ntpd[1843]: ---------------------------------------------------- May 14 23:49:54.456016 dbus-daemon[1838]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1703 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") May 14 23:49:54.464676 ntpd[1843]: proto: precision = 0.096 usec (-23) May 14 23:49:54.469825 dbus-daemon[1838]: [system] Successfully activated service 'org.freedesktop.systemd1' May 14 23:49:54.471660 ntpd[1843]: basedate set to 2025-05-02 May 14 23:49:54.473620 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: basedate set to 2025-05-02 May 14 23:49:54.473620 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: gps base set to 2025-05-04 (week 2365) May 14 23:49:54.471708 ntpd[1843]: gps base set to 2025-05-04 (week 2365) May 14 23:49:54.477522 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks May 14 23:49:54.479920 update_engine[1850]: I20250514 23:49:54.479831 1850 update_check_scheduler.cc:74] Next update check in 10m45s May 14 23:49:54.485592 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 14 23:49:54.491021 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listen and drop on 0 v6wildcard [::]:123 May 14 23:49:54.491021 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 14 23:49:54.491021 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listen normally on 2 lo 127.0.0.1:123 May 14 23:49:54.491021 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listen normally on 3 eth0 172.31.21.175:123 May 14 23:49:54.491021 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listen normally on 4 lo [::1]:123 May 14 23:49:54.488495 ntpd[1843]: Listen and drop on 0 v6wildcard [::]:123 May 14 23:49:54.488974 systemd[1]: Started update-engine.service - Update Engine. May 14 23:49:54.488596 ntpd[1843]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 14 23:49:54.488877 ntpd[1843]: Listen normally on 2 lo 127.0.0.1:123 May 14 23:49:54.503760 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: bind(21) AF_INET6 fe80::4fd:9cff:fe25:2a11%2#123 flags 0x11 failed: Cannot assign requested address May 14 23:49:54.503760 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: unable to create socket on eth0 (5) for fe80::4fd:9cff:fe25:2a11%2#123 May 14 23:49:54.503760 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: failed to init interface for address fe80::4fd:9cff:fe25:2a11%2 May 14 23:49:54.503760 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: Listening on routing socket on fd #21 for interface updates May 14 23:49:54.488953 ntpd[1843]: Listen normally on 3 eth0 172.31.21.175:123 May 14 23:49:54.489025 ntpd[1843]: Listen normally on 4 lo [::1]:123 May 14 23:49:54.494887 ntpd[1843]: bind(21) AF_INET6 fe80::4fd:9cff:fe25:2a11%2#123 flags 0x11 failed: Cannot assign requested address May 14 23:49:54.494944 ntpd[1843]: unable to create socket on eth0 (5) for fe80::4fd:9cff:fe25:2a11%2#123 May 14 23:49:54.494974 ntpd[1843]: failed to init interface for address fe80::4fd:9cff:fe25:2a11%2 May 14 23:49:54.498616 ntpd[1843]: Listening on routing socket on fd #21 for interface updates May 14 23:49:54.508991 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 14 23:49:54.522372 ntpd[1843]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:49:54.527348 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:49:54.527348 ntpd[1843]: 14 May 23:49:54 ntpd[1843]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:49:54.525624 ntpd[1843]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 14 23:49:54.564544 systemd[1]: Finished setup-oem.service - Setup OEM. May 14 23:49:54.587585 coreos-metadata[1837]: May 14 23:49:54.585 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 14 23:49:54.594002 coreos-metadata[1837]: May 14 23:49:54.593 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 May 14 23:49:54.596611 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 May 14 23:49:54.602240 coreos-metadata[1837]: May 14 23:49:54.601 INFO Fetch successful May 14 23:49:54.602240 coreos-metadata[1837]: May 14 23:49:54.601 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 May 14 23:49:54.603791 coreos-metadata[1837]: May 14 23:49:54.603 INFO Fetch successful May 14 23:49:54.603791 coreos-metadata[1837]: May 14 23:49:54.603 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 May 14 23:49:54.609778 coreos-metadata[1837]: May 14 23:49:54.609 INFO Fetch successful May 14 23:49:54.609778 coreos-metadata[1837]: May 14 23:49:54.609 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 May 14 23:49:54.611559 extend-filesystems[1882]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required May 14 23:49:54.611559 extend-filesystems[1882]: old_desc_blocks = 1, new_desc_blocks = 1 May 14 23:49:54.611559 extend-filesystems[1882]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. May 14 23:49:54.620339 coreos-metadata[1837]: May 14 23:49:54.619 INFO Fetch successful May 14 23:49:54.620339 coreos-metadata[1837]: May 14 23:49:54.619 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 May 14 23:49:54.623000 extend-filesystems[1840]: Resized filesystem in /dev/nvme0n1p9 May 14 23:49:54.630911 coreos-metadata[1837]: May 14 23:49:54.630 INFO Fetch failed with 404: resource not found May 14 23:49:54.630911 coreos-metadata[1837]: May 14 23:49:54.630 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 May 14 23:49:54.631570 systemd[1]: extend-filesystems.service: Deactivated successfully. May 14 23:49:54.632850 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 14 23:49:54.641727 coreos-metadata[1837]: May 14 23:49:54.639 INFO Fetch successful May 14 23:49:54.641727 coreos-metadata[1837]: May 14 23:49:54.639 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 May 14 23:49:54.641727 coreos-metadata[1837]: May 14 23:49:54.640 INFO Fetch successful May 14 23:49:54.641727 coreos-metadata[1837]: May 14 23:49:54.640 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 May 14 23:49:54.644903 coreos-metadata[1837]: May 14 23:49:54.644 INFO Fetch successful May 14 23:49:54.644903 coreos-metadata[1837]: May 14 23:49:54.644 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 May 14 23:49:54.649502 coreos-metadata[1837]: May 14 23:49:54.645 INFO Fetch successful May 14 23:49:54.649502 coreos-metadata[1837]: May 14 23:49:54.645 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 May 14 23:49:54.651552 coreos-metadata[1837]: May 14 23:49:54.649 INFO Fetch successful May 14 23:49:54.703512 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 44 scanned by (udev-worker) (1710) May 14 23:49:54.708645 bash[1914]: Updated "/home/core/.ssh/authorized_keys" May 14 23:49:54.724619 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 14 23:49:54.739911 systemd[1]: Starting sshkeys.service... May 14 23:49:54.781675 systemd-networkd[1703]: eth0: Gained IPv6LL May 14 23:49:54.788634 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 14 23:49:54.796315 systemd[1]: Reached target network-online.target - Network is Online. May 14 23:49:54.814888 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. May 14 23:49:54.835520 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 14 23:49:54.840426 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 14 23:49:54.856121 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 14 23:49:54.858774 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 14 23:49:54.868649 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 14 23:49:54.875125 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 14 23:49:54.923129 (-wrapper)[1943]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 14 23:49:55.000329 systemd-logind[1849]: Watching system buttons on /dev/input/event0 (Power Button) May 14 23:49:55.000390 systemd-logind[1849]: Watching system buttons on /dev/input/event1 (Sleep Button) May 14 23:49:55.007388 systemd-logind[1849]: New seat seat0. May 14 23:49:55.009053 systemd[1]: Started systemd-logind.service - User Login Management. May 14 23:49:55.103091 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 14 23:49:55.141569 amazon-ssm-agent[1936]: Initializing new seelog logger May 14 23:49:55.141569 amazon-ssm-agent[1936]: New Seelog Logger Creation Complete May 14 23:49:55.141569 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.141569 amazon-ssm-agent[1936]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.154776 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 processing appconfig overrides May 14 23:49:55.158402 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO Proxy environment variables: May 14 23:49:55.166742 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.166742 amazon-ssm-agent[1936]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.166742 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 processing appconfig overrides May 14 23:49:55.166742 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.166742 amazon-ssm-agent[1936]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.178697 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 processing appconfig overrides May 14 23:49:55.194265 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.194265 amazon-ssm-agent[1936]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 14 23:49:55.194425 amazon-ssm-agent[1936]: 2025/05/14 23:49:55 processing appconfig overrides May 14 23:49:55.265073 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO https_proxy: May 14 23:49:55.373799 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO http_proxy: May 14 23:49:55.433786 locksmithd[1889]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 14 23:49:55.441391 coreos-metadata[1946]: May 14 23:49:55.439 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 14 23:49:55.444645 coreos-metadata[1946]: May 14 23:49:55.442 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 May 14 23:49:55.450668 coreos-metadata[1946]: May 14 23:49:55.448 INFO Fetch successful May 14 23:49:55.450668 coreos-metadata[1946]: May 14 23:49:55.448 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 May 14 23:49:55.451540 coreos-metadata[1946]: May 14 23:49:55.451 INFO Fetch successful May 14 23:49:55.459150 unknown[1946]: wrote ssh authorized keys file for user: core May 14 23:49:55.474351 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO no_proxy: May 14 23:49:55.484120 dbus-daemon[1838]: [system] Successfully activated service 'org.freedesktop.hostname1' May 14 23:49:55.488998 dbus-daemon[1838]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=1888 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") May 14 23:49:55.506710 systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 14 23:49:55.526123 systemd[1]: Starting polkit.service - Authorization Manager... May 14 23:49:55.546764 update-ssh-keys[2029]: Updated "/home/core/.ssh/authorized_keys" May 14 23:49:55.583139 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO Checking if agent identity type OnPrem can be assumed May 14 23:49:55.583215 containerd[1863]: time="2025-05-14T23:49:55.560055585Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 14 23:49:55.583141 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 14 23:49:55.588243 systemd[1]: Finished sshkeys.service. May 14 23:49:55.678353 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO Checking if agent identity type EC2 can be assumed May 14 23:49:55.692802 polkitd[2031]: Started polkitd version 121 May 14 23:49:55.762325 polkitd[2031]: Loading rules from directory /etc/polkit-1/rules.d May 14 23:49:55.762538 polkitd[2031]: Loading rules from directory /usr/share/polkit-1/rules.d May 14 23:49:55.767653 polkitd[2031]: Finished loading, compiling and executing 2 rules May 14 23:49:55.771352 dbus-daemon[1838]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 14 23:49:55.772111 systemd[1]: Started polkit.service - Authorization Manager. May 14 23:49:55.775254 polkitd[2031]: Acquired the name org.freedesktop.PolicyKit1 on the system bus May 14 23:49:55.775903 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO Agent will take identity from EC2 May 14 23:49:55.784224 containerd[1863]: time="2025-05-14T23:49:55.783890026Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.801420 containerd[1863]: time="2025-05-14T23:49:55.801346127Z" 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.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 14 23:49:55.801706 containerd[1863]: time="2025-05-14T23:49:55.801649835Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 14 23:49:55.801848 containerd[1863]: time="2025-05-14T23:49:55.801817979Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 14 23:49:55.804132 containerd[1863]: time="2025-05-14T23:49:55.803694263Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 14 23:49:55.804132 containerd[1863]: time="2025-05-14T23:49:55.803788127Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.804339 containerd[1863]: time="2025-05-14T23:49:55.804071855Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 14 23:49:55.804574 containerd[1863]: time="2025-05-14T23:49:55.804503291Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.808226 containerd[1863]: time="2025-05-14T23:49:55.807785435Z" 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 May 14 23:49:55.808226 containerd[1863]: time="2025-05-14T23:49:55.807872351Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.808226 containerd[1863]: time="2025-05-14T23:49:55.807912923Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 14 23:49:55.808226 containerd[1863]: time="2025-05-14T23:49:55.807968483Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.810485 containerd[1863]: time="2025-05-14T23:49:55.808668167Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.811328 containerd[1863]: time="2025-05-14T23:49:55.811278791Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 14 23:49:55.815373 containerd[1863]: time="2025-05-14T23:49:55.814656395Z" 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 May 14 23:49:55.815373 containerd[1863]: time="2025-05-14T23:49:55.814731059Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 14 23:49:55.815373 containerd[1863]: time="2025-05-14T23:49:55.814984571Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 14 23:49:55.815373 containerd[1863]: time="2025-05-14T23:49:55.815107463Z" level=info msg="metadata content store policy set" policy=shared May 14 23:49:55.820710 systemd-resolved[1653]: System hostname changed to 'ip-172-31-21-175'. May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821054207Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821165519Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821200427Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821313779Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821348555Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 14 23:49:55.822106 containerd[1863]: time="2025-05-14T23:49:55.821689775Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 14 23:49:55.822493 systemd-hostnamed[1888]: Hostname set to (transient) May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.825862835Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.827810351Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.827862659Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.827945639Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.827985083Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828020891Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828052643Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828092375Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828126239Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828158951Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828189119Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828216203Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828258695Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 14 23:49:55.829496 containerd[1863]: time="2025-05-14T23:49:55.828299099Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828328883Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828361367Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828394223Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828426191Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828494999Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828535199Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828580355Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828620975Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828673967Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828721427Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828753119Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828786611Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828835079Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828867467Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830207 containerd[1863]: time="2025-05-14T23:49:55.828895295Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829036835Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829078103Z" 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 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829108967Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829140191Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829163915Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829194371Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829218011Z" level=info msg="NRI interface is disabled by configuration." May 14 23:49:55.830933 containerd[1863]: time="2025-05-14T23:49:55.829244303Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.836015459Z" 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}" May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.836136275Z" level=info msg="Connect containerd service" May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.836223479Z" level=info msg="using legacy CRI server" May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.836245679Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.836567915Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 14 23:49:55.840566 containerd[1863]: time="2025-05-14T23:49:55.840227975Z" 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" May 14 23:49:55.845491 containerd[1863]: time="2025-05-14T23:49:55.843813107Z" level=info msg="Start subscribing containerd event" May 14 23:49:55.845491 containerd[1863]: time="2025-05-14T23:49:55.843919451Z" level=info msg="Start recovering state" May 14 23:49:55.845491 containerd[1863]: time="2025-05-14T23:49:55.844217291Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 14 23:49:55.845491 containerd[1863]: time="2025-05-14T23:49:55.844327127Z" level=info msg=serving... address=/run/containerd/containerd.sock May 14 23:49:55.845858 containerd[1863]: time="2025-05-14T23:49:55.845809247Z" level=info msg="Start event monitor" May 14 23:49:55.846008 containerd[1863]: time="2025-05-14T23:49:55.845974871Z" level=info msg="Start snapshots syncer" May 14 23:49:55.846173 containerd[1863]: time="2025-05-14T23:49:55.846140999Z" level=info msg="Start cni network conf syncer for default" May 14 23:49:55.846275 containerd[1863]: time="2025-05-14T23:49:55.846249671Z" level=info msg="Start streaming server" May 14 23:49:55.846554 containerd[1863]: time="2025-05-14T23:49:55.846520259Z" level=info msg="containerd successfully booted in 0.291542s" May 14 23:49:55.847693 systemd[1]: Started containerd.service - containerd container runtime. May 14 23:49:55.860956 systemd[1]: Starting docker.service - Docker Application Container Engine... May 14 23:49:55.881506 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] using named pipe channel for IPC May 14 23:49:55.882297 (dockerd)[2067]: 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 May 14 23:49:55.896246 sshd_keygen[1885]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 14 23:49:55.979041 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] using named pipe channel for IPC May 14 23:49:56.005611 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 14 23:49:56.024978 systemd[1]: Starting issuegen.service - Generate /run/issue... May 14 23:49:56.066749 systemd[1]: issuegen.service: Deactivated successfully. May 14 23:49:56.070802 systemd[1]: Finished issuegen.service - Generate /run/issue. May 14 23:49:56.079280 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] using named pipe channel for IPC May 14 23:49:56.085659 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 14 23:49:56.130432 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 14 23:49:56.147000 systemd[1]: Started getty@tty1.service - Getty on tty1. May 14 23:49:56.161965 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 14 23:49:56.165768 systemd[1]: Reached target getty.target - Login Prompts. May 14 23:49:56.178532 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 May 14 23:49:56.278193 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 May 14 23:49:56.378731 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] Starting Core Agent May 14 23:49:56.388824 dockerd[2067]: time="2025-05-14T23:49:56.388716369Z" level=info msg="Starting up" May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [amazon-ssm-agent] registrar detected. Attempting registration May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [Registrar] Starting registrar module May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:55 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:56 INFO [EC2Identity] EC2 registration was successful. May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:56 INFO [CredentialRefresher] credentialRefresher has started May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:56 INFO [CredentialRefresher] Starting credentials refresher loop May 14 23:49:56.430369 amazon-ssm-agent[1936]: 2025-05-14 23:49:56 INFO EC2RoleProvider Successfully connected with instance profile role credentials May 14 23:49:56.479367 amazon-ssm-agent[1936]: 2025-05-14 23:49:56 INFO [CredentialRefresher] Next credential rotation will be in 30.333323403533335 minutes May 14 23:49:56.535530 dockerd[2067]: time="2025-05-14T23:49:56.535326046Z" level=info msg="Loading containers: start." May 14 23:49:56.803636 kernel: Initializing XFRM netlink socket May 14 23:49:56.837856 (udev-worker)[1721]: Network interface NamePolicy= disabled on kernel command line. May 14 23:49:56.933302 systemd-networkd[1703]: docker0: Link UP May 14 23:49:56.976112 dockerd[2067]: time="2025-05-14T23:49:56.976059552Z" level=info msg="Loading containers: done." May 14 23:49:57.000436 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3850982326-merged.mount: Deactivated successfully. May 14 23:49:57.003656 dockerd[2067]: time="2025-05-14T23:49:57.003580917Z" 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 May 14 23:49:57.003818 dockerd[2067]: time="2025-05-14T23:49:57.003740985Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 May 14 23:49:57.004014 dockerd[2067]: time="2025-05-14T23:49:57.003965133Z" level=info msg="Daemon has completed initialization" May 14 23:49:57.073704 dockerd[2067]: time="2025-05-14T23:49:57.073612785Z" level=info msg="API listen on /run/docker.sock" May 14 23:49:57.074247 systemd[1]: Started docker.service - Docker Application Container Engine. May 14 23:49:57.078786 etcd-wrapper[1977]: Error response from daemon: No such container: etcd-member May 14 23:49:57.102135 etcd-wrapper[2260]: Error response from daemon: No such container: etcd-member May 14 23:49:57.149506 etcd-wrapper[2278]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally May 14 23:49:57.251788 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 14 23:49:57.262961 systemd[1]: Started sshd@0-172.31.21.175:22-139.178.89.65:41660.service - OpenSSH per-connection server daemon (139.178.89.65:41660). May 14 23:49:57.461782 amazon-ssm-agent[1936]: 2025-05-14 23:49:57 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process May 14 23:49:57.476349 sshd[2287]: Accepted publickey for core from 139.178.89.65 port 41660 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:49:57.479859 sshd-session[2287]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:49:57.508138 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 14 23:49:57.521078 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 14 23:49:57.531732 systemd-logind[1849]: New session 1 of user core. May 14 23:49:57.562049 amazon-ssm-agent[1936]: 2025-05-14 23:49:57 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2290) started May 14 23:49:57.565585 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 14 23:49:57.580120 systemd[1]: Starting user@500.service - User Manager for UID 500... May 14 23:49:57.602958 (systemd)[2296]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 14 23:49:57.610095 systemd-logind[1849]: New session c1 of user core. May 14 23:49:57.662355 amazon-ssm-agent[1936]: 2025-05-14 23:49:57 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds May 14 23:49:57.963567 systemd[2296]: Queued start job for default target default.target. May 14 23:49:57.972433 systemd[2296]: Created slice app.slice - User Application Slice. May 14 23:49:57.972784 systemd[2296]: Reached target paths.target - Paths. May 14 23:49:57.973099 systemd[2296]: Reached target timers.target - Timers. May 14 23:49:57.975904 systemd[2296]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 23:49:58.007441 systemd[2296]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 23:49:58.007746 systemd[2296]: Reached target sockets.target - Sockets. May 14 23:49:58.007852 systemd[2296]: Reached target basic.target - Basic System. May 14 23:49:58.007940 systemd[2296]: Reached target default.target - Main User Target. May 14 23:49:58.008001 systemd[2296]: Startup finished in 383ms. May 14 23:49:58.008742 systemd[1]: Started user@500.service - User Manager for UID 500. May 14 23:49:58.019787 systemd[1]: Started session-1.scope - Session 1 of User core. May 14 23:49:58.188213 systemd[1]: Started sshd@1-172.31.21.175:22-139.178.89.65:41666.service - OpenSSH per-connection server daemon (139.178.89.65:41666). May 14 23:49:58.233036 etcd-wrapper[2278]: v3.5.0: Pulling from coreos/etcd May 14 23:49:58.373211 sshd[2313]: Accepted publickey for core from 139.178.89.65 port 41666 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:49:58.375910 sshd-session[2313]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:49:58.386061 systemd-logind[1849]: New session 2 of user core. May 14 23:49:58.397767 systemd[1]: Started session-2.scope - Session 2 of User core. May 14 23:49:58.487931 etcd-wrapper[2278]: 6022c6b01d76: Pulling fs layer May 14 23:49:58.487931 etcd-wrapper[2278]: 1d97b1ec77fc: Pulling fs layer May 14 23:49:58.487931 etcd-wrapper[2278]: 99ef4b690837: Pulling fs layer May 14 23:49:58.487931 etcd-wrapper[2278]: 435b7bf0967c: Pulling fs layer May 14 23:49:58.487931 etcd-wrapper[2278]: 17e4a5f7c1b5: Pulling fs layer May 14 23:49:58.487931 etcd-wrapper[2278]: 5b0292999756: Pulling fs layer May 14 23:49:58.490076 etcd-wrapper[2278]: 17e4a5f7c1b5: Waiting May 14 23:49:58.490076 etcd-wrapper[2278]: 435b7bf0967c: Waiting May 14 23:49:58.490076 etcd-wrapper[2278]: 5b0292999756: Waiting May 14 23:49:58.527773 sshd[2315]: Connection closed by 139.178.89.65 port 41666 May 14 23:49:58.528882 sshd-session[2313]: pam_unix(sshd:session): session closed for user core May 14 23:49:58.535065 systemd-logind[1849]: Session 2 logged out. Waiting for processes to exit. May 14 23:49:58.538053 systemd[1]: sshd@1-172.31.21.175:22-139.178.89.65:41666.service: Deactivated successfully. May 14 23:49:58.541937 systemd[1]: session-2.scope: Deactivated successfully. May 14 23:49:58.544491 systemd-logind[1849]: Removed session 2. May 14 23:49:58.570121 systemd[1]: Started sshd@2-172.31.21.175:22-139.178.89.65:41676.service - OpenSSH per-connection server daemon (139.178.89.65:41676). May 14 23:49:58.769725 sshd[2321]: Accepted publickey for core from 139.178.89.65 port 41676 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:49:58.772377 sshd-session[2321]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:49:58.781555 systemd-logind[1849]: New session 3 of user core. May 14 23:49:58.793761 systemd[1]: Started session-3.scope - Session 3 of User core. May 14 23:49:58.905744 etcd-wrapper[2278]: 1d97b1ec77fc: Verifying Checksum May 14 23:49:58.905744 etcd-wrapper[2278]: 1d97b1ec77fc: Download complete May 14 23:49:58.933703 sshd[2323]: Connection closed by 139.178.89.65 port 41676 May 14 23:49:58.935764 sshd-session[2321]: pam_unix(sshd:session): session closed for user core May 14 23:49:58.945590 systemd[1]: sshd@2-172.31.21.175:22-139.178.89.65:41676.service: Deactivated successfully. May 14 23:49:58.954607 systemd[1]: session-3.scope: Deactivated successfully. May 14 23:49:58.960082 systemd-logind[1849]: Session 3 logged out. Waiting for processes to exit. May 14 23:49:58.961882 systemd-logind[1849]: Removed session 3. May 14 23:49:59.089305 etcd-wrapper[2278]: 99ef4b690837: Verifying Checksum May 14 23:49:59.089305 etcd-wrapper[2278]: 99ef4b690837: Download complete May 14 23:49:59.285688 etcd-wrapper[2278]: 435b7bf0967c: Verifying Checksum May 14 23:49:59.286559 etcd-wrapper[2278]: 435b7bf0967c: Download complete May 14 23:49:59.366075 etcd-wrapper[2278]: 17e4a5f7c1b5: Verifying Checksum May 14 23:49:59.366075 etcd-wrapper[2278]: 17e4a5f7c1b5: Download complete May 14 23:49:59.383033 etcd-wrapper[2278]: 6022c6b01d76: Verifying Checksum May 14 23:49:59.383033 etcd-wrapper[2278]: 6022c6b01d76: Download complete May 14 23:49:59.446912 ntpd[1843]: Listen normally on 6 eth0 [fe80::4fd:9cff:fe25:2a11%2]:123 May 14 23:49:59.448632 ntpd[1843]: 14 May 23:49:59 ntpd[1843]: Listen normally on 6 eth0 [fe80::4fd:9cff:fe25:2a11%2]:123 May 14 23:49:59.696834 etcd-wrapper[2278]: 5b0292999756: Verifying Checksum May 14 23:49:59.696834 etcd-wrapper[2278]: 5b0292999756: Download complete May 14 23:50:00.784507 etcd-wrapper[2278]: 6022c6b01d76: Pull complete May 14 23:50:01.039618 systemd[1]: var-lib-docker-overlay2-4111a958ee429a150f7ba13eff4e0e9b7380892e81f7fdb8f37829b0f9394d50-merged.mount: Deactivated successfully. May 14 23:50:01.293518 etcd-wrapper[2278]: 1d97b1ec77fc: Pull complete May 14 23:50:01.587425 systemd-resolved[1653]: Clock change detected. Flushing caches. May 14 23:50:01.660152 etcd-wrapper[2278]: 99ef4b690837: Pull complete May 14 23:50:01.854031 etcd-wrapper[2278]: 435b7bf0967c: Pull complete May 14 23:50:01.876854 etcd-wrapper[2278]: 17e4a5f7c1b5: Pull complete May 14 23:50:01.903622 etcd-wrapper[2278]: 5b0292999756: Pull complete May 14 23:50:01.915891 etcd-wrapper[2278]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b May 14 23:50:01.921563 etcd-wrapper[2278]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0 May 14 23:50:01.940490 systemd[1]: var-lib-docker-overlay2-05f60b05293efd2bb424ab6fdf0db07b34cc02b2238c087260dbfd80ddc4a55e-merged.mount: Deactivated successfully. May 14 23:50:02.130412 containerd[1863]: time="2025-05-14T23:50:02.130103418Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:02.131868 containerd[1863]: time="2025-05-14T23:50:02.131753922Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:02.132102 containerd[1863]: time="2025-05-14T23:50:02.131833350Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:02.132387 containerd[1863]: time="2025-05-14T23:50:02.132301938Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:02.166234 systemd[1]: Started docker-559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09.scope - libcontainer container 559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09. May 14 23:50:02.260575 etcd-wrapper[2278]: {"level":"info","ts":1747266602.2600856,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"info","ts":1747266602.2603412,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2b0092edc7383be82a716f58e91122"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"warn","ts":1747266602.2606409,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"warn","ts":1747266602.26087,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"warn","ts":1747266602.260941,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"warn","ts":1747266602.261158,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"warn","ts":1747266602.2612753,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.261Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.31.21.175:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.31.21.175:2380","--advertise-client-urls=http://172.31.21.175:2379","--discovery=https://discovery.etcd.io/9b303b34ac49e345b8305bea852102df","--enable-v2=true"]} May 14 23:50:02.262273 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.261Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.31.21.175:2380"]} May 14 23:50:02.263313 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.262Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 14 23:50:02.263313 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.262Z","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":"ec2b0092edc7383be82a716f58e91122","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.21.175:2380"],"listen-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2b0092edc7383be82a716f58e91122=http://172.31.21.175:2380","initial-cluster-state":"new","initial-cluster-token":"https://discovery.etcd.io/9b303b34ac49e345b8305bea852102df","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/9b303b34ac49e345b8305bea852102df","discovery-proxy":"","downgrade-check-interval":"5s"} May 14 23:50:02.265928 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.265Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.851312ms"} May 14 23:50:02.882573 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.882Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"830c55c15a1f45cb"} May 14 23:50:02.883112 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.882Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"https://discovery.etcd.io","peer":"ab556f38458dc3e0"} May 14 23:50:02.883112 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.882Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"https://discovery.etcd.io","self":"21c4bfb2f272665b"} May 14 23:50:02.883112 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.882Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"https://discovery.etcd.io","found-peers":3} May 14 23:50:02.889968 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.889Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"21c4bfb2f272665b","cluster-id":"8a7388d05ba84a1f"} May 14 23:50:02.890408 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=()"} May 14 23:50:02.890408 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b became follower at term 0"} May 14 23:50:02.891153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21c4bfb2f272665b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 14 23:50:02.891153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b became follower at term 1"} May 14 23:50:02.891153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947)"} May 14 23:50:02.891153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499)"} May 14 23:50:02.891153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.890Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499 12345896240981853152)"} May 14 23:50:02.892020 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:02.891Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} May 14 23:50:02.893168 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.892Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} May 14 23:50:02.893946 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.893Z","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"} May 14 23:50:02.894842 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.894Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.896999 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.895Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.897524 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.895Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.899002 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.898Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.899782 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.899Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.899782 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.899Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb","remote-peer-urls":["http://172.31.19.163:2380"]} May 14 23:50:02.899782 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.899Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.899782 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.899Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.903519 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.903Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.906045 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.905Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.906404 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.905Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.906404 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.905Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.907791 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.907Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.907791 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.907Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0","remote-peer-urls":["http://172.31.20.194:2380"]} May 14 23:50:02.907791 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.907Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"21c4bfb2f272665b","local-server-version":"3.5.0","cluster-version":"to_be_decided"} May 14 23:50:02.909380 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.908Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.910153 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.909Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.912353 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:02.910Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} May 14 23:50:02.912353 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.910Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"21c4bfb2f272665b","initial-advertise-peer-urls":["http://172.31.21.175:2380"],"listen-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 14 23:50:02.912353 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.910Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.31.21.175:2380"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.31.21.175:2380"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499 12345896240981853152)"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"21c4bfb2f272665b","added-peer-peer-urls":["http://172.31.21.175:2380"]} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499 12345896240981853152)"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"830c55c15a1f45cb","added-peer-peer-urls":["http://172.31.19.163:2380"]} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"ab556f38458dc3e0","stream-type":"stream Message"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499 12345896240981853152)"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"ab556f38458dc3e0"} May 14 23:50:02.913416 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.914177 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"ab556f38458dc3e0","added-peer-peer-urls":["http://172.31.20.194:2380"]} May 14 23:50:02.914177 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"830c55c15a1f45cb"} May 14 23:50:02.914177 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.914177 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.911Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.914998 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.912Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"830c55c15a1f45cb","stream-type":"stream MsgApp v2"} May 14 23:50:02.915299 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.912Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"830c55c15a1f45cb","stream-type":"stream Message"} May 14 23:50:02.915299 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.912Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"ab556f38458dc3e0","stream-type":"stream MsgApp v2"} May 14 23:50:02.915299 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.914Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.915299 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.913Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.915299 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.913Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:02.915613 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.915Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.915893 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.915Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:02.961915 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:02.961Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"21c4bfb2f272665b","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} May 14 23:50:03.340542 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.340Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [logterm: 1, index: 3, vote: 0] cast MsgPreVote for ab556f38458dc3e0 [logterm: 1, index: 3] at term 1"} May 14 23:50:03.342451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [term: 1] received a MsgVote message with higher term from ab556f38458dc3e0 [term: 2]"} May 14 23:50:03.343262 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b became follower at term 2"} May 14 23:50:03.343262 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [logterm: 1, index: 3, vote: 0] cast MsgVote for ab556f38458dc3e0 [logterm: 1, index: 3] at term 2"} May 14 23:50:03.344439 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21c4bfb2f272665b elected leader ab556f38458dc3e0 at term 2"} May 14 23:50:03.350307 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.348Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"21c4bfb2f272665b","local-member-attributes":"{Name:ec2b0092edc7383be82a716f58e91122 ClientURLs:[http://172.31.21.175:2379]}","request-path":"/0/members/21c4bfb2f272665b/attributes","cluster-id":"8a7388d05ba84a1f","publish-timeout":"7s"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.348Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.349Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.349Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.350Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.350Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","cluster-version":"3.5"} May 14 23:50:03.352451 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:03.350Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 14 23:50:03.363297 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... May 14 23:50:03.463449 etcdctl[2412]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } May 14 23:50:03.515097 flannel-wrapper[2419]: Error response from daemon: No such container: flannel May 14 23:50:03.537052 flannel-wrapper[2427]: Error response from daemon: No such container: flannel May 14 23:50:03.568078 flannel-wrapper[2443]: Unable to find image 'quay.io/coreos/flannel:v0.14.0-arm64' locally May 14 23:50:04.687743 flannel-wrapper[2443]: v0.14.0-arm64: Pulling from coreos/flannel May 14 23:50:04.687743 flannel-wrapper[2443]: b538f80385f9: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: 782376d11ccb: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: ab023a03e512: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: 1ce603871d35: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: 2ff40439b6d5: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: f6c30695e556: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: 071b96dd834b: Pulling fs layer May 14 23:50:04.687743 flannel-wrapper[2443]: e9e292d9edba: Pulling fs layer May 14 23:50:04.689563 flannel-wrapper[2443]: 1ce603871d35: Waiting May 14 23:50:04.689563 flannel-wrapper[2443]: 2ff40439b6d5: Waiting May 14 23:50:04.689563 flannel-wrapper[2443]: f6c30695e556: Waiting May 14 23:50:04.689563 flannel-wrapper[2443]: 071b96dd834b: Waiting May 14 23:50:04.689563 flannel-wrapper[2443]: e9e292d9edba: Waiting May 14 23:50:05.068253 flannel-wrapper[2443]: b538f80385f9: Verifying Checksum May 14 23:50:05.068253 flannel-wrapper[2443]: b538f80385f9: Download complete May 14 23:50:05.081741 flannel-wrapper[2443]: 782376d11ccb: Verifying Checksum May 14 23:50:05.082626 flannel-wrapper[2443]: 782376d11ccb: Download complete May 14 23:50:05.208590 flannel-wrapper[2443]: ab023a03e512: Verifying Checksum May 14 23:50:05.208590 flannel-wrapper[2443]: ab023a03e512: Download complete May 14 23:50:05.275264 flannel-wrapper[2443]: b538f80385f9: Pull complete May 14 23:50:05.344824 systemd[1]: var-lib-docker-overlay2-af473636ba4dae0fdeb7a2a639019324d4b5ad061768943088dac27a847eaf2c-merged.mount: Deactivated successfully. May 14 23:50:05.404110 flannel-wrapper[2443]: 782376d11ccb: Pull complete May 14 23:50:05.424277 flannel-wrapper[2443]: 1ce603871d35: Verifying Checksum May 14 23:50:05.426067 flannel-wrapper[2443]: 1ce603871d35: Download complete May 14 23:50:05.622446 flannel-wrapper[2443]: f6c30695e556: Verifying Checksum May 14 23:50:05.622446 flannel-wrapper[2443]: f6c30695e556: Download complete May 14 23:50:05.678251 flannel-wrapper[2443]: 071b96dd834b: Verifying Checksum May 14 23:50:05.678251 flannel-wrapper[2443]: 071b96dd834b: Download complete May 14 23:50:05.865126 flannel-wrapper[2443]: 2ff40439b6d5: Verifying Checksum May 14 23:50:05.865126 flannel-wrapper[2443]: 2ff40439b6d5: Download complete May 14 23:50:05.931166 systemd[1]: var-lib-docker-overlay2-9544942995fbb6b626855743754ac80e5039fdbc4daba28f2a0bf04a781cef1a-merged.mount: Deactivated successfully. May 14 23:50:05.937999 flannel-wrapper[2443]: e9e292d9edba: Verifying Checksum May 14 23:50:05.937999 flannel-wrapper[2443]: e9e292d9edba: Download complete May 14 23:50:06.013174 flannel-wrapper[2443]: ab023a03e512: Pull complete May 14 23:50:06.122757 flannel-wrapper[2443]: 1ce603871d35: Pull complete May 14 23:50:06.291741 systemd[1]: var-lib-docker-overlay2-bbc100b8f1d2fe6b31bec6a72a02a88b62f9353bc05472f1a4085e3566a744a3-merged.mount: Deactivated successfully. May 14 23:50:06.608101 systemd[1]: var-lib-docker-overlay2-3536b27db867a1a3d8820bbe39bfced24054e175c8d8f4c5d49d6c3c9c4fcb5f-merged.mount: Deactivated successfully. May 14 23:50:06.707294 flannel-wrapper[2443]: 2ff40439b6d5: Pull complete May 14 23:50:06.729909 systemd[1]: var-lib-docker-overlay2-a8696fc18d6454883849ed1e34186e75e3374a25423cb1d0ca36275207c7620d-merged.mount: Deactivated successfully. May 14 23:50:06.737821 flannel-wrapper[2443]: f6c30695e556: Pull complete May 14 23:50:06.768451 flannel-wrapper[2443]: 071b96dd834b: Pull complete May 14 23:50:06.793767 flannel-wrapper[2443]: e9e292d9edba: Pull complete May 14 23:50:06.802385 flannel-wrapper[2443]: Digest: sha256:dad4e92d4eb00d129a664ac7b44a71a5cbadea8a7f11b5b239e1e2b2701baac6 May 14 23:50:06.805172 flannel-wrapper[2443]: Status: Downloaded newer image for quay.io/coreos/flannel:v0.14.0-arm64 May 14 23:50:06.891634 containerd[1863]: time="2025-05-14T23:50:06.891391898Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:06.891634 containerd[1863]: time="2025-05-14T23:50:06.891516830Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:06.892660 containerd[1863]: time="2025-05-14T23:50:06.891889034Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:06.893494 containerd[1863]: time="2025-05-14T23:50:06.893363990Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:06.926287 systemd[1]: Started docker-d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06.scope - libcontainer container d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06. May 14 23:50:07.004167 flannel-wrapper[2443]: I0514 23:50:07.003693 1 main.go:520] Determining IP address of default interface May 14 23:50:07.005843 flannel-wrapper[2443]: I0514 23:50:07.004150 1 main.go:533] Using interface with name eth0 and address 172.31.21.175 May 14 23:50:07.005843 flannel-wrapper[2443]: I0514 23:50:07.004188 1 main.go:550] Defaulting external address to interface address (172.31.21.175) May 14 23:50:07.005843 flannel-wrapper[2443]: I0514 23:50:07.004341 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: None May 14 23:50:07.005843 flannel-wrapper[2443]: I0514 23:50:07.004352 1 main.go:257] Installing signal handlers May 14 23:50:07.009547 flannel-wrapper[2443]: I0514 23:50:07.009080 1 main.go:392] Found network config - Backend type: vxlan May 14 23:50:07.011568 flannel-wrapper[2443]: I0514 23:50:07.009167 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false May 14 23:50:07.031493 (udev-worker)[2543]: Network interface NamePolicy= disabled on kernel command line. May 14 23:50:07.040700 flannel-wrapper[2443]: I0514 23:50:07.039558 1 local_manager.go:234] Picking subnet in range 10.254.1.0 ... 10.254.255.0 May 14 23:50:07.048631 flannel-wrapper[2443]: I0514 23:50:07.048300 1 local_manager.go:220] Allocated lease (10.254.86.0/24) to current node (172.31.21.175) May 14 23:50:07.051328 flannel-wrapper[2443]: I0514 23:50:07.049809 1 main.go:357] Current network or subnet (10.254.0.0/16, 10.254.86.0/24) is not equal to previous one (0.0.0.0/0, 0.0.0.0/0), trying to recycle old iptables rules May 14 23:50:07.051502 systemd-networkd[1703]: flannel.1: Link UP May 14 23:50:07.051519 systemd-networkd[1703]: flannel.1: Gained carrier May 14 23:50:07.075051 flannel-wrapper[2443]: I0514 23:50:07.074476 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN May 14 23:50:07.077212 flannel-wrapper[2443]: I0514 23:50:07.076890 1 iptables.go:172] Deleting iptables rule: -s 0.0.0.0/0 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully May 14 23:50:07.079454 flannel-wrapper[2443]: I0514 23:50:07.078974 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j RETURN May 14 23:50:07.081506 flannel-wrapper[2443]: I0514 23:50:07.081199 1 iptables.go:172] Deleting iptables rule: ! -s 0.0.0.0/0 -d 0.0.0.0/0 -j MASQUERADE --random-fully May 14 23:50:07.084034 flannel-wrapper[2443]: I0514 23:50:07.083202 1 main.go:307] Setting up masking rules May 14 23:50:07.085255 flannel-wrapper[2443]: I0514 23:50:07.084822 1 main.go:315] Changing default FORWARD chain policy to ACCEPT May 14 23:50:07.086077 flannel-wrapper[2443]: I0514 23:50:07.085530 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env May 14 23:50:07.086077 flannel-wrapper[2443]: I0514 23:50:07.085623 1 main.go:327] Running backend. May 14 23:50:07.087410 flannel-wrapper[2443]: I0514 23:50:07.086720 1 vxlan_network.go:59] watching for new subnet leases May 14 23:50:07.088088 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). May 14 23:50:07.089092 systemd[1]: Reached target multi-user.target - Multi-User System. May 14 23:50:07.098412 flannel-wrapper[2443]: I0514 23:50:07.097033 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules May 14 23:50:07.098412 flannel-wrapper[2443]: I0514 23:50:07.097089 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN May 14 23:50:07.100045 flannel-wrapper[2443]: I0514 23:50:07.098359 1 iptables.go:148] Some iptables rules are missing; deleting and recreating rules May 14 23:50:07.100045 flannel-wrapper[2443]: I0514 23:50:07.098383 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 -j ACCEPT May 14 23:50:07.099653 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... May 14 23:50:07.106023 flannel-wrapper[2443]: I0514 23:50:07.101133 1 main.go:439] Waiting for 22h59m59.938970556s to renew lease May 14 23:50:07.106916 flannel-wrapper[2443]: I0514 23:50:07.106511 1 iptables.go:172] Deleting iptables rule: -d 10.254.0.0/16 -j ACCEPT May 14 23:50:07.107716 flannel-wrapper[2443]: I0514 23:50:07.107400 1 iptables.go:172] Deleting iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully May 14 23:50:07.110462 flannel-wrapper[2443]: I0514 23:50:07.110052 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.86.0/24 -j RETURN May 14 23:50:07.111518 flannel-wrapper[2443]: I0514 23:50:07.111180 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -j ACCEPT May 14 23:50:07.116736 flannel-wrapper[2443]: I0514 23:50:07.116241 1 iptables.go:172] Deleting iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully May 14 23:50:07.119536 flannel-wrapper[2443]: I0514 23:50:07.119261 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 -d 10.254.0.0/16 -j RETURN May 14 23:50:07.122237 sh[2579]: + for try in 1 2 3 4 5 6 May 14 23:50:07.122237 sh[2579]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i May 14 23:50:07.125036 flannel-wrapper[2443]: I0514 23:50:07.123187 1 iptables.go:160] Adding iptables rule: -d 10.254.0.0/16 -j ACCEPT May 14 23:50:07.130905 flannel-wrapper[2443]: I0514 23:50:07.129075 1 iptables.go:160] Adding iptables rule: -s 10.254.0.0/16 ! -d 224.0.0.0/4 -j MASQUERADE --random-fully May 14 23:50:07.135379 flannel-wrapper[2443]: I0514 23:50:07.135073 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.86.0/24 -j RETURN May 14 23:50:07.140862 flannel-wrapper[2443]: I0514 23:50:07.140566 1 iptables.go:160] Adding iptables rule: ! -s 10.254.0.0/16 -d 10.254.0.0/16 -j MASQUERADE --random-fully May 14 23:50:07.156119 sh[2596]: Error response from daemon: No such container: flannel-docker-opts May 14 23:50:07.177981 sh[2607]: Error response from daemon: No such container: flannel-docker-opts May 14 23:50:07.292841 systemd[1]: var-lib-docker-overlay2-aa17d637d0e802da691ff3efab72f40fd82bb647cec837d36faedfc70fbfa297-merged.mount: Deactivated successfully. May 14 23:50:07.306041 containerd[1863]: time="2025-05-14T23:50:07.305413344Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:07.306041 containerd[1863]: time="2025-05-14T23:50:07.305861400Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:07.306041 containerd[1863]: time="2025-05-14T23:50:07.305912388Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:07.307347 containerd[1863]: time="2025-05-14T23:50:07.307166268Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:07.344229 systemd[1]: Started docker-25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5.scope - libcontainer container 25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5. May 14 23:50:07.403599 systemd[1]: docker-25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5.scope: Deactivated successfully. May 14 23:50:07.432825 containerd[1863]: time="2025-05-14T23:50:07.432643225Z" level=info msg="shim disconnected" id=25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5 namespace=moby May 14 23:50:07.432825 containerd[1863]: time="2025-05-14T23:50:07.432721669Z" level=warning msg="cleaning up after shim disconnected" id=25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5 namespace=moby May 14 23:50:07.432825 containerd[1863]: time="2025-05-14T23:50:07.432744733Z" level=info msg="cleaning up dead shim" namespace=moby May 14 23:50:07.433346 dockerd[2067]: time="2025-05-14T23:50:07.433272397Z" level=info msg="ignoring event" container=25ba159ccc4c4b0057375bc110c31b30a86cd181c2b65ba6ce3ba90da44b9bb5 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 23:50:07.455305 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.454Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb","error":"EOF"} May 14 23:50:07.456413 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.455Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb","error":"EOF"} May 14 23:50:07.471695 systemd[1]: var-lib-docker-overlay2-260c1b322eaa093e283d43b2f5a0f1c68a2795fc6e4f9aba84fe7b0cd34e66f2-merged.mount: Deactivated successfully. May 14 23:50:07.475441 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.474Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"830c55c15a1f45cb","error":"failed to dial 830c55c15a1f45cb on stream MsgApp v2 (peer 830c55c15a1f45cb failed to find local node 21c4bfb2f272665b)"} May 14 23:50:07.495917 dockerd[2067]: time="2025-05-14T23:50:07.495816541Z" level=info msg="Processing signal 'terminated'" May 14 23:50:07.495941 systemd[1]: Stopping docker.service - Docker Application Container Engine... May 14 23:50:07.512215 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.511Z","caller":"osutil/interrupt_unix.go:64","msg":"received signal; shutting down","signal":"terminated"} May 14 23:50:07.512215 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.511Z","caller":"embed/etcd.go:367","msg":"closing etcd server","name":"ec2b0092edc7383be82a716f58e91122","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"]} May 14 23:50:07.513182 etcd-wrapper[2278]: WARNING: 2025/05/14 23:50:07 [core] grpc: addrConn.createTransport failed to connect to {0.0.0.0:2379 0.0.0.0:2379 0 }. Err: connection error: desc = "transport: Error while dialing dial tcp 0.0.0.0:2379: connect: connection refused". Reconnecting... May 14 23:50:07.515833 flannel-wrapper[2443]: I0514 23:50:07.514898 1 main.go:376] shutdownHandler sent cancel signal... May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515207 1 watch.go:40] context canceled, close receiver chan May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515236 1 vxlan_network.go:74] evts chan closed May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515397 1 watch.go:174] context canceled, close receiver chan May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515414 1 main.go:432] Stopped monitoring lease May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515425 1 main.go:345] Waiting for all goroutines to exit May 14 23:50:07.518772 flannel-wrapper[2443]: I0514 23:50:07.515435 1 main.go:348] Exiting cleanly... May 14 23:50:07.519157 systemd[1]: docker-d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06.scope: Deactivated successfully. May 14 23:50:07.521097 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.518Z","caller":"etcdserver/server.go:1429","msg":"skipped leadership transfer; local server is not leader","local-member-id":"21c4bfb2f272665b","current-leader-member-id":"ab556f38458dc3e0"} May 14 23:50:07.521097 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.518Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.522433 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.521Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.525084 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.522Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.525590 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.525Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.526093 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.525Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.526876 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.526Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.526Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.526Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.526Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.526Z","caller":"rafthttp/peer.go:330","msg":"stopping remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.527Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.527Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.527Z","caller":"rafthttp/stream.go:286","msg":"closed TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.527Z","caller":"rafthttp/stream.go:294","msg":"stopped TCP streaming connection with remote peer","stream-writer-type":"stream Message","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.531128 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.527Z","caller":"rafthttp/pipeline.go:85","msg":"stopped HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0","error":"context canceled"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/peer_status.go:66","msg":"peer became inactive (message send to peer failed)","peer-id":"ab556f38458dc3e0","error":"failed to read ab556f38458dc3e0 on stream MsgApp v2 (context canceled)"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/stream.go:421","msg":"lost TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0","error":"context canceled"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/stream.go:442","msg":"stopped stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.532173 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.528Z","caller":"rafthttp/peer.go:335","msg":"stopped remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:07.534669 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.533Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"21c4bfb2f272665b","remote-peer-id-stream-handler":"21c4bfb2f272665b","remote-peer-id-from":"ab556f38458dc3e0","cluster-id":"8a7388d05ba84a1f"} May 14 23:50:07.534669 etcd-wrapper[2278]: {"level":"warn","ts":"2025-05-14T23:50:07.533Z","caller":"rafthttp/http.go:413","msg":"failed to find remote peer in cluster","local-member-id":"21c4bfb2f272665b","remote-peer-id-stream-handler":"21c4bfb2f272665b","remote-peer-id-from":"ab556f38458dc3e0","cluster-id":"8a7388d05ba84a1f"} May 14 23:50:07.536732 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.536Z","caller":"embed/etcd.go:562","msg":"stopping serving peer traffic","address":"172.31.21.175:2380"} May 14 23:50:07.557915 dockerd[2067]: time="2025-05-14T23:50:07.557843761Z" level=info msg="ignoring event" container=d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 23:50:07.559113 containerd[1863]: time="2025-05-14T23:50:07.559008745Z" level=info msg="shim disconnected" id=d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06 namespace=moby May 14 23:50:07.559113 containerd[1863]: time="2025-05-14T23:50:07.559108993Z" level=warning msg="cleaning up after shim disconnected" id=d07bcda395f4bbf245054b464d3a939c0ff62086085935bb7e9aa8820338dd06 namespace=moby May 14 23:50:07.560190 containerd[1863]: time="2025-05-14T23:50:07.559131181Z" level=info msg="cleaning up dead shim" namespace=moby May 14 23:50:07.592289 systemd[1]: var-lib-docker-overlay2-0b6512edc27fe36cb7cbaec59970719cbe86ff48f9d5ff5138a83524b3cce6d1-merged.mount: Deactivated successfully. May 14 23:50:07.677532 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.677Z","caller":"embed/etcd.go:567","msg":"stopped serving peer traffic","address":"172.31.21.175:2380"} May 14 23:50:07.677532 etcd-wrapper[2278]: {"level":"info","ts":"2025-05-14T23:50:07.677Z","caller":"embed/etcd.go:369","msg":"closed etcd server","name":"ec2b0092edc7383be82a716f58e91122","data-dir":"/var/lib/etcd","advertise-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"]} May 14 23:50:07.680801 systemd[1]: docker-559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09.scope: Deactivated successfully. May 14 23:50:07.706893 dockerd[2067]: time="2025-05-14T23:50:07.706725890Z" level=info msg="ignoring event" container=559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 23:50:07.708252 containerd[1863]: time="2025-05-14T23:50:07.708205130Z" level=info msg="shim disconnected" id=559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09 namespace=moby May 14 23:50:07.709669 containerd[1863]: time="2025-05-14T23:50:07.709416410Z" level=warning msg="cleaning up after shim disconnected" id=559e3dbdd0b77e142d3c7744c8782da65e18f2386fc1ab446814daba2f66dc09 namespace=moby May 14 23:50:07.709669 containerd[1863]: time="2025-05-14T23:50:07.709458578Z" level=info msg="cleaning up dead shim" namespace=moby May 14 23:50:07.745190 systemd[1]: var-lib-docker-overlay2-b6eaa37e52fd79f592c329df329fafaa9dbeec8a34cc26517f553a485255288c-merged.mount: Deactivated successfully. May 14 23:50:07.756616 dockerd[2067]: time="2025-05-14T23:50:07.756558122Z" level=info msg="stopping event stream following graceful shutdown" error="" module=libcontainerd namespace=moby May 14 23:50:07.757825 dockerd[2067]: time="2025-05-14T23:50:07.757767110Z" level=info msg="Daemon shutdown complete" May 14 23:50:07.780123 systemd[1]: docker.service: Deactivated successfully. May 14 23:50:07.782151 systemd[1]: Stopped docker.service - Docker Application Container Engine. May 14 23:50:07.782251 systemd[1]: docker.service: Consumed 6.120s CPU time, 352.2M memory peak. May 14 23:50:07.783929 systemd[1]: docker.socket: Deactivated successfully. May 14 23:50:07.786880 systemd[1]: Closed docker.socket - Docker Socket for the API. May 14 23:50:07.787772 docker[2732]: Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running? May 14 23:50:07.787777 systemd[1]: Stopping docker.socket - Docker Socket for the API... May 14 23:50:07.793225 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 23:50:07.797465 systemd[1]: flanneld.service: Control process exited, code=exited, status=1/FAILURE May 14 23:50:07.800587 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 23:50:07.819674 systemd[1]: Starting docker.service - Docker Application Container Engine... May 14 23:50:07.821421 (dockerd)[2772]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS May 14 23:50:07.886726 dockerd[2772]: time="2025-05-14T23:50:07.885808803Z" level=info msg="Starting up" May 14 23:50:07.932111 dockerd[2772]: time="2025-05-14T23:50:07.932048535Z" level=info msg="[graphdriver] using prior storage driver: overlay2" May 14 23:50:07.947622 dockerd[2772]: time="2025-05-14T23:50:07.947553651Z" level=info msg="Loading containers: start." May 14 23:50:08.427209 systemd-networkd[1703]: flannel.1: Gained IPv6LL May 14 23:50:08.491333 dockerd[2772]: time="2025-05-14T23:50:08.491262182Z" level=info msg="Loading containers: done." May 14 23:50:08.512311 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1847104985-merged.mount: Deactivated successfully. May 14 23:50:08.514672 dockerd[2772]: time="2025-05-14T23:50:08.514327646Z" 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 May 14 23:50:08.514672 dockerd[2772]: time="2025-05-14T23:50:08.514496366Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 May 14 23:50:08.514672 dockerd[2772]: time="2025-05-14T23:50:08.514587470Z" level=info msg="Daemon has completed initialization" May 14 23:50:08.554485 dockerd[2772]: time="2025-05-14T23:50:08.554391446Z" level=info msg="API listen on /run/docker.sock" May 14 23:50:08.555718 systemd[1]: Started docker.service - Docker Application Container Engine. May 14 23:50:08.559757 sh[2589]: Restarted Docker to apply Flannel options May 14 23:50:08.561292 sh[2579]: + break May 14 23:50:08.561292 sh[2579]: + '[' 1 '!=' fail ']' May 14 23:50:08.561646 docker[2761]: etcd-member May 14 23:50:08.563335 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). May 14 23:50:08.568578 docker[2770]: flannel May 14 23:50:08.569071 systemd[1]: Startup finished in 1.135s (kernel) + 7.247s (initrd) + 19.439s (userspace) = 27.822s. May 14 23:50:08.575867 systemd[1]: flanneld.service: Failed with result 'exit-code'. May 14 23:50:08.613028 docker[2966]: etcd-member May 14 23:50:08.617473 systemd[1]: etcd-member.service: Deactivated successfully. May 14 23:50:09.118482 systemd[1]: Started sshd@3-172.31.21.175:22-139.178.89.65:40684.service - OpenSSH per-connection server daemon (139.178.89.65:40684). May 14 23:50:09.303686 sshd[2979]: Accepted publickey for core from 139.178.89.65 port 40684 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:09.306292 sshd-session[2979]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:09.316026 systemd-logind[1849]: New session 4 of user core. May 14 23:50:09.323296 systemd[1]: Started session-4.scope - Session 4 of User core. May 14 23:50:09.451942 sshd[2981]: Connection closed by 139.178.89.65 port 40684 May 14 23:50:09.450799 sshd-session[2979]: pam_unix(sshd:session): session closed for user core May 14 23:50:09.458172 systemd[1]: sshd@3-172.31.21.175:22-139.178.89.65:40684.service: Deactivated successfully. May 14 23:50:09.461825 systemd[1]: session-4.scope: Deactivated successfully. May 14 23:50:09.463214 systemd-logind[1849]: Session 4 logged out. Waiting for processes to exit. May 14 23:50:09.465122 systemd-logind[1849]: Removed session 4. May 14 23:50:09.498829 systemd[1]: Started sshd@4-172.31.21.175:22-139.178.89.65:40692.service - OpenSSH per-connection server daemon (139.178.89.65:40692). May 14 23:50:09.680562 sshd[2987]: Accepted publickey for core from 139.178.89.65 port 40692 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:09.683118 sshd-session[2987]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:09.692421 systemd-logind[1849]: New session 5 of user core. May 14 23:50:09.699285 systemd[1]: Started session-5.scope - Session 5 of User core. May 14 23:50:09.820360 sshd[2989]: Connection closed by 139.178.89.65 port 40692 May 14 23:50:09.821153 sshd-session[2987]: pam_unix(sshd:session): session closed for user core May 14 23:50:09.826159 systemd-logind[1849]: Session 5 logged out. Waiting for processes to exit. May 14 23:50:09.828108 systemd[1]: sshd@4-172.31.21.175:22-139.178.89.65:40692.service: Deactivated successfully. May 14 23:50:09.831626 systemd[1]: session-5.scope: Deactivated successfully. May 14 23:50:09.835694 systemd-logind[1849]: Removed session 5. May 14 23:50:09.869477 systemd[1]: Started sshd@5-172.31.21.175:22-139.178.89.65:40694.service - OpenSSH per-connection server daemon (139.178.89.65:40694). May 14 23:50:10.051870 sshd[2995]: Accepted publickey for core from 139.178.89.65 port 40694 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:10.054886 sshd-session[2995]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:10.063367 systemd-logind[1849]: New session 6 of user core. May 14 23:50:10.074274 systemd[1]: Started session-6.scope - Session 6 of User core. May 14 23:50:10.201455 sshd[2997]: Connection closed by 139.178.89.65 port 40694 May 14 23:50:10.202340 sshd-session[2995]: pam_unix(sshd:session): session closed for user core May 14 23:50:10.208620 systemd[1]: sshd@5-172.31.21.175:22-139.178.89.65:40694.service: Deactivated successfully. May 14 23:50:10.213415 systemd[1]: session-6.scope: Deactivated successfully. May 14 23:50:10.215058 systemd-logind[1849]: Session 6 logged out. Waiting for processes to exit. May 14 23:50:10.216748 systemd-logind[1849]: Removed session 6. May 14 23:50:10.238325 systemd[1]: Started sshd@6-172.31.21.175:22-139.178.89.65:40710.service - OpenSSH per-connection server daemon (139.178.89.65:40710). May 14 23:50:10.437021 sshd[3003]: Accepted publickey for core from 139.178.89.65 port 40710 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:10.439769 sshd-session[3003]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:10.449204 systemd-logind[1849]: New session 7 of user core. May 14 23:50:10.461324 systemd[1]: Started session-7.scope - Session 7 of User core. May 14 23:50:10.584197 sudo[3006]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 14 23:50:10.584942 sudo[3006]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 23:50:10.587307 ntpd[1843]: Listen normally on 7 flannel.1 10.254.86.0:123 May 14 23:50:10.587436 ntpd[1843]: Listen normally on 8 flannel.1 [fe80::587a:faff:fe2f:d77d%4]:123 May 14 23:50:10.588205 ntpd[1843]: 14 May 23:50:10 ntpd[1843]: Listen normally on 7 flannel.1 10.254.86.0:123 May 14 23:50:10.588205 ntpd[1843]: 14 May 23:50:10 ntpd[1843]: Listen normally on 8 flannel.1 [fe80::587a:faff:fe2f:d77d%4]:123 May 14 23:50:10.605791 sudo[3006]: pam_unix(sudo:session): session closed for user root May 14 23:50:10.629721 sshd[3005]: Connection closed by 139.178.89.65 port 40710 May 14 23:50:10.631127 sshd-session[3003]: pam_unix(sshd:session): session closed for user core May 14 23:50:10.639395 systemd[1]: sshd@6-172.31.21.175:22-139.178.89.65:40710.service: Deactivated successfully. May 14 23:50:10.644044 systemd[1]: session-7.scope: Deactivated successfully. May 14 23:50:10.646304 systemd-logind[1849]: Session 7 logged out. Waiting for processes to exit. May 14 23:50:10.648845 systemd-logind[1849]: Removed session 7. May 14 23:50:10.678527 systemd[1]: Started sshd@7-172.31.21.175:22-139.178.89.65:40726.service - OpenSSH per-connection server daemon (139.178.89.65:40726). May 14 23:50:10.875327 sshd[3012]: Accepted publickey for core from 139.178.89.65 port 40726 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:10.878126 sshd-session[3012]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:10.887309 systemd-logind[1849]: New session 8 of user core. May 14 23:50:10.897317 systemd[1]: Started session-8.scope - Session 8 of User core. May 14 23:50:11.005175 sudo[3016]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 14 23:50:11.005857 sudo[3016]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 23:50:11.013073 sudo[3016]: pam_unix(sudo:session): session closed for user root May 14 23:50:11.023116 sudo[3015]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 14 23:50:11.023757 sudo[3015]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 23:50:11.053576 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 23:50:11.106357 augenrules[3038]: No rules May 14 23:50:11.109123 systemd[1]: audit-rules.service: Deactivated successfully. May 14 23:50:11.111062 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 23:50:11.113431 sudo[3015]: pam_unix(sudo:session): session closed for user root May 14 23:50:11.137236 sshd[3014]: Connection closed by 139.178.89.65 port 40726 May 14 23:50:11.138028 sshd-session[3012]: pam_unix(sshd:session): session closed for user core May 14 23:50:11.143798 systemd[1]: sshd@7-172.31.21.175:22-139.178.89.65:40726.service: Deactivated successfully. May 14 23:50:11.144378 systemd-logind[1849]: Session 8 logged out. Waiting for processes to exit. May 14 23:50:11.146762 systemd[1]: session-8.scope: Deactivated successfully. May 14 23:50:11.150576 systemd-logind[1849]: Removed session 8. May 14 23:50:18.619536 systemd[1]: flanneld.service: Scheduled restart job, restart counter is at 1. May 14 23:50:18.622048 systemd[1]: etcd-member.service: Scheduled restart job, restart counter is at 1. May 14 23:50:18.633386 systemd[1]: Stopping flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... May 14 23:50:18.656375 docker[3047]: flannel-docker-opts May 14 23:50:18.707576 docker[3054]: flannel-docker-opts May 14 23:50:18.711859 systemd[1]: flannel-docker-opts.service: Deactivated successfully. May 14 23:50:18.713997 systemd[1]: Stopped flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). May 14 23:50:18.715106 systemd[1]: Stopped flanneld.service - flannel - Network fabric for containers (System Application Container). May 14 23:50:18.721548 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 14 23:50:18.732939 (-wrapper)[3064]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 14 23:50:18.770896 etcd-wrapper[3068]: Error response from daemon: No such container: etcd-member May 14 23:50:18.796027 etcd-wrapper[3075]: Error response from daemon: No such container: etcd-member May 14 23:50:18.882336 systemd[1]: var-lib-docker-overlay2-b3d1e6da0aa518ec2ec7e55b16768aee858b72a3c711a00608f13ec4ffbb2c02-merged.mount: Deactivated successfully. May 14 23:50:18.964409 containerd[1863]: time="2025-05-14T23:50:18.963742802Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:18.964409 containerd[1863]: time="2025-05-14T23:50:18.963857774Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:18.964409 containerd[1863]: time="2025-05-14T23:50:18.963893258Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:18.964409 containerd[1863]: time="2025-05-14T23:50:18.964079834Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:18.995260 systemd[1]: Started docker-32f36ca084eb26b7f0a38e6ac7b464a59afd4c799524490ebd319ffbff0ca97a.scope - libcontainer container 32f36ca084eb26b7f0a38e6ac7b464a59afd4c799524490ebd319ffbff0ca97a. May 14 23:50:19.075875 etcd-wrapper[3093]: {"level":"info","ts":1747266619.0753858,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"info","ts":1747266619.075521,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2b0092edc7383be82a716f58e91122"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"warn","ts":1747266619.0755823,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"warn","ts":1747266619.0756001,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"warn","ts":1747266619.0756142,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"warn","ts":1747266619.0756261,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"warn","ts":1747266619.0756414,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.075Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://172.31.21.175:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://172.31.21.175:2380","--advertise-client-urls=http://172.31.21.175:2379","--discovery=https://discovery.etcd.io/9b303b34ac49e345b8305bea852102df","--enable-v2=true"]} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.075Z","caller":"etcdmain/etcd.go:115","msg":"server has been already initialized","data-dir":"/var/lib/etcd","dir-type":"member"} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.076Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://172.31.21.175:2380"]} May 14 23:50:19.077417 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.076Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 14 23:50:19.079816 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.076Z","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":true,"name":"ec2b0092edc7383be82a716f58e91122","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.21.175:2380"],"listen-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"","initial-cluster-state":"new","initial-cluster-token":"","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/9b303b34ac49e345b8305bea852102df","discovery-proxy":"","downgrade-check-interval":"5s"} May 14 23:50:19.079816 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.077Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"336.24µs"} May 14 23:50:19.079816 etcd-wrapper[3093]: {"level":"warn","ts":"2025-05-14T23:50:19.077Z","caller":"etcdserver/server.go:480","msg":"discovery token is ignored since cluster already initialized; valid logs are found","wal-dir":"/var/lib/etcd/member/wal"} May 14 23:50:19.079816 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.078Z","caller":"etcdserver/server.go:526","msg":"No snapshot found. Recovering WAL from scratch!"} May 14 23:50:19.079816 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.079Z","caller":"etcdserver/raft.go:483","msg":"restarting local member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","commit-index":30} May 14 23:50:19.080536 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=()"} May 14 23:50:19.080536 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b became follower at term 2"} May 14 23:50:19.080536 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21c4bfb2f272665b [peers: [], term: 2, commit: 30, applied: 0, lastindex: 30, lastterm: 2]"} May 14 23:50:19.081274 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.080Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 14 23:50:19.081274 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.080Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","recovered-remote-peer-id":"21c4bfb2f272665b","recovered-remote-peer-urls":["http://172.31.21.175:2380"]} May 14 23:50:19.081274 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.080Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","recovered-remote-peer-id":"830c55c15a1f45cb","recovered-remote-peer-urls":["http://172.31.19.163:2380"]} May 14 23:50:19.081274 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.080Z","caller":"membership/cluster.go:276","msg":"recovered/added member from store","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","recovered-remote-peer-id":"ab556f38458dc3e0","recovered-remote-peer-urls":["http://172.31.20.194:2380"]} May 14 23:50:19.081274 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.081Z","caller":"membership/cluster.go:285","msg":"set cluster version from store","cluster-version":"3.5"} May 14 23:50:19.083469 etcd-wrapper[3093]: {"level":"warn","ts":"2025-05-14T23:50:19.083Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} May 14 23:50:19.085254 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.085Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} May 14 23:50:19.087280 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.087Z","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"} May 14 23:50:19.088812 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.088Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.088812 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.088Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.092211 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.091Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.092Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.093Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.093Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.093Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb","remote-peer-urls":["http://172.31.19.163:2380"]} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.094Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.094Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.095255 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.094Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.097220 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.096Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.097969 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.097Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.097969 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.097Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.100058 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.097Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.100441 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.098Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0","remote-peer-urls":["http://172.31.20.194:2380"]} May 14 23:50:19.100441 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.098Z","caller":"etcdserver/server.go:834","msg":"starting etcd server","local-member-id":"21c4bfb2f272665b","local-server-version":"3.5.0","cluster-id":"8a7388d05ba84a1f","cluster-version":"3.5"} May 14 23:50:19.100441 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.099Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} May 14 23:50:19.100441 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.099Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.100927 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.100Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947)"} May 14 23:50:19.101072 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.100Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"21c4bfb2f272665b","added-peer-peer-urls":["http://172.31.21.175:2380"]} May 14 23:50:19.101464 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499)"} May 14 23:50:19.101812 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.101Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"830c55c15a1f45cb","added-peer-peer-urls":["http://172.31.19.163:2380"]} May 14 23:50:19.102244 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b switched to configuration voters=(2433280473979577947 9443016807618135499 12345896240981853152)"} May 14 23:50:19.103848 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.103Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","added-peer-id":"ab556f38458dc3e0","added-peer-peer-urls":["http://172.31.20.194:2380"]} May 14 23:50:19.104166 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.103Z","caller":"membership/cluster.go:523","msg":"updated cluster version","cluster-id":"8a7388d05ba84a1f","local-member-id":"21c4bfb2f272665b","from":"3.5","to":"3.5"} May 14 23:50:19.108192 etcd-wrapper[3093]: {"level":"warn","ts":"2025-05-14T23:50:19.107Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} May 14 23:50:19.109365 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.108Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"172.31.21.175:2380"} May 14 23:50:19.109365 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.109Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"172.31.21.175:2380"} May 14 23:50:19.109805 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.109Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"21c4bfb2f272665b","initial-advertise-peer-urls":["http://172.31.21.175:2380"],"listen-peer-urls":["http://172.31.21.175:2380"],"advertise-client-urls":["http://172.31.21.175:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 14 23:50:19.158890 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.158Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"ab556f38458dc3e0","stream-type":"stream MsgApp v2"} May 14 23:50:19.158890 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.158Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"ab556f38458dc3e0"} May 14 23:50:19.158890 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.158Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.162264 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.161Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"ab556f38458dc3e0","stream-type":"stream Message"} May 14 23:50:19.162549 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.161Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.202355 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.201Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.207533 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.207Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"ab556f38458dc3e0"} May 14 23:50:19.422269 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.421Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"830c55c15a1f45cb","stream-type":"stream Message"} May 14 23:50:19.422269 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.421Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"830c55c15a1f45cb"} May 14 23:50:19.422269 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.421Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"21c4bfb2f272665b","to":"830c55c15a1f45cb","stream-type":"stream MsgApp v2"} May 14 23:50:19.422269 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.421Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.422269 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.421Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.452457 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.452Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"21c4bfb2f272665b","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} May 14 23:50:19.469574 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [logterm: 2, index: 30, vote: ab556f38458dc3e0] cast MsgPreVote for ab556f38458dc3e0 [logterm: 2, index: 30] at term 2"} May 14 23:50:19.473127 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [term: 2] received a MsgVote message with higher term from ab556f38458dc3e0 [term: 3]"} May 14 23:50:19.473127 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b became follower at term 3"} May 14 23:50:19.473423 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21c4bfb2f272665b [logterm: 2, index: 30, vote: 0] cast MsgVote for ab556f38458dc3e0 [logterm: 2, index: 30] at term 3"} May 14 23:50:19.477096 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21c4bfb2f272665b elected leader ab556f38458dc3e0 at term 3"} May 14 23:50:19.484484 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.483Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"21c4bfb2f272665b","local-member-attributes":"{Name:ec2b0092edc7383be82a716f58e91122 ClientURLs:[http://172.31.21.175:2379]}","request-path":"/0/members/21c4bfb2f272665b/attributes","cluster-id":"8a7388d05ba84a1f","publish-timeout":"7s"} May 14 23:50:19.485327 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 14 23:50:19.487111 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.484Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} May 14 23:50:19.487111 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.484Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} May 14 23:50:19.487111 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.484Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} May 14 23:50:19.487111 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.485Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} May 14 23:50:19.497478 systemd[1]: Starting flanneld.service - flannel - Network fabric for containers (System Application Container)... May 14 23:50:19.499570 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.498Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.500756 etcd-wrapper[3093]: {"level":"info","ts":"2025-05-14T23:50:19.499Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"21c4bfb2f272665b","remote-peer-id":"830c55c15a1f45cb"} May 14 23:50:19.550612 etcdctl[3151]: { "Network": "10.254.0.0/16", "Backend": {"Type": "vxlan"} } May 14 23:50:19.603573 flannel-wrapper[3159]: Error response from daemon: No such container: flannel May 14 23:50:19.629930 flannel-wrapper[3166]: Error response from daemon: No such container: flannel May 14 23:50:19.762942 containerd[1863]: time="2025-05-14T23:50:19.761577818Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:19.762942 containerd[1863]: time="2025-05-14T23:50:19.762670370Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:19.762942 containerd[1863]: time="2025-05-14T23:50:19.762770894Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:19.762942 containerd[1863]: time="2025-05-14T23:50:19.763182686Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:19.798328 systemd[1]: Started docker-eeffeb6dc07fbb6165ff815c8c2e9197be2267a9b8e056a29de98624a25250d5.scope - libcontainer container eeffeb6dc07fbb6165ff815c8c2e9197be2267a9b8e056a29de98624a25250d5. May 14 23:50:19.890601 flannel-wrapper[3181]: I0514 23:50:19.889835 1 main.go:520] Determining IP address of default interface May 14 23:50:19.892001 flannel-wrapper[3181]: I0514 23:50:19.891502 1 main.go:533] Using interface with name eth0 and address 172.31.21.175 May 14 23:50:19.892001 flannel-wrapper[3181]: I0514 23:50:19.891558 1 main.go:550] Defaulting external address to interface address (172.31.21.175) May 14 23:50:19.892645 flannel-wrapper[3181]: I0514 23:50:19.891928 1 main.go:254] Created subnet manager: Etcd Local Manager with Previous Subnet: 10.254.86.0/24 May 14 23:50:19.892645 flannel-wrapper[3181]: I0514 23:50:19.892160 1 main.go:257] Installing signal handlers May 14 23:50:19.897616 flannel-wrapper[3181]: I0514 23:50:19.897360 1 main.go:392] Found network config - Backend type: vxlan May 14 23:50:19.898075 flannel-wrapper[3181]: I0514 23:50:19.897862 1 vxlan.go:123] VXLAN config: VNI=1 Port=0 GBP=false Learning=false DirectRouting=false May 14 23:50:19.908424 flannel-wrapper[3181]: I0514 23:50:19.907815 1 local_manager.go:147] Found lease (10.254.86.0/24) for current IP (172.31.21.175), reusing May 14 23:50:19.912616 flannel-wrapper[3181]: I0514 23:50:19.912252 1 main.go:307] Setting up masking rules May 14 23:50:19.943203 flannel-wrapper[3181]: I0514 23:50:19.940715 1 main.go:315] Changing default FORWARD chain policy to ACCEPT May 14 23:50:19.943203 flannel-wrapper[3181]: I0514 23:50:19.941049 1 main.go:323] Wrote subnet file to /run/flannel/subnet.env May 14 23:50:19.943203 flannel-wrapper[3181]: I0514 23:50:19.941077 1 main.go:327] Running backend. May 14 23:50:19.941710 systemd[1]: Started flanneld.service - flannel - Network fabric for containers (System Application Container). May 14 23:50:19.946175 flannel-wrapper[3181]: I0514 23:50:19.943940 1 vxlan_network.go:59] watching for new subnet leases May 14 23:50:19.952679 systemd[1]: Starting flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container)... May 14 23:50:19.954349 flannel-wrapper[3181]: I0514 23:50:19.950754 1 main.go:439] Waiting for 22h59m59.957855232s to renew lease May 14 23:50:19.973031 sh[3264]: + for try in 1 2 3 4 5 6 May 14 23:50:19.973031 sh[3264]: + /usr/lib/coreos/flannel-wrapper -d /run/flannel/flannel_docker_opts.env -i May 14 23:50:20.006824 sh[3275]: Error response from daemon: No such container: flannel-docker-opts May 14 23:50:20.035620 sh[3283]: Error response from daemon: No such container: flannel-docker-opts May 14 23:50:20.086259 systemd[1]: var-lib-docker-overlay2-24d928b420a7ac7bab9ba2b3f0b4177af15cb4ea9d69a1df0cff9559f39b3503\x2dinit-merged.mount: Deactivated successfully. May 14 23:50:20.174329 systemd[1]: Started sshd@8-172.31.21.175:22-139.178.89.65:55888.service - OpenSSH per-connection server daemon (139.178.89.65:55888). May 14 23:50:20.200308 containerd[1863]: time="2025-05-14T23:50:20.199825728Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 14 23:50:20.200308 containerd[1863]: time="2025-05-14T23:50:20.199945032Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 14 23:50:20.200308 containerd[1863]: time="2025-05-14T23:50:20.200009040Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:20.203840 containerd[1863]: time="2025-05-14T23:50:20.201653016Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 14 23:50:20.241326 systemd[1]: Started docker-dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f.scope - libcontainer container dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f. May 14 23:50:20.307413 systemd[1]: docker-dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f.scope: Deactivated successfully. May 14 23:50:20.338547 dockerd[2772]: time="2025-05-14T23:50:20.338308081Z" level=info msg="ignoring event" container=dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 14 23:50:20.340267 containerd[1863]: time="2025-05-14T23:50:20.339055693Z" level=info msg="shim disconnected" id=dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f namespace=moby May 14 23:50:20.340267 containerd[1863]: time="2025-05-14T23:50:20.339198769Z" level=warning msg="cleaning up after shim disconnected" id=dbd6e4834db2e839f781068fb6ecca480dbeaf05cc7b30dc96907d168273cf4f namespace=moby May 14 23:50:20.340267 containerd[1863]: time="2025-05-14T23:50:20.339222649Z" level=info msg="cleaning up dead shim" namespace=moby May 14 23:50:20.371578 sshd[3302]: Accepted publickey for core from 139.178.89.65 port 55888 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:20.376363 sshd-session[3302]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:20.390177 systemd-logind[1849]: New session 9 of user core. May 14 23:50:20.394933 sh[3264]: + break May 14 23:50:20.394933 sh[3264]: + '[' 1 '!=' fail ']' May 14 23:50:20.400297 systemd[1]: Started session-9.scope - Session 9 of User core. May 14 23:50:20.403075 systemd[1]: Finished flannel-docker-opts.service - flannel docker export service - Network fabric for containers (System Application Container). May 14 23:50:20.574876 sshd[3380]: Connection closed by 139.178.89.65 port 55888 May 14 23:50:20.576094 sshd-session[3302]: pam_unix(sshd:session): session closed for user core May 14 23:50:20.582637 systemd-logind[1849]: Session 9 logged out. Waiting for processes to exit. May 14 23:50:20.583568 systemd[1]: sshd@8-172.31.21.175:22-139.178.89.65:55888.service: Deactivated successfully. May 14 23:50:20.587939 systemd[1]: session-9.scope: Deactivated successfully. May 14 23:50:20.592727 systemd-logind[1849]: Removed session 9. May 14 23:50:20.627531 systemd[1]: Started sshd@9-172.31.21.175:22-139.178.89.65:55894.service - OpenSSH per-connection server daemon (139.178.89.65:55894). May 14 23:50:20.810825 sshd[3391]: Accepted publickey for core from 139.178.89.65 port 55894 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:20.813523 sshd-session[3391]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:20.822193 systemd-logind[1849]: New session 10 of user core. May 14 23:50:20.830287 systemd[1]: Started session-10.scope - Session 10 of User core. May 14 23:50:20.967811 sshd[3393]: Connection closed by 139.178.89.65 port 55894 May 14 23:50:20.969024 sshd-session[3391]: pam_unix(sshd:session): session closed for user core May 14 23:50:20.975474 systemd[1]: sshd@9-172.31.21.175:22-139.178.89.65:55894.service: Deactivated successfully. May 14 23:50:20.978924 systemd[1]: session-10.scope: Deactivated successfully. May 14 23:50:20.980341 systemd-logind[1849]: Session 10 logged out. Waiting for processes to exit. May 14 23:50:20.982668 systemd-logind[1849]: Removed session 10. May 14 23:50:21.420497 systemd[1]: Started sshd@10-172.31.21.175:22-139.178.89.65:55910.service - OpenSSH per-connection server daemon (139.178.89.65:55910). May 14 23:50:21.598607 sshd[3402]: Accepted publickey for core from 139.178.89.65 port 55910 ssh2: RSA SHA256:P5lx8LuVgYRnVINBokzXFUV2F/1CVpmkiH+0ahpdjwk May 14 23:50:21.601450 sshd-session[3402]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 23:50:21.612266 systemd-logind[1849]: New session 11 of user core. May 14 23:50:21.620333 systemd[1]: Started session-11.scope - Session 11 of User core. May 14 23:50:25.984186 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 14 23:50:30.975429 sshd[3404]: Connection closed by 139.178.89.65 port 55910 May 14 23:50:30.976379 sshd-session[3402]: pam_unix(sshd:session): session closed for user core May 14 23:50:30.982425 systemd-logind[1849]: Session 11 logged out. Waiting for processes to exit. May 14 23:50:30.983653 systemd[1]: sshd@10-172.31.21.175:22-139.178.89.65:55910.service: Deactivated successfully. May 14 23:50:30.986893 systemd[1]: session-11.scope: Deactivated successfully. May 14 23:50:30.991149 systemd-logind[1849]: Removed session 11.