May 15 23:39:55.180588 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] May 15 23:39:55.180633 kernel: Linux version 6.6.90-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT Thu May 15 22:19:24 -00 2025 May 15 23:39:55.180657 kernel: KASLR disabled due to lack of seed May 15 23:39:55.180673 kernel: efi: EFI v2.7 by EDK II May 15 23:39:55.180689 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b000a98 MEMRESERVE=0x78503d98 May 15 23:39:55.180704 kernel: secureboot: Secure boot disabled May 15 23:39:55.180721 kernel: ACPI: Early table checksum verification disabled May 15 23:39:55.180736 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) May 15 23:39:55.180752 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) May 15 23:39:55.180768 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) May 15 23:39:55.180787 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) May 15 23:39:55.180803 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) May 15 23:39:55.180818 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) May 15 23:39:55.180834 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) May 15 23:39:55.180852 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) May 15 23:39:55.180873 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) May 15 23:39:55.180889 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) May 15 23:39:55.180906 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) May 15 23:39:55.180945 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 May 15 23:39:55.180966 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') May 15 23:39:55.180983 kernel: printk: bootconsole [uart0] enabled May 15 23:39:55.181000 kernel: NUMA: Failed to initialise from firmware May 15 23:39:55.181017 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] May 15 23:39:55.181033 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] May 15 23:39:55.181049 kernel: Zone ranges: May 15 23:39:55.181065 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] May 15 23:39:55.181088 kernel: DMA32 empty May 15 23:39:55.181105 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] May 15 23:39:55.181121 kernel: Movable zone start for each node May 15 23:39:55.181137 kernel: Early memory node ranges May 15 23:39:55.181153 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] May 15 23:39:55.181169 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] May 15 23:39:55.181185 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] May 15 23:39:55.181200 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] May 15 23:39:55.181216 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] May 15 23:39:55.181232 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] May 15 23:39:55.181248 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] May 15 23:39:55.181264 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] May 15 23:39:55.181284 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] May 15 23:39:55.181301 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges May 15 23:39:55.181324 kernel: psci: probing for conduit method from ACPI. May 15 23:39:55.181341 kernel: psci: PSCIv1.0 detected in firmware. May 15 23:39:55.181357 kernel: psci: Using standard PSCI v0.2 function IDs May 15 23:39:55.181378 kernel: psci: Trusted OS migration not required May 15 23:39:55.181395 kernel: psci: SMC Calling Convention v1.1 May 15 23:39:55.181412 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 15 23:39:55.181429 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 15 23:39:55.181446 kernel: pcpu-alloc: [0] 0 [0] 1 May 15 23:39:55.181463 kernel: Detected PIPT I-cache on CPU0 May 15 23:39:55.181480 kernel: CPU features: detected: GIC system register CPU interface May 15 23:39:55.181497 kernel: CPU features: detected: Spectre-v2 May 15 23:39:55.181514 kernel: CPU features: detected: Spectre-v3a May 15 23:39:55.181531 kernel: CPU features: detected: Spectre-BHB May 15 23:39:55.181548 kernel: CPU features: detected: ARM erratum 1742098 May 15 23:39:55.181565 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 May 15 23:39:55.181586 kernel: alternatives: applying boot alternatives May 15 23:39:55.181605 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=a39d79b1d2ff9998339b60958cf17b8dfae5bd16f05fb844c0e06a5d7107915a May 15 23:39:55.181623 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 15 23:39:55.181640 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 15 23:39:55.181657 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 15 23:39:55.181674 kernel: Fallback order for Node 0: 0 May 15 23:39:55.181691 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 May 15 23:39:55.181708 kernel: Policy zone: Normal May 15 23:39:55.181724 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 15 23:39:55.181741 kernel: software IO TLB: area num 2. May 15 23:39:55.181763 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) May 15 23:39:55.181781 kernel: Memory: 3819896K/4030464K available (10240K kernel code, 2186K rwdata, 8108K rodata, 39744K init, 897K bss, 210568K reserved, 0K cma-reserved) May 15 23:39:55.181798 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 15 23:39:55.181815 kernel: rcu: Preemptible hierarchical RCU implementation. May 15 23:39:55.181833 kernel: rcu: RCU event tracing is enabled. May 15 23:39:55.181851 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 15 23:39:55.181868 kernel: Trampoline variant of Tasks RCU enabled. May 15 23:39:55.181885 kernel: Tracing variant of Tasks RCU enabled. May 15 23:39:55.181902 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 15 23:39:55.181919 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 15 23:39:55.181978 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 15 23:39:55.182003 kernel: GICv3: 96 SPIs implemented May 15 23:39:55.182021 kernel: GICv3: 0 Extended SPIs implemented May 15 23:39:55.182038 kernel: Root IRQ handler: gic_handle_irq May 15 23:39:55.183980 kernel: GICv3: GICv3 features: 16 PPIs May 15 23:39:55.183999 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 May 15 23:39:55.184016 kernel: ITS [mem 0x10080000-0x1009ffff] May 15 23:39:55.184034 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000b0000 (indirect, esz 8, psz 64K, shr 1) May 15 23:39:55.184051 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000c0000 (flat, esz 8, psz 64K, shr 1) May 15 23:39:55.184069 kernel: GICv3: using LPI property table @0x00000004000d0000 May 15 23:39:55.184086 kernel: ITS: Using hypervisor restricted LPI range [128] May 15 23:39:55.184103 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000e0000 May 15 23:39:55.184120 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 15 23:39:55.184145 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). May 15 23:39:55.184163 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns May 15 23:39:55.184181 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns May 15 23:39:55.184199 kernel: Console: colour dummy device 80x25 May 15 23:39:55.184217 kernel: printk: console [tty1] enabled May 15 23:39:55.184234 kernel: ACPI: Core revision 20230628 May 15 23:39:55.184252 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) May 15 23:39:55.184270 kernel: pid_max: default: 32768 minimum: 301 May 15 23:39:55.184288 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 15 23:39:55.184309 kernel: landlock: Up and running. May 15 23:39:55.184328 kernel: SELinux: Initializing. May 15 23:39:55.184345 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 23:39:55.184363 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 15 23:39:55.184415 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 15 23:39:55.184436 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 15 23:39:55.184454 kernel: rcu: Hierarchical SRCU implementation. May 15 23:39:55.184486 kernel: rcu: Max phase no-delay instances is 400. May 15 23:39:55.184509 kernel: Platform MSI: ITS@0x10080000 domain created May 15 23:39:55.184533 kernel: PCI/MSI: ITS@0x10080000 domain created May 15 23:39:55.184551 kernel: Remapping and enabling EFI services. May 15 23:39:55.184568 kernel: smp: Bringing up secondary CPUs ... May 15 23:39:55.184586 kernel: Detected PIPT I-cache on CPU1 May 15 23:39:55.184603 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 May 15 23:39:55.184621 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000004000f0000 May 15 23:39:55.184639 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] May 15 23:39:55.184656 kernel: smp: Brought up 1 node, 2 CPUs May 15 23:39:55.184673 kernel: SMP: Total of 2 processors activated. May 15 23:39:55.184694 kernel: CPU features: detected: 32-bit EL0 Support May 15 23:39:55.184712 kernel: CPU features: detected: 32-bit EL1 Support May 15 23:39:55.184730 kernel: CPU features: detected: CRC32 instructions May 15 23:39:55.184760 kernel: CPU: All CPU(s) started at EL1 May 15 23:39:55.184783 kernel: alternatives: applying system-wide alternatives May 15 23:39:55.184801 kernel: devtmpfs: initialized May 15 23:39:55.184820 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 15 23:39:55.184838 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 15 23:39:55.184856 kernel: pinctrl core: initialized pinctrl subsystem May 15 23:39:55.184874 kernel: SMBIOS 3.0.0 present. May 15 23:39:55.184897 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 May 15 23:39:55.184915 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 15 23:39:55.184977 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 15 23:39:55.184998 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 15 23:39:55.185017 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 15 23:39:55.185036 kernel: audit: initializing netlink subsys (disabled) May 15 23:39:55.185054 kernel: audit: type=2000 audit(0.223:1): state=initialized audit_enabled=0 res=1 May 15 23:39:55.185078 kernel: thermal_sys: Registered thermal governor 'step_wise' May 15 23:39:55.185096 kernel: cpuidle: using governor menu May 15 23:39:55.185114 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 15 23:39:55.185132 kernel: ASID allocator initialised with 65536 entries May 15 23:39:55.185151 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 15 23:39:55.185170 kernel: Serial: AMBA PL011 UART driver May 15 23:39:55.185188 kernel: Modules: 17424 pages in range for non-PLT usage May 15 23:39:55.185207 kernel: Modules: 508944 pages in range for PLT usage May 15 23:39:55.185225 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 15 23:39:55.185247 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 15 23:39:55.185265 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 15 23:39:55.185284 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 15 23:39:55.185302 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 15 23:39:55.185320 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 15 23:39:55.185339 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 15 23:39:55.185357 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 15 23:39:55.185375 kernel: ACPI: Added _OSI(Module Device) May 15 23:39:55.185393 kernel: ACPI: Added _OSI(Processor Device) May 15 23:39:55.185415 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 15 23:39:55.185433 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 15 23:39:55.185451 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 15 23:39:55.185469 kernel: ACPI: Interpreter enabled May 15 23:39:55.185487 kernel: ACPI: Using GIC for interrupt routing May 15 23:39:55.185505 kernel: ACPI: MCFG table detected, 1 entries May 15 23:39:55.185523 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) May 15 23:39:55.185811 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 15 23:39:55.186061 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 15 23:39:55.186261 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 15 23:39:55.186456 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 May 15 23:39:55.186659 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] May 15 23:39:55.186684 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] May 15 23:39:55.186703 kernel: acpiphp: Slot [1] registered May 15 23:39:55.186721 kernel: acpiphp: Slot [2] registered May 15 23:39:55.186739 kernel: acpiphp: Slot [3] registered May 15 23:39:55.186763 kernel: acpiphp: Slot [4] registered May 15 23:39:55.186781 kernel: acpiphp: Slot [5] registered May 15 23:39:55.186799 kernel: acpiphp: Slot [6] registered May 15 23:39:55.186817 kernel: acpiphp: Slot [7] registered May 15 23:39:55.186835 kernel: acpiphp: Slot [8] registered May 15 23:39:55.186853 kernel: acpiphp: Slot [9] registered May 15 23:39:55.186871 kernel: acpiphp: Slot [10] registered May 15 23:39:55.186889 kernel: acpiphp: Slot [11] registered May 15 23:39:55.186906 kernel: acpiphp: Slot [12] registered May 15 23:39:55.186942 kernel: acpiphp: Slot [13] registered May 15 23:39:55.186969 kernel: acpiphp: Slot [14] registered May 15 23:39:55.186987 kernel: acpiphp: Slot [15] registered May 15 23:39:55.187005 kernel: acpiphp: Slot [16] registered May 15 23:39:55.187022 kernel: acpiphp: Slot [17] registered May 15 23:39:55.187040 kernel: acpiphp: Slot [18] registered May 15 23:39:55.187058 kernel: acpiphp: Slot [19] registered May 15 23:39:55.187076 kernel: acpiphp: Slot [20] registered May 15 23:39:55.187094 kernel: acpiphp: Slot [21] registered May 15 23:39:55.187112 kernel: acpiphp: Slot [22] registered May 15 23:39:55.187134 kernel: acpiphp: Slot [23] registered May 15 23:39:55.187153 kernel: acpiphp: Slot [24] registered May 15 23:39:55.187171 kernel: acpiphp: Slot [25] registered May 15 23:39:55.187189 kernel: acpiphp: Slot [26] registered May 15 23:39:55.187207 kernel: acpiphp: Slot [27] registered May 15 23:39:55.187224 kernel: acpiphp: Slot [28] registered May 15 23:39:55.187242 kernel: acpiphp: Slot [29] registered May 15 23:39:55.187260 kernel: acpiphp: Slot [30] registered May 15 23:39:55.187278 kernel: acpiphp: Slot [31] registered May 15 23:39:55.187296 kernel: PCI host bridge to bus 0000:00 May 15 23:39:55.187508 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] May 15 23:39:55.187726 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 15 23:39:55.187914 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] May 15 23:39:55.188142 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] May 15 23:39:55.188380 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 May 15 23:39:55.188621 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 May 15 23:39:55.188847 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] May 15 23:39:55.189141 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 May 15 23:39:55.189343 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] May 15 23:39:55.189542 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold May 15 23:39:55.189752 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 May 15 23:39:55.190007 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] May 15 23:39:55.190229 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] May 15 23:39:55.190437 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] May 15 23:39:55.193161 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold May 15 23:39:55.193380 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] May 15 23:39:55.193584 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] May 15 23:39:55.193815 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] May 15 23:39:55.194704 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] May 15 23:39:55.194993 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] May 15 23:39:55.195206 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] May 15 23:39:55.195394 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 15 23:39:55.198197 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] May 15 23:39:55.198248 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 15 23:39:55.198268 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 15 23:39:55.198287 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 15 23:39:55.198306 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 15 23:39:55.198325 kernel: iommu: Default domain type: Translated May 15 23:39:55.198358 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 15 23:39:55.198376 kernel: efivars: Registered efivars operations May 15 23:39:55.198395 kernel: vgaarb: loaded May 15 23:39:55.198413 kernel: clocksource: Switched to clocksource arch_sys_counter May 15 23:39:55.198432 kernel: VFS: Disk quotas dquot_6.6.0 May 15 23:39:55.198451 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 15 23:39:55.198469 kernel: pnp: PnP ACPI init May 15 23:39:55.198709 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved May 15 23:39:55.198750 kernel: pnp: PnP ACPI: found 1 devices May 15 23:39:55.198770 kernel: NET: Registered PF_INET protocol family May 15 23:39:55.198790 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 15 23:39:55.198809 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 15 23:39:55.198828 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 15 23:39:55.198848 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 15 23:39:55.198867 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 15 23:39:55.198885 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 15 23:39:55.198904 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 23:39:55.200023 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 15 23:39:55.200057 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 15 23:39:55.200076 kernel: PCI: CLS 0 bytes, default 64 May 15 23:39:55.200094 kernel: kvm [1]: HYP mode not available May 15 23:39:55.200112 kernel: Initialise system trusted keyrings May 15 23:39:55.200131 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 15 23:39:55.200149 kernel: Key type asymmetric registered May 15 23:39:55.200167 kernel: Asymmetric key parser 'x509' registered May 15 23:39:55.200186 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 15 23:39:55.200215 kernel: io scheduler mq-deadline registered May 15 23:39:55.200233 kernel: io scheduler kyber registered May 15 23:39:55.200251 kernel: io scheduler bfq registered May 15 23:39:55.200538 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered May 15 23:39:55.200569 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 15 23:39:55.200588 kernel: ACPI: button: Power Button [PWRB] May 15 23:39:55.200607 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 May 15 23:39:55.200625 kernel: ACPI: button: Sleep Button [SLPB] May 15 23:39:55.200650 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 15 23:39:55.200670 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 May 15 23:39:55.200907 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) May 15 23:39:55.200958 kernel: printk: console [ttyS0] disabled May 15 23:39:55.200979 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A May 15 23:39:55.200998 kernel: printk: console [ttyS0] enabled May 15 23:39:55.201016 kernel: printk: bootconsole [uart0] disabled May 15 23:39:55.201034 kernel: thunder_xcv, ver 1.0 May 15 23:39:55.201052 kernel: thunder_bgx, ver 1.0 May 15 23:39:55.201077 kernel: nicpf, ver 1.0 May 15 23:39:55.201096 kernel: nicvf, ver 1.0 May 15 23:39:55.201332 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 15 23:39:55.201531 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-15T23:39:54 UTC (1747352394) May 15 23:39:55.201557 kernel: hid: raw HID events driver (C) Jiri Kosina May 15 23:39:55.201576 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available May 15 23:39:55.201595 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 15 23:39:55.201613 kernel: watchdog: Hard watchdog permanently disabled May 15 23:39:55.201636 kernel: NET: Registered PF_INET6 protocol family May 15 23:39:55.201655 kernel: Segment Routing with IPv6 May 15 23:39:55.201675 kernel: In-situ OAM (IOAM) with IPv6 May 15 23:39:55.201693 kernel: NET: Registered PF_PACKET protocol family May 15 23:39:55.201711 kernel: Key type dns_resolver registered May 15 23:39:55.201729 kernel: registered taskstats version 1 May 15 23:39:55.201747 kernel: Loading compiled-in X.509 certificates May 15 23:39:55.201766 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.90-flatcar: c5ee9c587519d4ef57ff0de9630e786a4c7faded' May 15 23:39:55.201784 kernel: Key type .fscrypt registered May 15 23:39:55.201806 kernel: Key type fscrypt-provisioning registered May 15 23:39:55.201824 kernel: ima: No TPM chip found, activating TPM-bypass! May 15 23:39:55.201843 kernel: ima: Allocated hash algorithm: sha1 May 15 23:39:55.201860 kernel: ima: No architecture policies found May 15 23:39:55.201879 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 15 23:39:55.201898 kernel: clk: Disabling unused clocks May 15 23:39:55.201916 kernel: Freeing unused kernel memory: 39744K May 15 23:39:55.205578 kernel: Run /init as init process May 15 23:39:55.205600 kernel: with arguments: May 15 23:39:55.205619 kernel: /init May 15 23:39:55.205648 kernel: with environment: May 15 23:39:55.205666 kernel: HOME=/ May 15 23:39:55.205685 kernel: TERM=linux May 15 23:39:55.205705 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 15 23:39:55.205730 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 15 23:39:55.205755 systemd[1]: Detected virtualization amazon. May 15 23:39:55.205775 systemd[1]: Detected architecture arm64. May 15 23:39:55.205801 systemd[1]: Running in initrd. May 15 23:39:55.205821 systemd[1]: No hostname configured, using default hostname. May 15 23:39:55.205841 systemd[1]: Hostname set to . May 15 23:39:55.205861 systemd[1]: Initializing machine ID from VM UUID. May 15 23:39:55.205881 systemd[1]: Queued start job for default target initrd.target. May 15 23:39:55.205901 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:39:55.205945 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:39:55.205973 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 15 23:39:55.206000 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 23:39:55.206021 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 15 23:39:55.206041 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 15 23:39:55.206064 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 15 23:39:55.206084 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 15 23:39:55.206104 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:39:55.206124 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 23:39:55.206149 systemd[1]: Reached target paths.target - Path Units. May 15 23:39:55.206168 systemd[1]: Reached target slices.target - Slice Units. May 15 23:39:55.206188 systemd[1]: Reached target swap.target - Swaps. May 15 23:39:55.206208 systemd[1]: Reached target timers.target - Timer Units. May 15 23:39:55.206228 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 15 23:39:55.206247 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 23:39:55.206267 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 15 23:39:55.206287 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 15 23:39:55.206307 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 23:39:55.206331 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 23:39:55.206351 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:39:55.206370 systemd[1]: Reached target sockets.target - Socket Units. May 15 23:39:55.206390 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 15 23:39:55.206410 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 23:39:55.206430 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 15 23:39:55.206451 systemd[1]: Starting systemd-fsck-usr.service... May 15 23:39:55.206471 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 23:39:55.206496 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 23:39:55.206516 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:39:55.206536 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 15 23:39:55.206603 systemd-journald[252]: Collecting audit messages is disabled. May 15 23:39:55.206654 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:39:55.206675 systemd[1]: Finished systemd-fsck-usr.service. May 15 23:39:55.206696 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 23:39:55.206716 systemd-journald[252]: Journal started May 15 23:39:55.206758 systemd-journald[252]: Runtime Journal (/run/log/journal/ec2a5159ce9aa3429e0219e46de2bffa) is 8.0M, max 75.3M, 67.3M free. May 15 23:39:55.202038 systemd-modules-load[253]: Inserted module 'overlay' May 15 23:39:55.219945 systemd[1]: Started systemd-journald.service - Journal Service. May 15 23:39:55.234227 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 23:39:55.244968 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 15 23:39:55.247961 kernel: Bridge firewalling registered May 15 23:39:55.248055 systemd-modules-load[253]: Inserted module 'br_netfilter' May 15 23:39:55.251398 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:39:55.252569 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 23:39:55.263239 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:39:55.284205 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 23:39:55.287608 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:39:55.299188 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 23:39:55.307429 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:39:55.335247 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 23:39:55.350328 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 23:39:55.354763 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:39:55.370413 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:39:55.388019 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 15 23:39:55.417464 dracut-cmdline[290]: dracut-dracut-053 May 15 23:39:55.426959 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlycon flatcar.first_boot=detected acpi=force flatcar.oem.id=ec2 modprobe.blacklist=xen_fbfront net.ifnames=0 nvme_core.io_timeout=4294967295 verity.usrhash=a39d79b1d2ff9998339b60958cf17b8dfae5bd16f05fb844c0e06a5d7107915a May 15 23:39:55.455578 systemd-resolved[283]: Positive Trust Anchors: May 15 23:39:55.458191 systemd-resolved[283]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 23:39:55.472635 systemd-resolved[283]: 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 15 23:39:55.560960 kernel: SCSI subsystem initialized May 15 23:39:55.567959 kernel: Loading iSCSI transport class v2.0-870. May 15 23:39:55.580977 kernel: iscsi: registered transport (tcp) May 15 23:39:55.603236 kernel: iscsi: registered transport (qla4xxx) May 15 23:39:55.603324 kernel: QLogic iSCSI HBA Driver May 15 23:39:55.682968 kernel: random: crng init done May 15 23:39:55.683313 systemd-resolved[283]: Defaulting to hostname 'linux'. May 15 23:39:55.687111 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 23:39:55.692245 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 23:39:55.711409 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 15 23:39:55.721238 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 15 23:39:55.762822 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 15 23:39:55.762895 kernel: device-mapper: uevent: version 1.0.3 May 15 23:39:55.764973 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 15 23:39:55.834004 kernel: raid6: neonx8 gen() 6631 MB/s May 15 23:39:55.850975 kernel: raid6: neonx4 gen() 6458 MB/s May 15 23:39:55.867956 kernel: raid6: neonx2 gen() 5416 MB/s May 15 23:39:55.884958 kernel: raid6: neonx1 gen() 3944 MB/s May 15 23:39:55.901964 kernel: raid6: int64x8 gen() 3798 MB/s May 15 23:39:55.918955 kernel: raid6: int64x4 gen() 3722 MB/s May 15 23:39:55.935955 kernel: raid6: int64x2 gen() 3597 MB/s May 15 23:39:55.953817 kernel: raid6: int64x1 gen() 2768 MB/s May 15 23:39:55.953879 kernel: raid6: using algorithm neonx8 gen() 6631 MB/s May 15 23:39:55.971778 kernel: raid6: .... xor() 4906 MB/s, rmw enabled May 15 23:39:55.971854 kernel: raid6: using neon recovery algorithm May 15 23:39:55.980349 kernel: xor: measuring software checksum speed May 15 23:39:55.980414 kernel: 8regs : 10975 MB/sec May 15 23:39:55.981475 kernel: 32regs : 11453 MB/sec May 15 23:39:55.982658 kernel: arm64_neon : 9513 MB/sec May 15 23:39:55.982699 kernel: xor: using function: 32regs (11453 MB/sec) May 15 23:39:56.067972 kernel: Btrfs loaded, zoned=no, fsverity=no May 15 23:39:56.086802 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 15 23:39:56.097238 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:39:56.142601 systemd-udevd[471]: Using default interface naming scheme 'v255'. May 15 23:39:56.152080 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:39:56.164224 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 15 23:39:56.200042 dracut-pre-trigger[476]: rd.md=0: removing MD RAID activation May 15 23:39:56.257999 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 15 23:39:56.271202 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 23:39:56.388580 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:39:56.408395 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 15 23:39:56.452863 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 15 23:39:56.457466 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 15 23:39:56.459810 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:39:56.474696 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 23:39:56.483183 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 15 23:39:56.543983 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 15 23:39:56.596294 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 15 23:39:56.596376 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) May 15 23:39:56.602998 kernel: ena 0000:00:05.0: ENA device version: 0.10 May 15 23:39:56.603346 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 May 15 23:39:56.607334 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 23:39:56.608252 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:39:56.615798 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:39:56.648673 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:78:26:db:42:ef May 15 23:39:56.618081 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 23:39:56.618214 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:39:56.618370 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:39:56.627137 (udev-worker)[531]: Network interface NamePolicy= disabled on kernel command line. May 15 23:39:56.628275 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:39:56.677571 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 May 15 23:39:56.677636 kernel: nvme nvme0: pci function 0000:00:04.0 May 15 23:39:56.687958 kernel: nvme nvme0: 2/0/0 default/read/poll queues May 15 23:39:56.691831 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:39:56.705036 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 15 23:39:56.705110 kernel: GPT:9289727 != 16777215 May 15 23:39:56.705136 kernel: GPT:Alternate GPT header not at the end of the disk. May 15 23:39:56.705160 kernel: GPT:9289727 != 16777215 May 15 23:39:56.706627 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 15 23:39:56.710060 kernel: GPT: Use GNU Parted to correct GPT errors. May 15 23:39:56.710095 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:39:56.743376 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:39:56.790375 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by (udev-worker) (520) May 15 23:39:56.843959 kernel: BTRFS: device fsid 462ff9f1-7a02-4839-b355-edf30dab0598 devid 1 transid 39 /dev/nvme0n1p3 scanned by (udev-worker) (535) May 15 23:39:56.895212 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. May 15 23:39:56.914335 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. May 15 23:39:56.929902 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 15 23:39:56.957269 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. May 15 23:39:56.960415 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. May 15 23:39:56.982394 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 15 23:39:56.996527 disk-uuid[664]: Primary Header is updated. May 15 23:39:56.996527 disk-uuid[664]: Secondary Entries is updated. May 15 23:39:56.996527 disk-uuid[664]: Secondary Header is updated. May 15 23:39:57.009010 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:39:58.023062 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 15 23:39:58.025381 disk-uuid[666]: The operation has completed successfully. May 15 23:39:58.199453 systemd[1]: disk-uuid.service: Deactivated successfully. May 15 23:39:58.199681 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 15 23:39:58.249180 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 15 23:39:58.258298 sh[926]: Success May 15 23:39:58.281982 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 15 23:39:58.401659 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 15 23:39:58.408978 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 15 23:39:58.418133 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 15 23:39:58.452098 kernel: BTRFS info (device dm-0): first mount of filesystem 462ff9f1-7a02-4839-b355-edf30dab0598 May 15 23:39:58.452160 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 15 23:39:58.453946 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 15 23:39:58.455327 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 15 23:39:58.456676 kernel: BTRFS info (device dm-0): using free space tree May 15 23:39:58.573956 kernel: BTRFS info (device dm-0): enabling ssd optimizations May 15 23:39:58.620700 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 15 23:39:58.623461 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 15 23:39:58.635160 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 15 23:39:58.639690 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 15 23:39:58.682517 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:39:58.682592 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:39:58.682629 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:39:58.690011 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:39:58.706802 systemd[1]: mnt-oem.mount: Deactivated successfully. May 15 23:39:58.709221 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:39:58.717031 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 15 23:39:58.730369 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 15 23:39:58.814014 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 23:39:58.828276 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 23:39:58.883369 systemd-networkd[1118]: lo: Link UP May 15 23:39:58.883394 systemd-networkd[1118]: lo: Gained carrier May 15 23:39:58.888115 systemd-networkd[1118]: Enumeration completed May 15 23:39:58.890123 systemd-networkd[1118]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:39:58.890131 systemd-networkd[1118]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 23:39:58.890210 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 23:39:58.901110 systemd[1]: Reached target network.target - Network. May 15 23:39:58.903492 systemd-networkd[1118]: eth0: Link UP May 15 23:39:58.903500 systemd-networkd[1118]: eth0: Gained carrier May 15 23:39:58.903518 systemd-networkd[1118]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:39:58.936001 systemd-networkd[1118]: eth0: DHCPv4 address 172.31.21.66/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 15 23:39:59.129105 ignition[1049]: Ignition 2.20.0 May 15 23:39:59.129136 ignition[1049]: Stage: fetch-offline May 15 23:39:59.129575 ignition[1049]: no configs at "/usr/lib/ignition/base.d" May 15 23:39:59.129600 ignition[1049]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:39:59.131134 ignition[1049]: Ignition finished successfully May 15 23:39:59.140011 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 15 23:39:59.152765 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 15 23:39:59.176163 ignition[1127]: Ignition 2.20.0 May 15 23:39:59.176186 ignition[1127]: Stage: fetch May 15 23:39:59.176762 ignition[1127]: no configs at "/usr/lib/ignition/base.d" May 15 23:39:59.176786 ignition[1127]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:39:59.183210 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:39:59.192632 ignition[1127]: PUT result: OK May 15 23:39:59.195351 ignition[1127]: parsed url from cmdline: "" May 15 23:39:59.195372 ignition[1127]: no config URL provided May 15 23:39:59.195388 ignition[1127]: reading system config file "/usr/lib/ignition/user.ign" May 15 23:39:59.195413 ignition[1127]: no config at "/usr/lib/ignition/user.ign" May 15 23:39:59.195446 ignition[1127]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:39:59.199187 ignition[1127]: PUT result: OK May 15 23:39:59.199261 ignition[1127]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 May 15 23:39:59.201457 ignition[1127]: GET result: OK May 15 23:39:59.201536 ignition[1127]: parsing config with SHA512: b02fc46dfe1318a672510589d8deff86c9658d51825c0ab3fcf78d42bf3e80a1c262031ae8978c40ad2f4be42386f04033c23779acf7453582a1f6d2591f06fb May 15 23:39:59.215387 unknown[1127]: fetched base config from "system" May 15 23:39:59.215414 unknown[1127]: fetched base config from "system" May 15 23:39:59.217082 ignition[1127]: fetch: fetch complete May 15 23:39:59.215428 unknown[1127]: fetched user config from "aws" May 15 23:39:59.217094 ignition[1127]: fetch: fetch passed May 15 23:39:59.217181 ignition[1127]: Ignition finished successfully May 15 23:39:59.226776 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 15 23:39:59.243100 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 15 23:39:59.266983 ignition[1134]: Ignition 2.20.0 May 15 23:39:59.267471 ignition[1134]: Stage: kargs May 15 23:39:59.268089 ignition[1134]: no configs at "/usr/lib/ignition/base.d" May 15 23:39:59.268114 ignition[1134]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:39:59.268291 ignition[1134]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:39:59.272273 ignition[1134]: PUT result: OK May 15 23:39:59.279816 ignition[1134]: kargs: kargs passed May 15 23:39:59.279908 ignition[1134]: Ignition finished successfully May 15 23:39:59.285842 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 15 23:39:59.293234 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 15 23:39:59.322445 ignition[1141]: Ignition 2.20.0 May 15 23:39:59.322476 ignition[1141]: Stage: disks May 15 23:39:59.323482 ignition[1141]: no configs at "/usr/lib/ignition/base.d" May 15 23:39:59.323508 ignition[1141]: no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:39:59.323660 ignition[1141]: PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:39:59.325452 ignition[1141]: PUT result: OK May 15 23:39:59.334391 ignition[1141]: disks: disks passed May 15 23:39:59.334494 ignition[1141]: Ignition finished successfully May 15 23:39:59.336227 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 15 23:39:59.344116 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 15 23:39:59.346439 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 15 23:39:59.348827 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 23:39:59.350788 systemd[1]: Reached target sysinit.target - System Initialization. May 15 23:39:59.352996 systemd[1]: Reached target basic.target - Basic System. May 15 23:39:59.373268 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 15 23:39:59.410024 systemd-fsck[1150]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 15 23:39:59.414160 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 15 23:39:59.431326 systemd[1]: Mounting sysroot.mount - /sysroot... May 15 23:39:59.512959 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 759e3456-2e58-4307-81e1-19f20d3141c2 r/w with ordered data mode. Quota mode: none. May 15 23:39:59.514688 systemd[1]: Mounted sysroot.mount - /sysroot. May 15 23:39:59.517257 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 15 23:39:59.531307 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 23:39:59.539084 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 15 23:39:59.547445 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 15 23:39:59.552247 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 15 23:39:59.565235 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/nvme0n1p6 scanned by mount (1169) May 15 23:39:59.552297 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 15 23:39:59.560678 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 15 23:39:59.575085 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:39:59.575120 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:39:59.575145 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:39:59.580230 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 15 23:39:59.593952 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:39:59.597249 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 23:39:59.982037 initrd-setup-root[1193]: cut: /sysroot/etc/passwd: No such file or directory May 15 23:39:59.991149 initrd-setup-root[1200]: cut: /sysroot/etc/group: No such file or directory May 15 23:39:59.999611 initrd-setup-root[1207]: cut: /sysroot/etc/shadow: No such file or directory May 15 23:40:00.007338 initrd-setup-root[1214]: cut: /sysroot/etc/gshadow: No such file or directory May 15 23:40:00.192100 systemd-networkd[1118]: eth0: Gained IPv6LL May 15 23:40:00.360686 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 15 23:40:00.375108 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 15 23:40:00.382226 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 15 23:40:00.397328 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 15 23:40:00.399540 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:40:00.442843 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 15 23:40:00.450647 ignition[1281]: INFO : Ignition 2.20.0 May 15 23:40:00.450647 ignition[1281]: INFO : Stage: mount May 15 23:40:00.453846 ignition[1281]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:40:00.453846 ignition[1281]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:40:00.457986 ignition[1281]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:40:00.460775 ignition[1281]: INFO : PUT result: OK May 15 23:40:00.464969 ignition[1281]: INFO : mount: mount passed May 15 23:40:00.464969 ignition[1281]: INFO : Ignition finished successfully May 15 23:40:00.471984 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 15 23:40:00.482245 systemd[1]: Starting ignition-files.service - Ignition (files)... May 15 23:40:00.524690 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 15 23:40:00.546964 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 scanned by mount (1293) May 15 23:40:00.550892 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem bb522e90-8598-4687-8a48-65ed6b798a46 May 15 23:40:00.550949 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm May 15 23:40:00.550977 kernel: BTRFS info (device nvme0n1p6): using free space tree May 15 23:40:00.556960 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations May 15 23:40:00.560878 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 15 23:40:00.595814 ignition[1310]: INFO : Ignition 2.20.0 May 15 23:40:00.595814 ignition[1310]: INFO : Stage: files May 15 23:40:00.599030 ignition[1310]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:40:00.599030 ignition[1310]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:40:00.599030 ignition[1310]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:40:00.605945 ignition[1310]: INFO : PUT result: OK May 15 23:40:00.609732 ignition[1310]: DEBUG : files: compiled without relabeling support, skipping May 15 23:40:00.612976 ignition[1310]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 15 23:40:00.612976 ignition[1310]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 15 23:40:00.644875 ignition[1310]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 15 23:40:00.647539 ignition[1310]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 15 23:40:00.650870 unknown[1310]: wrote ssh authorized keys file for user: core May 15 23:40:00.653044 ignition[1310]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 15 23:40:00.658754 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 15 23:40:00.662101 ignition[1310]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 15 23:40:00.662101 ignition[1310]: INFO : files: op(4): [started] processing unit "etcd-member.service" May 15 23:40:00.662101 ignition[1310]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 15 23:40:00.671977 ignition[1310]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" May 15 23:40:00.671977 ignition[1310]: INFO : files: op(4): [finished] processing unit "etcd-member.service" May 15 23:40:00.671977 ignition[1310]: INFO : files: op(6): [started] setting preset to enabled for "etcd-member.service" May 15 23:40:00.671977 ignition[1310]: INFO : files: op(6): [finished] setting preset to enabled for "etcd-member.service" May 15 23:40:00.671977 ignition[1310]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" May 15 23:40:00.671977 ignition[1310]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" May 15 23:40:00.671977 ignition[1310]: INFO : files: files passed May 15 23:40:00.671977 ignition[1310]: INFO : Ignition finished successfully May 15 23:40:00.694578 systemd[1]: Finished ignition-files.service - Ignition (files). May 15 23:40:00.703204 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 15 23:40:00.716263 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 15 23:40:00.727767 systemd[1]: ignition-quench.service: Deactivated successfully. May 15 23:40:00.731081 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 15 23:40:00.746819 initrd-setup-root-after-ignition[1339]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 23:40:00.746819 initrd-setup-root-after-ignition[1339]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 15 23:40:00.755713 initrd-setup-root-after-ignition[1343]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 15 23:40:00.763019 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 23:40:00.766305 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 15 23:40:00.776162 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 15 23:40:00.832799 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 15 23:40:00.833056 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 15 23:40:00.837205 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 15 23:40:00.839287 systemd[1]: Reached target initrd.target - Initrd Default Target. May 15 23:40:00.841332 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 15 23:40:00.864707 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 15 23:40:00.908887 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 23:40:00.930318 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 15 23:40:00.953310 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 15 23:40:00.958077 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:40:00.962430 systemd[1]: Stopped target timers.target - Timer Units. May 15 23:40:00.964696 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 15 23:40:00.964991 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 15 23:40:00.971361 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 15 23:40:00.975499 systemd[1]: Stopped target basic.target - Basic System. May 15 23:40:00.980637 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 15 23:40:00.984829 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 15 23:40:00.988326 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 15 23:40:00.992753 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 15 23:40:00.995281 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 15 23:40:01.004146 systemd[1]: Stopped target sysinit.target - System Initialization. May 15 23:40:01.006276 systemd[1]: Stopped target local-fs.target - Local File Systems. May 15 23:40:01.008535 systemd[1]: Stopped target swap.target - Swaps. May 15 23:40:01.015519 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 15 23:40:01.015967 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 15 23:40:01.021961 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 15 23:40:01.026210 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:40:01.030858 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 15 23:40:01.033186 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:40:01.038452 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 15 23:40:01.039376 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 15 23:40:01.044614 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 15 23:40:01.045037 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 15 23:40:01.052290 systemd[1]: ignition-files.service: Deactivated successfully. May 15 23:40:01.052685 systemd[1]: Stopped ignition-files.service - Ignition (files). May 15 23:40:01.079107 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 15 23:40:01.086416 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 15 23:40:01.090807 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 15 23:40:01.093553 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:40:01.098667 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 15 23:40:01.098945 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 15 23:40:01.112196 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 15 23:40:01.124023 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 15 23:40:01.134255 ignition[1363]: INFO : Ignition 2.20.0 May 15 23:40:01.136129 ignition[1363]: INFO : Stage: umount May 15 23:40:01.137590 ignition[1363]: INFO : no configs at "/usr/lib/ignition/base.d" May 15 23:40:01.139532 ignition[1363]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" May 15 23:40:01.139532 ignition[1363]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 May 15 23:40:01.144878 ignition[1363]: INFO : PUT result: OK May 15 23:40:01.149736 ignition[1363]: INFO : umount: umount passed May 15 23:40:01.149736 ignition[1363]: INFO : Ignition finished successfully May 15 23:40:01.154708 systemd[1]: ignition-mount.service: Deactivated successfully. May 15 23:40:01.154997 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 15 23:40:01.159728 systemd[1]: ignition-disks.service: Deactivated successfully. May 15 23:40:01.159820 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 15 23:40:01.163313 systemd[1]: ignition-kargs.service: Deactivated successfully. May 15 23:40:01.163408 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 15 23:40:01.165413 systemd[1]: ignition-fetch.service: Deactivated successfully. May 15 23:40:01.165496 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 15 23:40:01.168981 systemd[1]: Stopped target network.target - Network. May 15 23:40:01.174739 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 15 23:40:01.174849 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 15 23:40:01.199300 systemd[1]: Stopped target paths.target - Path Units. May 15 23:40:01.201797 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 15 23:40:01.216586 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:40:01.218935 systemd[1]: Stopped target slices.target - Slice Units. May 15 23:40:01.220684 systemd[1]: Stopped target sockets.target - Socket Units. May 15 23:40:01.222576 systemd[1]: iscsid.socket: Deactivated successfully. May 15 23:40:01.222654 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 15 23:40:01.224561 systemd[1]: iscsiuio.socket: Deactivated successfully. May 15 23:40:01.224628 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 15 23:40:01.226878 systemd[1]: ignition-setup.service: Deactivated successfully. May 15 23:40:01.226982 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 15 23:40:01.229458 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 15 23:40:01.229536 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 15 23:40:01.253078 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 15 23:40:01.256280 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 15 23:40:01.258013 systemd-networkd[1118]: eth0: DHCPv6 lease lost May 15 23:40:01.265664 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 15 23:40:01.266769 systemd[1]: systemd-networkd.service: Deactivated successfully. May 15 23:40:01.267051 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 15 23:40:01.270898 systemd[1]: sysroot-boot.service: Deactivated successfully. May 15 23:40:01.271422 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 15 23:40:01.280369 systemd[1]: systemd-resolved.service: Deactivated successfully. May 15 23:40:01.281130 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 15 23:40:01.290614 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 15 23:40:01.290722 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 15 23:40:01.294524 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 15 23:40:01.294616 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 15 23:40:01.313312 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 15 23:40:01.315479 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 15 23:40:01.315586 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 15 23:40:01.319513 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 15 23:40:01.319609 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 15 23:40:01.331986 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 15 23:40:01.332092 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 15 23:40:01.334144 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 15 23:40:01.334223 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:40:01.337984 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:40:01.366792 systemd[1]: network-cleanup.service: Deactivated successfully. May 15 23:40:01.367419 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 15 23:40:01.384018 systemd[1]: systemd-udevd.service: Deactivated successfully. May 15 23:40:01.385855 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:40:01.403335 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 15 23:40:01.403441 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 15 23:40:01.407243 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 15 23:40:01.407311 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:40:01.409290 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 15 23:40:01.409375 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 15 23:40:01.410464 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 15 23:40:01.410541 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 15 23:40:01.411328 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 15 23:40:01.411403 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 15 23:40:01.427604 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 15 23:40:01.441620 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 15 23:40:01.441744 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:40:01.444067 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 15 23:40:01.444163 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:40:01.446732 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 15 23:40:01.446815 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:40:01.461447 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 15 23:40:01.461561 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:40:01.464360 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 15 23:40:01.464554 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 15 23:40:01.469475 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 15 23:40:01.490175 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 15 23:40:01.507216 systemd[1]: Switching root. May 15 23:40:01.540496 systemd-journald[252]: Journal stopped May 15 23:40:03.789515 systemd-journald[252]: Received SIGTERM from PID 1 (systemd). May 15 23:40:03.789655 kernel: SELinux: policy capability network_peer_controls=1 May 15 23:40:03.789698 kernel: SELinux: policy capability open_perms=1 May 15 23:40:03.789735 kernel: SELinux: policy capability extended_socket_class=1 May 15 23:40:03.789765 kernel: SELinux: policy capability always_check_network=0 May 15 23:40:03.789795 kernel: SELinux: policy capability cgroup_seclabel=1 May 15 23:40:03.789824 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 15 23:40:03.789854 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 15 23:40:03.789884 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 15 23:40:03.789912 kernel: audit: type=1403 audit(1747352401.983:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 15 23:40:03.793840 systemd[1]: Successfully loaded SELinux policy in 81.546ms. May 15 23:40:03.793910 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.799ms. May 15 23:40:03.794089 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 15 23:40:03.794129 systemd[1]: Detected virtualization amazon. May 15 23:40:03.794162 systemd[1]: Detected architecture arm64. May 15 23:40:03.794195 systemd[1]: Detected first boot. May 15 23:40:03.794227 systemd[1]: Initializing machine ID from VM UUID. May 15 23:40:03.794264 zram_generator::config[1406]: No configuration found. May 15 23:40:03.794299 systemd[1]: Populated /etc with preset unit settings. May 15 23:40:03.794334 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 15 23:40:03.794371 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 15 23:40:03.794404 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 15 23:40:03.794434 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 15 23:40:03.794465 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 15 23:40:03.794495 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 15 23:40:03.794530 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 15 23:40:03.794564 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 15 23:40:03.794594 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 15 23:40:03.794625 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 15 23:40:03.794657 systemd[1]: Created slice user.slice - User and Session Slice. May 15 23:40:03.794688 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 15 23:40:03.794761 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 15 23:40:03.794794 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 15 23:40:03.794824 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 15 23:40:03.794869 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 15 23:40:03.794901 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 15 23:40:03.795057 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 15 23:40:03.795094 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 15 23:40:03.795124 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 15 23:40:03.795155 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 15 23:40:03.795184 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 15 23:40:03.795218 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 15 23:40:03.795249 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 15 23:40:03.795280 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 15 23:40:03.795311 systemd[1]: Reached target slices.target - Slice Units. May 15 23:40:03.795343 systemd[1]: Reached target swap.target - Swaps. May 15 23:40:03.795371 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 15 23:40:03.795402 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 15 23:40:03.795432 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 15 23:40:03.795462 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 15 23:40:03.795492 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 15 23:40:03.795526 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 15 23:40:03.795557 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 15 23:40:03.795585 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 15 23:40:03.795614 systemd[1]: Mounting media.mount - External Media Directory... May 15 23:40:03.795643 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 15 23:40:03.795671 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 15 23:40:03.795703 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 15 23:40:03.795733 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 15 23:40:03.795766 systemd[1]: Reached target machines.target - Containers. May 15 23:40:03.795795 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 15 23:40:03.795827 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:40:03.795856 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 15 23:40:03.795885 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 15 23:40:03.795917 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 23:40:03.804037 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 23:40:03.804072 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 23:40:03.804103 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 15 23:40:03.804142 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 23:40:03.804176 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 15 23:40:03.804206 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 15 23:40:03.804236 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 15 23:40:03.804265 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 15 23:40:03.804296 systemd[1]: Stopped systemd-fsck-usr.service. May 15 23:40:03.804327 systemd[1]: Starting systemd-journald.service - Journal Service... May 15 23:40:03.804357 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 15 23:40:03.804393 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 15 23:40:03.804423 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 15 23:40:03.804474 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 15 23:40:03.804510 systemd[1]: verity-setup.service: Deactivated successfully. May 15 23:40:03.804542 systemd[1]: Stopped verity-setup.service. May 15 23:40:03.804571 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 15 23:40:03.804600 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 15 23:40:03.804629 systemd[1]: Mounted media.mount - External Media Directory. May 15 23:40:03.804659 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 15 23:40:03.804695 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 15 23:40:03.804724 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 15 23:40:03.804754 kernel: ACPI: bus type drm_connector registered May 15 23:40:03.804784 kernel: fuse: init (API version 7.39) May 15 23:40:03.804812 kernel: loop: module loaded May 15 23:40:03.804846 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 15 23:40:03.804875 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 15 23:40:03.804904 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 15 23:40:03.804957 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 23:40:03.804990 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 23:40:03.805022 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 23:40:03.805051 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 23:40:03.805081 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 23:40:03.805109 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 23:40:03.805145 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 15 23:40:03.805176 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 15 23:40:03.805210 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 23:40:03.805240 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 23:40:03.805268 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 15 23:40:03.805304 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 15 23:40:03.805343 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 15 23:40:03.805373 systemd[1]: Reached target network-pre.target - Preparation for Network. May 15 23:40:03.805449 systemd-journald[1484]: Collecting audit messages is disabled. May 15 23:40:03.805500 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 15 23:40:03.805531 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 15 23:40:03.805561 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 15 23:40:03.805594 systemd-journald[1484]: Journal started May 15 23:40:03.805641 systemd-journald[1484]: Runtime Journal (/run/log/journal/ec2a5159ce9aa3429e0219e46de2bffa) is 8.0M, max 75.3M, 67.3M free. May 15 23:40:03.189667 systemd[1]: Queued start job for default target multi-user.target. May 15 23:40:03.808370 systemd[1]: Reached target local-fs.target - Local File Systems. May 15 23:40:03.239453 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. May 15 23:40:03.240252 systemd[1]: systemd-journald.service: Deactivated successfully. May 15 23:40:03.816174 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 15 23:40:03.839133 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 15 23:40:03.846547 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 15 23:40:03.846639 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:40:03.864074 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 15 23:40:03.868949 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 23:40:03.877981 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 15 23:40:03.882989 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 23:40:03.900781 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 15 23:40:03.913457 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 15 23:40:03.926138 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 15 23:40:03.933981 systemd[1]: Started systemd-journald.service - Journal Service. May 15 23:40:03.939112 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 15 23:40:03.941751 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 15 23:40:03.944315 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 15 23:40:03.946956 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 15 23:40:03.951614 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 15 23:40:04.008232 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 15 23:40:04.011077 kernel: loop0: detected capacity change from 0 to 113536 May 15 23:40:04.025108 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 15 23:40:04.038972 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 15 23:40:04.045018 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 15 23:40:04.071893 systemd-journald[1484]: Time spent on flushing to /var/log/journal/ec2a5159ce9aa3429e0219e46de2bffa is 150.510ms for 894 entries. May 15 23:40:04.071893 systemd-journald[1484]: System Journal (/var/log/journal/ec2a5159ce9aa3429e0219e46de2bffa) is 8.0M, max 195.6M, 187.6M free. May 15 23:40:04.239867 systemd-journald[1484]: Received client request to flush runtime journal. May 15 23:40:04.239982 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 15 23:40:04.240035 kernel: loop1: detected capacity change from 0 to 116808 May 15 23:40:04.115785 systemd-tmpfiles[1518]: ACLs are not supported, ignoring. May 15 23:40:04.115810 systemd-tmpfiles[1518]: ACLs are not supported, ignoring. May 15 23:40:04.151021 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 15 23:40:04.164633 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 15 23:40:04.169205 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 15 23:40:04.188405 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 15 23:40:04.221140 udevadm[1549]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 15 23:40:04.245352 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 15 23:40:04.273631 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 15 23:40:04.282687 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 15 23:40:04.322200 kernel: loop2: detected capacity change from 0 to 53784 May 15 23:40:04.333053 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 15 23:40:04.349106 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 15 23:40:04.391515 kernel: loop3: detected capacity change from 0 to 113536 May 15 23:40:04.415966 kernel: loop4: detected capacity change from 0 to 116808 May 15 23:40:04.436245 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. May 15 23:40:04.437084 kernel: loop5: detected capacity change from 0 to 53784 May 15 23:40:04.437436 systemd-tmpfiles[1561]: ACLs are not supported, ignoring. May 15 23:40:04.457064 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 15 23:40:04.465186 (sd-merge)[1564]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-ami'. May 15 23:40:04.466054 (sd-merge)[1564]: Merged extensions into '/usr'. May 15 23:40:04.472192 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 15 23:40:04.486434 systemd[1]: Starting ensure-sysext.service... May 15 23:40:04.501158 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 15 23:40:04.544274 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 15 23:40:04.545078 systemd[1]: Reloading requested from client PID 1567 ('systemctl') (unit ensure-sysext.service)... May 15 23:40:04.545111 systemd[1]: Reloading... May 15 23:40:04.546952 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 15 23:40:04.551202 systemd-tmpfiles[1568]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 15 23:40:04.552675 systemd-tmpfiles[1568]: ACLs are not supported, ignoring. May 15 23:40:04.553243 systemd-tmpfiles[1568]: ACLs are not supported, ignoring. May 15 23:40:04.566698 systemd-tmpfiles[1568]: Detected autofs mount point /boot during canonicalization of boot. May 15 23:40:04.566728 systemd-tmpfiles[1568]: Skipping /boot May 15 23:40:04.609161 systemd-tmpfiles[1568]: Detected autofs mount point /boot during canonicalization of boot. May 15 23:40:04.609188 systemd-tmpfiles[1568]: Skipping /boot May 15 23:40:04.715044 zram_generator::config[1596]: No configuration found. May 15 23:40:05.018159 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 15 23:40:05.141477 systemd[1]: Reloading finished in 595 ms. May 15 23:40:05.183730 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 15 23:40:05.218293 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 23:40:05.233346 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 15 23:40:05.243770 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 15 23:40:05.252315 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 15 23:40:05.269976 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 15 23:40:05.287408 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:40:05.292081 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 15 23:40:05.303158 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 15 23:40:05.317484 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 15 23:40:05.319692 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:40:05.335197 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 15 23:40:05.339091 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 15 23:40:05.356519 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:40:05.357120 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:40:05.364535 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 15 23:40:05.382047 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 15 23:40:05.393966 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 15 23:40:05.396413 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 15 23:40:05.396825 systemd[1]: Reached target time-set.target - System Time Set. May 15 23:40:05.400857 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 15 23:40:05.401689 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 15 23:40:05.405147 systemd[1]: modprobe@loop.service: Deactivated successfully. May 15 23:40:05.405444 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 15 23:40:05.416341 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 15 23:40:05.418438 systemd[1]: Finished ensure-sysext.service. May 15 23:40:05.429968 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 15 23:40:05.438798 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 15 23:40:05.442623 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 15 23:40:05.454582 systemd[1]: modprobe@drm.service: Deactivated successfully. May 15 23:40:05.455995 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 15 23:40:05.534190 augenrules[1685]: No rules May 15 23:40:05.536529 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 15 23:40:05.539380 systemd[1]: audit-rules.service: Deactivated successfully. May 15 23:40:05.543096 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 23:40:05.618003 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 15 23:40:05.622703 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 15 23:40:05.646988 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 15 23:40:05.660300 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 15 23:40:05.679310 systemd-resolved[1652]: Positive Trust Anchors: May 15 23:40:05.679999 systemd-resolved[1652]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 15 23:40:05.680071 systemd-resolved[1652]: 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 15 23:40:05.685525 ldconfig[1510]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 15 23:40:05.690310 systemd-resolved[1652]: Defaulting to hostname 'linux'. May 15 23:40:05.693618 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 15 23:40:05.696565 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 15 23:40:05.699896 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 15 23:40:05.711324 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 15 23:40:05.735194 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 15 23:40:05.741249 systemd-udevd[1698]: Using default interface naming scheme 'v255'. May 15 23:40:05.788716 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 15 23:40:05.800422 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 15 23:40:05.935673 systemd-networkd[1705]: lo: Link UP May 15 23:40:05.935699 systemd-networkd[1705]: lo: Gained carrier May 15 23:40:05.937071 systemd-networkd[1705]: Enumeration completed May 15 23:40:05.937235 systemd[1]: Started systemd-networkd.service - Network Configuration. May 15 23:40:05.939454 systemd[1]: Reached target network.target - Network. May 15 23:40:05.952950 (udev-worker)[1714]: Network interface NamePolicy= disabled on kernel command line. May 15 23:40:05.969689 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 15 23:40:05.972415 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 15 23:40:06.040879 systemd-networkd[1705]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:40:06.041455 systemd-networkd[1705]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 15 23:40:06.045640 systemd-networkd[1705]: eth0: Link UP May 15 23:40:06.046031 systemd-networkd[1705]: eth0: Gained carrier May 15 23:40:06.046063 systemd-networkd[1705]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 15 23:40:06.060084 systemd-networkd[1705]: eth0: DHCPv4 address 172.31.21.66/20, gateway 172.31.16.1 acquired from 172.31.16.1 May 15 23:40:06.188988 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (1713) May 15 23:40:06.210989 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 15 23:40:06.377045 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 15 23:40:06.400285 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. May 15 23:40:06.406013 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 15 23:40:06.414255 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 15 23:40:06.430762 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 15 23:40:06.455582 lvm[1825]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 23:40:06.467740 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 15 23:40:06.494745 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 15 23:40:06.498313 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 15 23:40:06.500528 systemd[1]: Reached target sysinit.target - System Initialization. May 15 23:40:06.502699 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 15 23:40:06.505045 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 15 23:40:06.507737 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 15 23:40:06.509973 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 15 23:40:06.512320 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 15 23:40:06.514634 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 15 23:40:06.514679 systemd[1]: Reached target paths.target - Path Units. May 15 23:40:06.516308 systemd[1]: Reached target timers.target - Timer Units. May 15 23:40:06.519648 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 15 23:40:06.525887 systemd[1]: Starting docker.socket - Docker Socket for the API... May 15 23:40:06.533412 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 15 23:40:06.537720 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 15 23:40:06.541235 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 15 23:40:06.544389 systemd[1]: Reached target sockets.target - Socket Units. May 15 23:40:06.548204 systemd[1]: Reached target basic.target - Basic System. May 15 23:40:06.550053 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 15 23:40:06.550109 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 15 23:40:06.562455 systemd[1]: Starting containerd.service - containerd container runtime... May 15 23:40:06.569309 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 15 23:40:06.574028 lvm[1832]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 15 23:40:06.583244 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 15 23:40:06.589177 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 15 23:40:06.600572 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 15 23:40:06.604093 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 15 23:40:06.610278 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 15 23:40:06.616599 systemd[1]: Started ntpd.service - Network Time Service. May 15 23:40:06.644032 jq[1836]: false May 15 23:40:06.636375 systemd[1]: Starting setup-oem.service - Setup OEM... May 15 23:40:06.655333 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 15 23:40:06.663204 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 15 23:40:06.680265 systemd[1]: Starting systemd-logind.service - User Login Management... May 15 23:40:06.683729 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 15 23:40:06.685878 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 15 23:40:06.690232 systemd[1]: Starting update-engine.service - Update Engine... May 15 23:40:06.696144 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 15 23:40:06.717683 dbus-daemon[1835]: [system] SELinux support is enabled May 15 23:40:06.702868 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 15 23:40:06.724378 dbus-daemon[1835]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1705 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") May 15 23:40:06.706026 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 15 23:40:06.718649 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 15 23:40:06.730125 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 15 23:40:06.731290 dbus-daemon[1835]: [system] Successfully activated service 'org.freedesktop.systemd1' May 15 23:40:06.732049 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 15 23:40:06.734502 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 15 23:40:06.734535 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 15 23:40:06.747397 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 15 23:40:06.768031 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 15 23:40:06.798089 ntpd[1839]: ntpd 4.2.8p17@1.4004-o Thu May 15 21:40:26 UTC 2025 (1): Starting May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: ntpd 4.2.8p17@1.4004-o Thu May 15 21:40:26 UTC 2025 (1): Starting May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: ---------------------------------------------------- May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: ntp-4 is maintained by Network Time Foundation, May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: corporation. Support and training for ntp-4 are May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: available at https://www.nwtime.org/support May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: ---------------------------------------------------- May 15 23:40:06.808315 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: proto: precision = 0.096 usec (-23) May 15 23:40:06.807728 (ntainerd)[1860]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 15 23:40:06.798148 ntpd[1839]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp May 15 23:40:06.829595 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: basedate set to 2025-05-03 May 15 23:40:06.829595 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: gps base set to 2025-05-04 (week 2365) May 15 23:40:06.829595 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listen and drop on 0 v6wildcard [::]:123 May 15 23:40:06.829595 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 15 23:40:06.826686 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 15 23:40:06.829854 coreos-metadata[1834]: May 15 23:40:06.823 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 15 23:40:06.829854 coreos-metadata[1834]: May 15 23:40:06.826 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 May 15 23:40:06.829854 coreos-metadata[1834]: May 15 23:40:06.827 INFO Fetch successful May 15 23:40:06.829854 coreos-metadata[1834]: May 15 23:40:06.827 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 May 15 23:40:06.798167 ntpd[1839]: ---------------------------------------------------- May 15 23:40:06.834386 coreos-metadata[1834]: May 15 23:40:06.832 INFO Fetch successful May 15 23:40:06.830039 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 15 23:40:06.798186 ntpd[1839]: ntp-4 is maintained by Network Time Foundation, May 15 23:40:06.798204 ntpd[1839]: Inc. (NTF), a non-profit 501(c)(3) public-benefit May 15 23:40:06.798221 ntpd[1839]: corporation. Support and training for ntp-4 are May 15 23:40:06.844354 coreos-metadata[1834]: May 15 23:40:06.834 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listen normally on 2 lo 127.0.0.1:123 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listen normally on 3 eth0 172.31.21.66:123 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listen normally on 4 lo [::1]:123 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: bind(21) AF_INET6 fe80::478:26ff:fedb:42ef%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: unable to create socket on eth0 (5) for fe80::478:26ff:fedb:42ef%2#123 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: failed to init interface for address fe80::478:26ff:fedb:42ef%2 May 15 23:40:06.844475 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: Listening on routing socket on fd #21 for interface updates May 15 23:40:06.798239 ntpd[1839]: available at https://www.nwtime.org/support May 15 23:40:06.798257 ntpd[1839]: ---------------------------------------------------- May 15 23:40:06.806250 ntpd[1839]: proto: precision = 0.096 usec (-23) May 15 23:40:06.811502 ntpd[1839]: basedate set to 2025-05-03 May 15 23:40:06.811532 ntpd[1839]: gps base set to 2025-05-04 (week 2365) May 15 23:40:06.826190 ntpd[1839]: Listen and drop on 0 v6wildcard [::]:123 May 15 23:40:06.826267 ntpd[1839]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 15 23:40:06.837839 ntpd[1839]: Listen normally on 2 lo 127.0.0.1:123 May 15 23:40:06.837910 ntpd[1839]: Listen normally on 3 eth0 172.31.21.66:123 May 15 23:40:06.838002 ntpd[1839]: Listen normally on 4 lo [::1]:123 May 15 23:40:06.838082 ntpd[1839]: bind(21) AF_INET6 fe80::478:26ff:fedb:42ef%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:40:06.838121 ntpd[1839]: unable to create socket on eth0 (5) for fe80::478:26ff:fedb:42ef%2#123 May 15 23:40:06.838149 ntpd[1839]: failed to init interface for address fe80::478:26ff:fedb:42ef%2 May 15 23:40:06.838202 ntpd[1839]: Listening on routing socket on fd #21 for interface updates May 15 23:40:06.850973 coreos-metadata[1834]: May 15 23:40:06.847 INFO Fetch successful May 15 23:40:06.850973 coreos-metadata[1834]: May 15 23:40:06.847 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 May 15 23:40:06.850973 coreos-metadata[1834]: May 15 23:40:06.849 INFO Fetch successful May 15 23:40:06.850973 coreos-metadata[1834]: May 15 23:40:06.849 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 May 15 23:40:06.851219 jq[1847]: true May 15 23:40:06.861375 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:40:06.861375 ntpd[1839]: 15 May 23:40:06 ntpd[1839]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:40:06.861483 coreos-metadata[1834]: May 15 23:40:06.853 INFO Fetch failed with 404: resource not found May 15 23:40:06.861483 coreos-metadata[1834]: May 15 23:40:06.853 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 May 15 23:40:06.861483 coreos-metadata[1834]: May 15 23:40:06.856 INFO Fetch successful May 15 23:40:06.861483 coreos-metadata[1834]: May 15 23:40:06.856 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 May 15 23:40:06.856652 ntpd[1839]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:40:06.856702 ntpd[1839]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 15 23:40:06.866962 coreos-metadata[1834]: May 15 23:40:06.862 INFO Fetch successful May 15 23:40:06.866962 coreos-metadata[1834]: May 15 23:40:06.862 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 May 15 23:40:06.875991 coreos-metadata[1834]: May 15 23:40:06.869 INFO Fetch successful May 15 23:40:06.875991 coreos-metadata[1834]: May 15 23:40:06.869 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 May 15 23:40:06.875991 coreos-metadata[1834]: May 15 23:40:06.871 INFO Fetch successful May 15 23:40:06.875991 coreos-metadata[1834]: May 15 23:40:06.871 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 May 15 23:40:06.875991 coreos-metadata[1834]: May 15 23:40:06.875 INFO Fetch successful May 15 23:40:06.901520 update_engine[1846]: I20250515 23:40:06.898404 1846 main.cc:92] Flatcar Update Engine starting May 15 23:40:06.913355 systemd[1]: Started update-engine.service - Update Engine. May 15 23:40:06.923437 update_engine[1846]: I20250515 23:40:06.923004 1846 update_check_scheduler.cc:74] Next update check in 2m10s May 15 23:40:06.944058 extend-filesystems[1837]: Found loop3 May 15 23:40:06.944058 extend-filesystems[1837]: Found loop4 May 15 23:40:06.944058 extend-filesystems[1837]: Found loop5 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p1 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p2 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p3 May 15 23:40:06.944058 extend-filesystems[1837]: Found usr May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p4 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p6 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p7 May 15 23:40:06.944058 extend-filesystems[1837]: Found nvme0n1p9 May 15 23:40:06.944058 extend-filesystems[1837]: Checking size of /dev/nvme0n1p9 May 15 23:40:06.933494 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 15 23:40:07.012754 jq[1869]: true May 15 23:40:06.948133 systemd[1]: motdgen.service: Deactivated successfully. May 15 23:40:06.948506 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 15 23:40:06.970597 systemd[1]: Finished setup-oem.service - Setup OEM. May 15 23:40:07.054331 extend-filesystems[1837]: Resized partition /dev/nvme0n1p9 May 15 23:40:07.066430 extend-filesystems[1892]: resize2fs 1.47.1 (20-May-2024) May 15 23:40:07.075604 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 15 23:40:07.078178 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 15 23:40:07.080988 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks May 15 23:40:07.096530 systemd-logind[1845]: Watching system buttons on /dev/input/event0 (Power Button) May 15 23:40:07.097033 systemd-logind[1845]: Watching system buttons on /dev/input/event1 (Sleep Button) May 15 23:40:07.097669 systemd-logind[1845]: New seat seat0. May 15 23:40:07.101505 systemd[1]: Started systemd-logind.service - User Login Management. May 15 23:40:07.186944 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 May 15 23:40:07.207003 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (1715) May 15 23:40:07.207949 extend-filesystems[1892]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required May 15 23:40:07.207949 extend-filesystems[1892]: old_desc_blocks = 1, new_desc_blocks = 1 May 15 23:40:07.207949 extend-filesystems[1892]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. May 15 23:40:07.216760 extend-filesystems[1837]: Resized filesystem in /dev/nvme0n1p9 May 15 23:40:07.219622 systemd[1]: extend-filesystems.service: Deactivated successfully. May 15 23:40:07.220007 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 15 23:40:07.238879 bash[1913]: Updated "/home/core/.ssh/authorized_keys" May 15 23:40:07.245371 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 15 23:40:07.274038 systemd[1]: Starting sshkeys.service... May 15 23:40:07.316756 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. May 15 23:40:07.366224 dbus-daemon[1835]: [system] Successfully activated service 'org.freedesktop.hostname1' May 15 23:40:07.366963 dbus-daemon[1835]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1854 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") May 15 23:40:07.368672 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... May 15 23:40:07.373428 systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 15 23:40:07.384647 systemd[1]: Starting polkit.service - Authorization Manager... May 15 23:40:07.418282 polkitd[1943]: Started polkitd version 121 May 15 23:40:07.437477 polkitd[1943]: Loading rules from directory /etc/polkit-1/rules.d May 15 23:40:07.445100 polkitd[1943]: Loading rules from directory /usr/share/polkit-1/rules.d May 15 23:40:07.446494 polkitd[1943]: Finished loading, compiling and executing 2 rules May 15 23:40:07.452540 dbus-daemon[1835]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 15 23:40:07.453143 systemd[1]: Started polkit.service - Authorization Manager. May 15 23:40:07.455204 polkitd[1943]: Acquired the name org.freedesktop.PolicyKit1 on the system bus May 15 23:40:07.474621 locksmithd[1880]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 15 23:40:07.517386 systemd-hostnamed[1854]: Hostname set to (transient) May 15 23:40:07.519538 systemd-resolved[1652]: System hostname changed to 'ip-172-31-21-66'. May 15 23:40:07.576307 containerd[1860]: time="2025-05-15T23:40:07.576160424Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 15 23:40:07.609882 coreos-metadata[1933]: May 15 23:40:07.609 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 May 15 23:40:07.613668 coreos-metadata[1933]: May 15 23:40:07.613 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 May 15 23:40:07.615969 coreos-metadata[1933]: May 15 23:40:07.615 INFO Fetch successful May 15 23:40:07.615969 coreos-metadata[1933]: May 15 23:40:07.615 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 May 15 23:40:07.618736 coreos-metadata[1933]: May 15 23:40:07.618 INFO Fetch successful May 15 23:40:07.626757 unknown[1933]: wrote ssh authorized keys file for user: core May 15 23:40:07.718975 update-ssh-keys[2014]: Updated "/home/core/.ssh/authorized_keys" May 15 23:40:07.732018 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). May 15 23:40:07.756005 systemd[1]: Finished sshkeys.service. May 15 23:40:07.765838 containerd[1860]: time="2025-05-15T23:40:07.765442893Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.774142 containerd[1860]: time="2025-05-15T23:40:07.773847033Z" 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.90-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 15 23:40:07.774142 containerd[1860]: time="2025-05-15T23:40:07.773955297Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 15 23:40:07.774554 containerd[1860]: time="2025-05-15T23:40:07.774456225Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 15 23:40:07.775039 containerd[1860]: time="2025-05-15T23:40:07.774948837Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 15 23:40:07.775039 containerd[1860]: time="2025-05-15T23:40:07.775002093Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.775967 containerd[1860]: time="2025-05-15T23:40:07.775187685Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 15 23:40:07.775967 containerd[1860]: time="2025-05-15T23:40:07.775251657Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.776212 containerd[1860]: time="2025-05-15T23:40:07.776161029Z" 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 15 23:40:07.776719 containerd[1860]: time="2025-05-15T23:40:07.776665401Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.776789 containerd[1860]: time="2025-05-15T23:40:07.776750625Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 15 23:40:07.776789 containerd[1860]: time="2025-05-15T23:40:07.776778993Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.779322 containerd[1860]: time="2025-05-15T23:40:07.779228589Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.780074 containerd[1860]: time="2025-05-15T23:40:07.780016857Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 15 23:40:07.781573 containerd[1860]: time="2025-05-15T23:40:07.781503381Z" 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 15 23:40:07.781573 containerd[1860]: time="2025-05-15T23:40:07.781563165Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 15 23:40:07.781830 containerd[1860]: time="2025-05-15T23:40:07.781784673Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 15 23:40:07.781948 containerd[1860]: time="2025-05-15T23:40:07.781901229Z" level=info msg="metadata content store policy set" policy=shared May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796357390Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796476502Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796512550Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796549426Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796584658Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.796841110Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797282938Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797485702Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797520586Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797553490Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797584918Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797614042Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797643826Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 15 23:40:07.797977 containerd[1860]: time="2025-05-15T23:40:07.797673622Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797705470Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797734330Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797763154Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797790766Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797830426Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797864050Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 15 23:40:07.798651 containerd[1860]: time="2025-05-15T23:40:07.797893198Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 15 23:40:07.798848 ntpd[1839]: bind(24) AF_INET6 fe80::478:26ff:fedb:42ef%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:40:07.799340 ntpd[1839]: 15 May 23:40:07 ntpd[1839]: bind(24) AF_INET6 fe80::478:26ff:fedb:42ef%2#123 flags 0x11 failed: Cannot assign requested address May 15 23:40:07.799340 ntpd[1839]: 15 May 23:40:07 ntpd[1839]: unable to create socket on eth0 (6) for fe80::478:26ff:fedb:42ef%2#123 May 15 23:40:07.799340 ntpd[1839]: 15 May 23:40:07 ntpd[1839]: failed to init interface for address fe80::478:26ff:fedb:42ef%2 May 15 23:40:07.798899 ntpd[1839]: unable to create socket on eth0 (6) for fe80::478:26ff:fedb:42ef%2#123 May 15 23:40:07.798951 ntpd[1839]: failed to init interface for address fe80::478:26ff:fedb:42ef%2 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.800969374Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801037510Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801076690Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801105094Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801135202Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801175570Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801210418Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801239542Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801268366Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801297046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801328942Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801378778Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801425302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803038 containerd[1860]: time="2025-05-15T23:40:07.801453214Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801582298Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801618406Z" 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 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801643642Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801693166Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801719470Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801749794Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801772834Z" level=info msg="NRI interface is disabled by configuration." May 15 23:40:07.803754 containerd[1860]: time="2025-05-15T23:40:07.801797974Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 15 23:40:07.804132 containerd[1860]: time="2025-05-15T23:40:07.802335706Z" 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 15 23:40:07.804132 containerd[1860]: time="2025-05-15T23:40:07.802422994Z" level=info msg="Connect containerd service" May 15 23:40:07.804132 containerd[1860]: time="2025-05-15T23:40:07.802485886Z" level=info msg="using legacy CRI server" May 15 23:40:07.804132 containerd[1860]: time="2025-05-15T23:40:07.802503358Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 15 23:40:07.804132 containerd[1860]: time="2025-05-15T23:40:07.802743274Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.805692346Z" 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 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.805910746Z" level=info msg="Start subscribing containerd event" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.805998718Z" level=info msg="Start recovering state" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.806114194Z" level=info msg="Start event monitor" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.806804446Z" level=info msg="Start snapshots syncer" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.806883490Z" level=info msg="Start cni network conf syncer for default" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.807219070Z" level=info msg="Start streaming server" May 15 23:40:07.807960 containerd[1860]: time="2025-05-15T23:40:07.806285014Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 15 23:40:07.810464 containerd[1860]: time="2025-05-15T23:40:07.809062846Z" level=info msg=serving... address=/run/containerd/containerd.sock May 15 23:40:07.810464 containerd[1860]: time="2025-05-15T23:40:07.809224990Z" level=info msg="containerd successfully booted in 0.236367s" May 15 23:40:07.809347 systemd[1]: Started containerd.service - containerd container runtime. May 15 23:40:07.968076 sshd_keygen[1879]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 15 23:40:08.000133 systemd-networkd[1705]: eth0: Gained IPv6LL May 15 23:40:08.007046 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 15 23:40:08.011247 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 15 23:40:08.014348 systemd[1]: Reached target network-online.target - Network is Online. May 15 23:40:08.026431 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. May 15 23:40:08.034034 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 15 23:40:08.044421 systemd[1]: Starting issuegen.service - Generate /run/issue... May 15 23:40:08.048408 (-wrapper)[2045]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 15 23:40:08.066489 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 15 23:40:08.098560 systemd[1]: issuegen.service: Deactivated successfully. May 15 23:40:08.099802 systemd[1]: Finished issuegen.service - Generate /run/issue. May 15 23:40:08.112347 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 15 23:40:08.126730 amazon-ssm-agent[2044]: Initializing new seelog logger May 15 23:40:08.126730 amazon-ssm-agent[2044]: New Seelog Logger Creation Complete May 15 23:40:08.126730 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.126730 amazon-ssm-agent[2044]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.127457 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 processing appconfig overrides May 15 23:40:08.128388 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.128388 amazon-ssm-agent[2044]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.128388 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 processing appconfig overrides May 15 23:40:08.129854 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.129854 amazon-ssm-agent[2044]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.130058 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 processing appconfig overrides May 15 23:40:08.130722 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO Proxy environment variables: May 15 23:40:08.138055 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 15 23:40:08.143428 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.143554 amazon-ssm-agent[2044]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. May 15 23:40:08.143761 amazon-ssm-agent[2044]: 2025/05/15 23:40:08 processing appconfig overrides May 15 23:40:08.153843 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 15 23:40:08.168595 systemd[1]: Started getty@tty1.service - Getty on tty1. May 15 23:40:08.190154 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 15 23:40:08.192679 systemd[1]: Reached target getty.target - Login Prompts. May 15 23:40:08.231232 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO https_proxy: May 15 23:40:08.337430 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO http_proxy: May 15 23:40:08.357558 systemd[1]: Starting docker.service - Docker Application Container Engine... May 15 23:40:08.362426 (dockerd)[2076]: 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 15 23:40:08.429536 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO no_proxy: May 15 23:40:08.528026 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO Checking if agent identity type OnPrem can be assumed May 15 23:40:08.627275 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO Checking if agent identity type EC2 can be assumed May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO Agent will take identity from EC2 May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] using named pipe channel for IPC May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 May 15 23:40:08.667561 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] Starting Core Agent May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [amazon-ssm-agent] registrar detected. Attempting registration May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [Registrar] Starting registrar module May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [EC2Identity] EC2 registration was successful. May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [CredentialRefresher] credentialRefresher has started May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [CredentialRefresher] Starting credentials refresher loop May 15 23:40:08.668185 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO EC2RoleProvider Successfully connected with instance profile role credentials May 15 23:40:08.725669 amazon-ssm-agent[2044]: 2025-05-15 23:40:08 INFO [CredentialRefresher] Next credential rotation will be in 32.408318543633335 minutes May 15 23:40:08.870384 dockerd[2076]: time="2025-05-15T23:40:08.870306863Z" level=info msg="Starting up" May 15 23:40:09.071384 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport1168448712-merged.mount: Deactivated successfully. May 15 23:40:09.103968 dockerd[2076]: time="2025-05-15T23:40:09.103672328Z" level=info msg="Loading containers: start." May 15 23:40:09.212105 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 15 23:40:09.222231 systemd[1]: Started sshd@0-172.31.21.66:22-147.75.109.163:46408.service - OpenSSH per-connection server daemon (147.75.109.163:46408). May 15 23:40:09.403957 kernel: Initializing XFRM netlink socket May 15 23:40:09.441014 sshd[2127]: Accepted publickey for core from 147.75.109.163 port 46408 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:09.445293 sshd-session[2127]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:09.457616 (udev-worker)[1731]: Network interface NamePolicy= disabled on kernel command line. May 15 23:40:09.472565 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 15 23:40:09.494494 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 15 23:40:09.507276 systemd-logind[1845]: New session 1 of user core. May 15 23:40:09.539066 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 15 23:40:09.551479 systemd[1]: Starting user@500.service - User Manager for UID 500... May 15 23:40:09.572440 (systemd)[2212]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 15 23:40:09.589460 systemd-networkd[1705]: docker0: Link UP May 15 23:40:09.636543 dockerd[2076]: time="2025-05-15T23:40:09.636492035Z" level=info msg="Loading containers: done." May 15 23:40:09.676129 dockerd[2076]: time="2025-05-15T23:40:09.676055327Z" 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 15 23:40:09.676377 dockerd[2076]: time="2025-05-15T23:40:09.676211927Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 May 15 23:40:09.676453 dockerd[2076]: time="2025-05-15T23:40:09.676404863Z" level=info msg="Daemon has completed initialization" May 15 23:40:09.711992 amazon-ssm-agent[2044]: 2025-05-15 23:40:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process May 15 23:40:09.816718 amazon-ssm-agent[2044]: 2025-05-15 23:40:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2248) started May 15 23:40:09.828575 systemd[1]: Started docker.service - Docker Application Container Engine. May 15 23:40:09.831804 dockerd[2076]: time="2025-05-15T23:40:09.828317580Z" level=info msg="API listen on /run/docker.sock" May 15 23:40:09.834218 etcd-wrapper[2060]: Error response from daemon: No such container: etcd-member May 15 23:40:09.855239 systemd[2212]: Queued start job for default target default.target. May 15 23:40:09.862713 systemd[2212]: Created slice app.slice - User Application Slice. May 15 23:40:09.863028 systemd[2212]: Reached target paths.target - Paths. May 15 23:40:09.863227 systemd[2212]: Reached target timers.target - Timers. May 15 23:40:09.872722 systemd[2212]: Starting dbus.socket - D-Bus User Message Bus Socket... May 15 23:40:09.885975 etcd-wrapper[2273]: Error response from daemon: No such container: etcd-member May 15 23:40:09.908632 systemd[2212]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 15 23:40:09.908868 systemd[2212]: Reached target sockets.target - Sockets. May 15 23:40:09.908902 systemd[2212]: Reached target basic.target - Basic System. May 15 23:40:09.911462 amazon-ssm-agent[2044]: 2025-05-15 23:40:09 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds May 15 23:40:09.911747 systemd[2212]: Reached target default.target - Main User Target. May 15 23:40:09.911813 systemd[1]: Started user@500.service - User Manager for UID 500. May 15 23:40:09.911827 systemd[2212]: Startup finished in 326ms. May 15 23:40:09.923950 systemd[1]: Started session-1.scope - Session 1 of User core. May 15 23:40:09.971971 etcd-wrapper[2294]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally May 15 23:40:10.098453 systemd[1]: Started sshd@1-172.31.21.66:22-147.75.109.163:46410.service - OpenSSH per-connection server daemon (147.75.109.163:46410). May 15 23:40:10.298092 sshd[2307]: Accepted publickey for core from 147.75.109.163 port 46410 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:10.300323 sshd-session[2307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:10.307761 systemd-logind[1845]: New session 2 of user core. May 15 23:40:10.320179 systemd[1]: Started session-2.scope - Session 2 of User core. May 15 23:40:10.448991 sshd[2309]: Connection closed by 147.75.109.163 port 46410 May 15 23:40:10.449779 sshd-session[2307]: pam_unix(sshd:session): session closed for user core May 15 23:40:10.454964 systemd[1]: sshd@1-172.31.21.66:22-147.75.109.163:46410.service: Deactivated successfully. May 15 23:40:10.457636 systemd[1]: session-2.scope: Deactivated successfully. May 15 23:40:10.461217 systemd-logind[1845]: Session 2 logged out. Waiting for processes to exit. May 15 23:40:10.463106 systemd-logind[1845]: Removed session 2. May 15 23:40:10.492658 systemd[1]: Started sshd@2-172.31.21.66:22-147.75.109.163:46416.service - OpenSSH per-connection server daemon (147.75.109.163:46416). May 15 23:40:10.675086 sshd[2314]: Accepted publickey for core from 147.75.109.163 port 46416 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:10.677485 sshd-session[2314]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:10.685714 systemd-logind[1845]: New session 3 of user core. May 15 23:40:10.696182 systemd[1]: Started session-3.scope - Session 3 of User core. May 15 23:40:10.823743 sshd[2316]: Connection closed by 147.75.109.163 port 46416 May 15 23:40:10.823975 sshd-session[2314]: pam_unix(sshd:session): session closed for user core May 15 23:40:10.830210 systemd[1]: sshd@2-172.31.21.66:22-147.75.109.163:46416.service: Deactivated successfully. May 15 23:40:10.832891 systemd[1]: session-3.scope: Deactivated successfully. May 15 23:40:10.834006 systemd-logind[1845]: Session 3 logged out. Waiting for processes to exit. May 15 23:40:10.835892 systemd-logind[1845]: Removed session 3. May 15 23:40:11.565399 etcd-wrapper[2294]: v3.5.16: Pulling from coreos/etcd May 15 23:40:11.798847 ntpd[1839]: Listen normally on 7 eth0 [fe80::478:26ff:fedb:42ef%2]:123 May 15 23:40:11.799443 ntpd[1839]: 15 May 23:40:11 ntpd[1839]: Listen normally on 7 eth0 [fe80::478:26ff:fedb:42ef%2]:123 May 15 23:40:12.188744 etcd-wrapper[2294]: d10f96ed9607: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 2ae710cd8bfe: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: d462aa345367: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 0f8b424aa0b9: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: d557676654e5: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: c8022d07192e: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: d858cbc252ad: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 1069fc2daed1: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: b40161cd83fc: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 5318d93a3a65: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 307c1adadb60: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: f9b3c65df792: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 5709b4a82c35: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: d5372e382b07: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 9c3b5253dddc: Pulling fs layer May 15 23:40:12.188744 etcd-wrapper[2294]: 7a57323f7551: Pulling fs layer May 15 23:40:12.190247 etcd-wrapper[2294]: 0f8b424aa0b9: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 307c1adadb60: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: f9b3c65df792: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 5709b4a82c35: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: d5372e382b07: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 9c3b5253dddc: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 7a57323f7551: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: c8022d07192e: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: d858cbc252ad: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 1069fc2daed1: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: b40161cd83fc: Waiting May 15 23:40:12.190247 etcd-wrapper[2294]: 5318d93a3a65: Waiting May 15 23:40:12.192298 etcd-wrapper[2294]: d557676654e5: Waiting May 15 23:40:12.494399 etcd-wrapper[2294]: 2ae710cd8bfe: Download complete May 15 23:40:12.601107 etcd-wrapper[2294]: d462aa345367: Download complete May 15 23:40:12.618765 etcd-wrapper[2294]: d10f96ed9607: Download complete May 15 23:40:12.657486 etcd-wrapper[2294]: d10f96ed9607: Pull complete May 15 23:40:12.688644 systemd[1]: var-lib-docker-overlay2-ffe2df8f360f30cb205085294570bdd21c3a592f19880819e26648f2662c29f9-merged.mount: Deactivated successfully. May 15 23:40:12.702389 etcd-wrapper[2294]: 2ae710cd8bfe: Pull complete May 15 23:40:12.871996 etcd-wrapper[2294]: 0f8b424aa0b9: Verifying Checksum May 15 23:40:12.871996 etcd-wrapper[2294]: 0f8b424aa0b9: Download complete May 15 23:40:12.887638 etcd-wrapper[2294]: d557676654e5: Verifying Checksum May 15 23:40:12.887638 etcd-wrapper[2294]: d557676654e5: Download complete May 15 23:40:12.905156 etcd-wrapper[2294]: c8022d07192e: Verifying Checksum May 15 23:40:12.905156 etcd-wrapper[2294]: c8022d07192e: Download complete May 15 23:40:13.188289 etcd-wrapper[2294]: b40161cd83fc: Verifying Checksum May 15 23:40:13.188289 etcd-wrapper[2294]: b40161cd83fc: Download complete May 15 23:40:13.232774 etcd-wrapper[2294]: 1069fc2daed1: Verifying Checksum May 15 23:40:13.232774 etcd-wrapper[2294]: 1069fc2daed1: Download complete May 15 23:40:13.360989 etcd-wrapper[2294]: d858cbc252ad: Verifying Checksum May 15 23:40:13.360989 etcd-wrapper[2294]: d858cbc252ad: Download complete May 15 23:40:13.430805 systemd[1]: var-lib-docker-overlay2-99fdfed20e9a75dc4570418c0e72cb7ea579cee98605a6a5328fc2622191a3c1-merged.mount: Deactivated successfully. May 15 23:40:13.461635 etcd-wrapper[2294]: 5318d93a3a65: Verifying Checksum May 15 23:40:13.461635 etcd-wrapper[2294]: 5318d93a3a65: Download complete May 15 23:40:13.479394 etcd-wrapper[2294]: d462aa345367: Pull complete May 15 23:40:13.506778 etcd-wrapper[2294]: 0f8b424aa0b9: Pull complete May 15 23:40:13.531317 etcd-wrapper[2294]: d557676654e5: Pull complete May 15 23:40:13.555601 etcd-wrapper[2294]: c8022d07192e: Pull complete May 15 23:40:13.557139 etcd-wrapper[2294]: 307c1adadb60: Verifying Checksum May 15 23:40:13.557139 etcd-wrapper[2294]: 307c1adadb60: Download complete May 15 23:40:13.593312 etcd-wrapper[2294]: d858cbc252ad: Pull complete May 15 23:40:13.616127 etcd-wrapper[2294]: 1069fc2daed1: Pull complete May 15 23:40:13.641505 etcd-wrapper[2294]: b40161cd83fc: Pull complete May 15 23:40:13.666181 etcd-wrapper[2294]: 5318d93a3a65: Pull complete May 15 23:40:13.669088 systemd[1]: var-lib-docker-overlay2-255f1d03cfa7092194783e1788ace86eef68864e8553222bb8d20d60bb024a34-merged.mount: Deactivated successfully. May 15 23:40:13.700686 systemd[1]: var-lib-docker-overlay2-0d8a3bf92b88d25d9ee6dabe6e329953d9575bcf7f121f897bb3fe657ded00aa-merged.mount: Deactivated successfully. May 15 23:40:13.716617 etcd-wrapper[2294]: 307c1adadb60: Pull complete May 15 23:40:14.262439 systemd-resolved[1652]: Clock change detected. Flushing caches. May 15 23:40:14.368045 etcd-wrapper[2294]: 5709b4a82c35: Verifying Checksum May 15 23:40:14.368170 etcd-wrapper[2294]: 5709b4a82c35: Download complete May 15 23:40:14.440065 etcd-wrapper[2294]: f9b3c65df792: Verifying Checksum May 15 23:40:14.440065 etcd-wrapper[2294]: f9b3c65df792: Download complete May 15 23:40:14.503883 etcd-wrapper[2294]: d5372e382b07: Verifying Checksum May 15 23:40:14.503883 etcd-wrapper[2294]: d5372e382b07: Download complete May 15 23:40:14.696157 systemd[1]: var-lib-docker-overlay2-bf0d1c3d8fd9157d98d89d5af8e1eb1274c2fbc2952577a3be321ac441214320-merged.mount: Deactivated successfully. May 15 23:40:14.726575 etcd-wrapper[2294]: 7a57323f7551: Verifying Checksum May 15 23:40:14.726575 etcd-wrapper[2294]: 7a57323f7551: Download complete May 15 23:40:14.758099 etcd-wrapper[2294]: 9c3b5253dddc: Verifying Checksum May 15 23:40:14.758383 etcd-wrapper[2294]: 9c3b5253dddc: Download complete May 15 23:40:14.768120 etcd-wrapper[2294]: f9b3c65df792: Pull complete May 15 23:40:14.996634 etcd-wrapper[2294]: 5709b4a82c35: Pull complete May 15 23:40:15.161213 systemd[1]: var-lib-docker-overlay2-ad9d18572499cd8d54121e708367183a3ffafd5d9a257dd0b6ed213fccbd3ecb-merged.mount: Deactivated successfully. May 15 23:40:15.197548 etcd-wrapper[2294]: d5372e382b07: Pull complete May 15 23:40:15.217873 systemd[1]: var-lib-docker-overlay2-5110243bd3350684af672c0f7eefdc3f0b6517dbb67381c9b94ce97161ec4790-merged.mount: Deactivated successfully. May 15 23:40:15.226997 etcd-wrapper[2294]: 9c3b5253dddc: Pull complete May 15 23:40:15.262063 etcd-wrapper[2294]: 7a57323f7551: Pull complete May 15 23:40:15.272290 etcd-wrapper[2294]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 May 15 23:40:15.275770 etcd-wrapper[2294]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 May 15 23:40:15.554740 containerd[1860]: time="2025-05-15T23:40:15.554117854Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 15 23:40:15.554740 containerd[1860]: time="2025-05-15T23:40:15.554237578Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 15 23:40:15.554740 containerd[1860]: time="2025-05-15T23:40:15.554273182Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:40:15.554740 containerd[1860]: time="2025-05-15T23:40:15.554436130Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 15 23:40:15.586650 systemd[1]: Started docker-2a519d778f125897f052e11bf57abd5da25bcdf12132c3565e737d2f17fa94a3.scope - libcontainer container 2a519d778f125897f052e11bf57abd5da25bcdf12132c3565e737d2f17fa94a3. May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.671993Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.672255Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec2a5159ce9aa3429e0219e46de2bffa"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672522Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672572Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672600Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672617Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672680Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.672735Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://172.31.21.66:2379"]} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.672855Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 15 23:40:15.673277 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.672885Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} May 15 23:40:15.674227 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.673604Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 15 23:40:15.674758 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.673853Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"arm64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"ec2a5159ce9aa3429e0219e46de2bffa","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,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.31.21.66:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec2a5159ce9aa3429e0219e46de2bffa=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} May 15 23:40:15.676630 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.676286Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.788864ms"} May 15 23:40:15.683149 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.682733Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} May 15 23:40:15.683787 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.682913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} May 15 23:40:15.683787 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.682988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} May 15 23:40:15.683787 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.683034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 15 23:40:15.683787 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.683055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} May 15 23:40:15.683787 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.683134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 23:40:15.687939 etcd-wrapper[2294]: {"level":"warn","ts":"2025-05-15T23:40:15.687653Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} May 15 23:40:15.689072 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.688715Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} May 15 23:40:15.690033 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.689728Z","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 15 23:40:15.691620 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.691363Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.691899Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692391Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692573Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692607Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692625Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 15 23:40:15.693655 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.692993Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} May 15 23:40:15.697307 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.696944Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} May 15 23:40:15.697975 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.697509Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} May 15 23:40:15.697975 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.697040Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://172.31.21.66:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} May 15 23:40:15.784677 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} May 15 23:40:15.785385 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.784405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} May 15 23:40:15.786075 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.785736Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} May 15 23:40:15.787067 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786451Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec2a5159ce9aa3429e0219e46de2bffa ClientURLs:[http://172.31.21.66:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786476Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786550Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786599Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786618Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} May 15 23:40:15.788228 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.786915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} May 15 23:40:15.788027 systemd[1]: Reached target multi-user.target - Multi-User System. May 15 23:40:15.789257 systemd[1]: Startup finished in 1.118s (kernel) + 7.153s (initrd) + 13.421s (userspace) = 21.692s. May 15 23:40:15.789812 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.787982Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 15 23:40:15.789812 etcd-wrapper[2294]: {"level":"info","ts":"2025-05-15T23:40:15.788745Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} May 15 23:40:16.135013 systemd[1]: var-lib-docker-overlay2-ebe3043756e07599839719cb2c65831966448780f6f98057f824bf54671dfe54-merged.mount: Deactivated successfully. May 15 23:40:21.330868 systemd[1]: Started sshd@3-172.31.21.66:22-147.75.109.163:51782.service - OpenSSH per-connection server daemon (147.75.109.163:51782). May 15 23:40:21.521470 sshd[2455]: Accepted publickey for core from 147.75.109.163 port 51782 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:21.523836 sshd-session[2455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:21.531091 systemd-logind[1845]: New session 4 of user core. May 15 23:40:21.540622 systemd[1]: Started session-4.scope - Session 4 of User core. May 15 23:40:21.666818 sshd[2457]: Connection closed by 147.75.109.163 port 51782 May 15 23:40:21.667624 sshd-session[2455]: pam_unix(sshd:session): session closed for user core May 15 23:40:21.674603 systemd[1]: sshd@3-172.31.21.66:22-147.75.109.163:51782.service: Deactivated successfully. May 15 23:40:21.677799 systemd[1]: session-4.scope: Deactivated successfully. May 15 23:40:21.680711 systemd-logind[1845]: Session 4 logged out. Waiting for processes to exit. May 15 23:40:21.682445 systemd-logind[1845]: Removed session 4. May 15 23:40:21.712817 systemd[1]: Started sshd@4-172.31.21.66:22-147.75.109.163:51786.service - OpenSSH per-connection server daemon (147.75.109.163:51786). May 15 23:40:21.891530 sshd[2462]: Accepted publickey for core from 147.75.109.163 port 51786 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:21.893968 sshd-session[2462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:21.902713 systemd-logind[1845]: New session 5 of user core. May 15 23:40:21.912673 systemd[1]: Started session-5.scope - Session 5 of User core. May 15 23:40:22.030412 sshd[2464]: Connection closed by 147.75.109.163 port 51786 May 15 23:40:22.031706 sshd-session[2462]: pam_unix(sshd:session): session closed for user core May 15 23:40:22.037096 systemd-logind[1845]: Session 5 logged out. Waiting for processes to exit. May 15 23:40:22.038553 systemd[1]: sshd@4-172.31.21.66:22-147.75.109.163:51786.service: Deactivated successfully. May 15 23:40:22.042025 systemd[1]: session-5.scope: Deactivated successfully. May 15 23:40:22.046163 systemd-logind[1845]: Removed session 5. May 15 23:40:22.072841 systemd[1]: Started sshd@5-172.31.21.66:22-147.75.109.163:51802.service - OpenSSH per-connection server daemon (147.75.109.163:51802). May 15 23:40:22.253123 sshd[2469]: Accepted publickey for core from 147.75.109.163 port 51802 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:22.255546 sshd-session[2469]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:22.262824 systemd-logind[1845]: New session 6 of user core. May 15 23:40:22.274602 systemd[1]: Started session-6.scope - Session 6 of User core. May 15 23:40:22.400184 sshd[2471]: Connection closed by 147.75.109.163 port 51802 May 15 23:40:22.400637 sshd-session[2469]: pam_unix(sshd:session): session closed for user core May 15 23:40:22.407403 systemd[1]: sshd@5-172.31.21.66:22-147.75.109.163:51802.service: Deactivated successfully. May 15 23:40:22.411025 systemd[1]: session-6.scope: Deactivated successfully. May 15 23:40:22.412544 systemd-logind[1845]: Session 6 logged out. Waiting for processes to exit. May 15 23:40:22.414292 systemd-logind[1845]: Removed session 6. May 15 23:40:22.444054 systemd[1]: Started sshd@6-172.31.21.66:22-147.75.109.163:51816.service - OpenSSH per-connection server daemon (147.75.109.163:51816). May 15 23:40:22.622233 sshd[2476]: Accepted publickey for core from 147.75.109.163 port 51816 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:22.624666 sshd-session[2476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:22.633189 systemd-logind[1845]: New session 7 of user core. May 15 23:40:22.643617 systemd[1]: Started session-7.scope - Session 7 of User core. May 15 23:40:22.789848 sudo[2479]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 15 23:40:22.790588 sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 23:40:22.810033 sudo[2479]: pam_unix(sudo:session): session closed for user root May 15 23:40:22.832928 sshd[2478]: Connection closed by 147.75.109.163 port 51816 May 15 23:40:22.834068 sshd-session[2476]: pam_unix(sshd:session): session closed for user core May 15 23:40:22.841043 systemd[1]: sshd@6-172.31.21.66:22-147.75.109.163:51816.service: Deactivated successfully. May 15 23:40:22.844689 systemd[1]: session-7.scope: Deactivated successfully. May 15 23:40:22.846991 systemd-logind[1845]: Session 7 logged out. Waiting for processes to exit. May 15 23:40:22.849735 systemd-logind[1845]: Removed session 7. May 15 23:40:22.877812 systemd[1]: Started sshd@7-172.31.21.66:22-147.75.109.163:51824.service - OpenSSH per-connection server daemon (147.75.109.163:51824). May 15 23:40:23.057445 sshd[2484]: Accepted publickey for core from 147.75.109.163 port 51824 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:23.059967 sshd-session[2484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:23.067089 systemd-logind[1845]: New session 8 of user core. May 15 23:40:23.076583 systemd[1]: Started session-8.scope - Session 8 of User core. May 15 23:40:23.179205 sudo[2488]: 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 15 23:40:23.179885 sudo[2488]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 23:40:23.186070 sudo[2488]: pam_unix(sudo:session): session closed for user root May 15 23:40:23.196031 sudo[2487]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 15 23:40:23.196730 sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 15 23:40:23.221928 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 15 23:40:23.270765 augenrules[2510]: No rules May 15 23:40:23.273405 systemd[1]: audit-rules.service: Deactivated successfully. May 15 23:40:23.274516 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 15 23:40:23.277713 sudo[2487]: pam_unix(sudo:session): session closed for user root May 15 23:40:23.300646 sshd[2486]: Connection closed by 147.75.109.163 port 51824 May 15 23:40:23.300529 sshd-session[2484]: pam_unix(sshd:session): session closed for user core May 15 23:40:23.306028 systemd-logind[1845]: Session 8 logged out. Waiting for processes to exit. May 15 23:40:23.306033 systemd[1]: sshd@7-172.31.21.66:22-147.75.109.163:51824.service: Deactivated successfully. May 15 23:40:23.309124 systemd[1]: session-8.scope: Deactivated successfully. May 15 23:40:23.313079 systemd-logind[1845]: Removed session 8. May 15 23:40:23.333657 systemd[1]: Started sshd@8-172.31.21.66:22-147.75.109.163:51832.service - OpenSSH per-connection server daemon (147.75.109.163:51832). May 15 23:40:23.523621 sshd[2518]: Accepted publickey for core from 147.75.109.163 port 51832 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:23.526026 sshd-session[2518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:23.535450 systemd-logind[1845]: New session 9 of user core. May 15 23:40:23.541588 systemd[1]: Started session-9.scope - Session 9 of User core. May 15 23:40:23.659213 sshd[2520]: Connection closed by 147.75.109.163 port 51832 May 15 23:40:23.660434 sshd-session[2518]: pam_unix(sshd:session): session closed for user core May 15 23:40:23.666436 systemd[1]: sshd@8-172.31.21.66:22-147.75.109.163:51832.service: Deactivated successfully. May 15 23:40:23.669207 systemd[1]: session-9.scope: Deactivated successfully. May 15 23:40:23.670387 systemd-logind[1845]: Session 9 logged out. Waiting for processes to exit. May 15 23:40:23.672603 systemd-logind[1845]: Removed session 9. May 15 23:40:23.697849 systemd[1]: Started sshd@9-172.31.21.66:22-147.75.109.163:51834.service - OpenSSH per-connection server daemon (147.75.109.163:51834). May 15 23:40:23.889389 sshd[2527]: Accepted publickey for core from 147.75.109.163 port 51834 ssh2: RSA SHA256:u2PSNRyq2mMracEjSx7iEhXrB7RNOCRf4fqr5PWZI6c May 15 23:40:23.891757 sshd-session[2527]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 15 23:40:23.898886 systemd-logind[1845]: New session 10 of user core. May 15 23:40:23.911601 systemd[1]: Started session-10.scope - Session 10 of User core. May 15 23:40:24.299134 sshd[2529]: Connection closed by 147.75.109.163 port 51834 May 15 23:40:24.299989 sshd-session[2527]: pam_unix(sshd:session): session closed for user core May 15 23:40:24.306138 systemd[1]: sshd@9-172.31.21.66:22-147.75.109.163:51834.service: Deactivated successfully. May 15 23:40:24.309065 systemd[1]: session-10.scope: Deactivated successfully. May 15 23:40:24.310190 systemd-logind[1845]: Session 10 logged out. Waiting for processes to exit. May 15 23:40:24.312158 systemd-logind[1845]: Removed session 10. May 15 23:40:38.016258 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 15 23:40:53.112135 update_engine[1846]: I20250515 23:40:53.112046 1846 update_attempter.cc:509] Updating boot flags... May 15 23:40:53.180388 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (2557) May 15 23:40:53.413592 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (2557) May 15 23:40:53.640394 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 39 scanned by (udev-worker) (2557) May 15 23:42:18.108402 update_engine[1846]: I20250515 23:42:18.107985 1846 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs May 15 23:42:18.108402 update_engine[1846]: I20250515 23:42:18.108052 1846 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs May 15 23:42:18.109092 update_engine[1846]: I20250515 23:42:18.108413 1846 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs May 15 23:42:18.109556 update_engine[1846]: I20250515 23:42:18.109309 1846 omaha_request_params.cc:62] Current group set to stable May 15 23:42:18.109556 update_engine[1846]: I20250515 23:42:18.109499 1846 update_attempter.cc:499] Already updated boot flags. Skipping. May 15 23:42:18.109556 update_engine[1846]: I20250515 23:42:18.109524 1846 update_attempter.cc:643] Scheduling an action processor start. May 15 23:42:18.109730 update_engine[1846]: I20250515 23:42:18.109556 1846 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction May 15 23:42:18.109730 update_engine[1846]: I20250515 23:42:18.109608 1846 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs May 15 23:42:18.109730 update_engine[1846]: I20250515 23:42:18.109709 1846 omaha_request_action.cc:271] Posting an Omaha request to disabled May 15 23:42:18.109974 update_engine[1846]: I20250515 23:42:18.109728 1846 omaha_request_action.cc:272] Request: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: May 15 23:42:18.109974 update_engine[1846]: I20250515 23:42:18.109745 1846 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 23:42:18.112360 update_engine[1846]: I20250515 23:42:18.112165 1846 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 23:42:18.112783 update_engine[1846]: I20250515 23:42:18.112622 1846 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. May 15 23:42:18.112870 locksmithd[1880]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 May 15 23:42:18.124457 update_engine[1846]: E20250515 23:42:18.124392 1846 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 23:42:18.124555 update_engine[1846]: I20250515 23:42:18.124509 1846 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 May 15 23:42:28.106658 update_engine[1846]: I20250515 23:42:28.106565 1846 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 23:42:28.107295 update_engine[1846]: I20250515 23:42:28.106911 1846 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 23:42:28.107295 update_engine[1846]: I20250515 23:42:28.107188 1846 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. May 15 23:42:28.107723 update_engine[1846]: E20250515 23:42:28.107667 1846 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 23:42:28.107795 update_engine[1846]: I20250515 23:42:28.107759 1846 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 May 15 23:42:38.099059 update_engine[1846]: I20250515 23:42:38.098965 1846 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 23:42:38.099651 update_engine[1846]: I20250515 23:42:38.099331 1846 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 23:42:38.099739 update_engine[1846]: I20250515 23:42:38.099642 1846 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. May 15 23:42:38.100325 update_engine[1846]: E20250515 23:42:38.100273 1846 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 23:42:38.100421 update_engine[1846]: I20250515 23:42:38.100376 1846 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 May 15 23:42:48.106549 update_engine[1846]: I20250515 23:42:48.106454 1846 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 23:42:48.107084 update_engine[1846]: I20250515 23:42:48.106803 1846 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 23:42:48.107162 update_engine[1846]: I20250515 23:42:48.107077 1846 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. May 15 23:42:48.107669 update_engine[1846]: E20250515 23:42:48.107611 1846 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 23:42:48.107759 update_engine[1846]: I20250515 23:42:48.107707 1846 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded May 15 23:42:48.107759 update_engine[1846]: I20250515 23:42:48.107728 1846 omaha_request_action.cc:617] Omaha request response: May 15 23:42:48.107860 update_engine[1846]: E20250515 23:42:48.107836 1846 omaha_request_action.cc:636] Omaha request network transfer failed. May 15 23:42:48.107922 update_engine[1846]: I20250515 23:42:48.107868 1846 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. May 15 23:42:48.107922 update_engine[1846]: I20250515 23:42:48.107885 1846 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 23:42:48.107922 update_engine[1846]: I20250515 23:42:48.107900 1846 update_attempter.cc:306] Processing Done. May 15 23:42:48.108074 update_engine[1846]: E20250515 23:42:48.107926 1846 update_attempter.cc:619] Update failed. May 15 23:42:48.108074 update_engine[1846]: I20250515 23:42:48.107942 1846 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse May 15 23:42:48.108074 update_engine[1846]: I20250515 23:42:48.107957 1846 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) May 15 23:42:48.108074 update_engine[1846]: I20250515 23:42:48.107972 1846 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. May 15 23:42:48.108292 update_engine[1846]: I20250515 23:42:48.108237 1846 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction May 15 23:42:48.108370 update_engine[1846]: I20250515 23:42:48.108285 1846 omaha_request_action.cc:271] Posting an Omaha request to disabled May 15 23:42:48.108370 update_engine[1846]: I20250515 23:42:48.108302 1846 omaha_request_action.cc:272] Request: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: May 15 23:42:48.108370 update_engine[1846]: I20250515 23:42:48.108319 1846 libcurl_http_fetcher.cc:47] Starting/Resuming transfer May 15 23:42:48.108747 update_engine[1846]: I20250515 23:42:48.108587 1846 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP May 15 23:42:48.108915 update_engine[1846]: I20250515 23:42:48.108839 1846 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. May 15 23:42:48.109261 update_engine[1846]: E20250515 23:42:48.109211 1846 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled May 15 23:42:48.109334 update_engine[1846]: I20250515 23:42:48.109299 1846 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded May 15 23:42:48.109334 update_engine[1846]: I20250515 23:42:48.109321 1846 omaha_request_action.cc:617] Omaha request response: May 15 23:42:48.109500 update_engine[1846]: I20250515 23:42:48.109365 1846 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 23:42:48.109500 update_engine[1846]: I20250515 23:42:48.109386 1846 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction May 15 23:42:48.109500 update_engine[1846]: I20250515 23:42:48.109402 1846 update_attempter.cc:306] Processing Done. May 15 23:42:48.109500 update_engine[1846]: I20250515 23:42:48.109418 1846 update_attempter.cc:310] Error event sent. May 15 23:42:48.109500 update_engine[1846]: I20250515 23:42:48.109444 1846 update_check_scheduler.cc:74] Next update check in 40m37s May 15 23:42:48.109899 locksmithd[1880]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 May 15 23:42:48.109899 locksmithd[1880]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0