Oct 8 19:34:30.201270 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083] Oct 8 19:34:30.201315 kernel: Linux version 6.6.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Tue Oct 8 18:25:39 -00 2024 Oct 8 19:34:30.201340 kernel: KASLR disabled due to lack of seed Oct 8 19:34:30.201357 kernel: efi: EFI v2.7 by EDK II Oct 8 19:34:30.201372 kernel: efi: SMBIOS=0x7bed0000 SMBIOS 3.0=0x7beb0000 ACPI=0x786e0000 ACPI 2.0=0x786e0014 MEMATTR=0x7b003a98 MEMRESERVE=0x7852ee18 Oct 8 19:34:30.201387 kernel: ACPI: Early table checksum verification disabled Oct 8 19:34:30.201405 kernel: ACPI: RSDP 0x00000000786E0014 000024 (v02 AMAZON) Oct 8 19:34:30.201420 kernel: ACPI: XSDT 0x00000000786D00E8 000064 (v01 AMAZON AMZNFACP 00000001 01000013) Oct 8 19:34:30.201436 kernel: ACPI: FACP 0x00000000786B0000 000114 (v06 AMAZON AMZNFACP 00000001 AMZN 00000001) Oct 8 19:34:30.201452 kernel: ACPI: DSDT 0x0000000078640000 00159D (v02 AMAZON AMZNDSDT 00000001 INTL 20160527) Oct 8 19:34:30.201472 kernel: ACPI: APIC 0x00000000786C0000 000108 (v04 AMAZON AMZNAPIC 00000001 AMZN 00000001) Oct 8 19:34:30.201487 kernel: ACPI: SPCR 0x00000000786A0000 000050 (v02 AMAZON AMZNSPCR 00000001 AMZN 00000001) Oct 8 19:34:30.201503 kernel: ACPI: GTDT 0x0000000078690000 000060 (v02 AMAZON AMZNGTDT 00000001 AMZN 00000001) Oct 8 19:34:30.201518 kernel: ACPI: MCFG 0x0000000078680000 00003C (v02 AMAZON AMZNMCFG 00000001 AMZN 00000001) Oct 8 19:34:30.201536 kernel: ACPI: SLIT 0x0000000078670000 00002D (v01 AMAZON AMZNSLIT 00000001 AMZN 00000001) Oct 8 19:34:30.201557 kernel: ACPI: IORT 0x0000000078660000 000078 (v01 AMAZON AMZNIORT 00000001 AMZN 00000001) Oct 8 19:34:30.201573 kernel: ACPI: PPTT 0x0000000078650000 0000EC (v01 AMAZON AMZNPPTT 00000001 AMZN 00000001) Oct 8 19:34:30.201589 kernel: ACPI: SPCR: console: uart,mmio,0x90a0000,115200 Oct 8 19:34:30.201606 kernel: earlycon: uart0 at MMIO 0x00000000090a0000 (options '115200') Oct 8 19:34:30.201622 kernel: printk: bootconsole [uart0] enabled Oct 8 19:34:30.201638 kernel: NUMA: Failed to initialise from firmware Oct 8 19:34:30.201654 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000004b5ffffff] Oct 8 19:34:30.201671 kernel: NUMA: NODE_DATA [mem 0x4b583f800-0x4b5844fff] Oct 8 19:34:30.201687 kernel: Zone ranges: Oct 8 19:34:30.201704 kernel: DMA [mem 0x0000000040000000-0x00000000ffffffff] Oct 8 19:34:30.201720 kernel: DMA32 empty Oct 8 19:34:30.201740 kernel: Normal [mem 0x0000000100000000-0x00000004b5ffffff] Oct 8 19:34:30.201757 kernel: Movable zone start for each node Oct 8 19:34:30.201772 kernel: Early memory node ranges Oct 8 19:34:30.201789 kernel: node 0: [mem 0x0000000040000000-0x000000007862ffff] Oct 8 19:34:30.201805 kernel: node 0: [mem 0x0000000078630000-0x000000007863ffff] Oct 8 19:34:30.201821 kernel: node 0: [mem 0x0000000078640000-0x00000000786effff] Oct 8 19:34:30.201860 kernel: node 0: [mem 0x00000000786f0000-0x000000007872ffff] Oct 8 19:34:30.201877 kernel: node 0: [mem 0x0000000078730000-0x000000007bbfffff] Oct 8 19:34:30.201894 kernel: node 0: [mem 0x000000007bc00000-0x000000007bfdffff] Oct 8 19:34:30.201910 kernel: node 0: [mem 0x000000007bfe0000-0x000000007fffffff] Oct 8 19:34:30.201926 kernel: node 0: [mem 0x0000000400000000-0x00000004b5ffffff] Oct 8 19:34:30.201943 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000004b5ffffff] Oct 8 19:34:30.201965 kernel: On node 0, zone Normal: 8192 pages in unavailable ranges Oct 8 19:34:30.201982 kernel: psci: probing for conduit method from ACPI. Oct 8 19:34:30.202006 kernel: psci: PSCIv1.0 detected in firmware. Oct 8 19:34:30.202023 kernel: psci: Using standard PSCI v0.2 function IDs Oct 8 19:34:30.202040 kernel: psci: Trusted OS migration not required Oct 8 19:34:30.202062 kernel: psci: SMC Calling Convention v1.1 Oct 8 19:34:30.202079 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Oct 8 19:34:30.202118 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Oct 8 19:34:30.202138 kernel: pcpu-alloc: [0] 0 [0] 1 Oct 8 19:34:30.202155 kernel: Detected PIPT I-cache on CPU0 Oct 8 19:34:30.202172 kernel: CPU features: detected: GIC system register CPU interface Oct 8 19:34:30.202189 kernel: CPU features: detected: Spectre-v2 Oct 8 19:34:30.202206 kernel: CPU features: detected: Spectre-v3a Oct 8 19:34:30.202223 kernel: CPU features: detected: Spectre-BHB Oct 8 19:34:30.202240 kernel: CPU features: detected: ARM erratum 1742098 Oct 8 19:34:30.202258 kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923 Oct 8 19:34:30.202281 kernel: alternatives: applying boot alternatives Oct 8 19:34:30.202302 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=f7968382bc5b46f9b6104a9f012cfba991c8ea306771e716a099618547de81d3 Oct 8 19:34:30.202320 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 8 19:34:30.202338 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 8 19:34:30.202355 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 8 19:34:30.202372 kernel: Fallback order for Node 0: 0 Oct 8 19:34:30.202389 kernel: Built 1 zonelists, mobility grouping on. Total pages: 991872 Oct 8 19:34:30.202406 kernel: Policy zone: Normal Oct 8 19:34:30.202423 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 8 19:34:30.202440 kernel: software IO TLB: area num 2. Oct 8 19:34:30.202457 kernel: software IO TLB: mapped [mem 0x000000007c000000-0x0000000080000000] (64MB) Oct 8 19:34:30.202479 kernel: Memory: 3820152K/4030464K available (10304K kernel code, 2184K rwdata, 8092K rodata, 39360K init, 897K bss, 210312K reserved, 0K cma-reserved) Oct 8 19:34:30.202496 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 8 19:34:30.202513 kernel: trace event string verifier disabled Oct 8 19:34:30.202531 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 8 19:34:30.202549 kernel: rcu: RCU event tracing is enabled. Oct 8 19:34:30.202567 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 8 19:34:30.202584 kernel: Trampoline variant of Tasks RCU enabled. Oct 8 19:34:30.202601 kernel: Tracing variant of Tasks RCU enabled. Oct 8 19:34:30.202619 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 8 19:34:30.202636 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 8 19:34:30.202653 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 8 19:34:30.202675 kernel: GICv3: 96 SPIs implemented Oct 8 19:34:30.202692 kernel: GICv3: 0 Extended SPIs implemented Oct 8 19:34:30.202709 kernel: Root IRQ handler: gic_handle_irq Oct 8 19:34:30.202726 kernel: GICv3: GICv3 features: 16 PPIs Oct 8 19:34:30.202743 kernel: GICv3: CPU0: found redistributor 0 region 0:0x0000000010200000 Oct 8 19:34:30.202760 kernel: ITS [mem 0x10080000-0x1009ffff] Oct 8 19:34:30.202777 kernel: ITS@0x0000000010080000: allocated 8192 Devices @4000c0000 (indirect, esz 8, psz 64K, shr 1) Oct 8 19:34:30.202794 kernel: ITS@0x0000000010080000: allocated 8192 Interrupt Collections @4000d0000 (flat, esz 8, psz 64K, shr 1) Oct 8 19:34:30.202812 kernel: GICv3: using LPI property table @0x00000004000e0000 Oct 8 19:34:30.202828 kernel: ITS: Using hypervisor restricted LPI range [128] Oct 8 19:34:30.202845 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000004000f0000 Oct 8 19:34:30.202863 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 8 19:34:30.202884 kernel: arch_timer: cp15 timer(s) running at 83.33MHz (virt). Oct 8 19:34:30.202901 kernel: clocksource: arch_sys_counter: mask: 0x1ffffffffffffff max_cycles: 0x13381ebeec, max_idle_ns: 440795203145 ns Oct 8 19:34:30.202919 kernel: sched_clock: 57 bits at 83MHz, resolution 12ns, wraps every 4398046511100ns Oct 8 19:34:30.202936 kernel: Console: colour dummy device 80x25 Oct 8 19:34:30.202953 kernel: printk: console [tty1] enabled Oct 8 19:34:30.202971 kernel: ACPI: Core revision 20230628 Oct 8 19:34:30.202988 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 166.66 BogoMIPS (lpj=83333) Oct 8 19:34:30.203006 kernel: pid_max: default: 32768 minimum: 301 Oct 8 19:34:30.203023 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 8 19:34:30.203041 kernel: landlock: Up and running. Oct 8 19:34:30.203063 kernel: SELinux: Initializing. Oct 8 19:34:30.203080 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 8 19:34:30.203961 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 8 19:34:30.203990 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 8 19:34:30.204009 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 8 19:34:30.204027 kernel: rcu: Hierarchical SRCU implementation. Oct 8 19:34:30.204045 kernel: rcu: Max phase no-delay instances is 400. Oct 8 19:34:30.204063 kernel: Platform MSI: ITS@0x10080000 domain created Oct 8 19:34:30.204080 kernel: PCI/MSI: ITS@0x10080000 domain created Oct 8 19:34:30.204132 kernel: Remapping and enabling EFI services. Oct 8 19:34:30.204150 kernel: smp: Bringing up secondary CPUs ... Oct 8 19:34:30.204167 kernel: Detected PIPT I-cache on CPU1 Oct 8 19:34:30.204185 kernel: GICv3: CPU1: found redistributor 1 region 0:0x0000000010220000 Oct 8 19:34:30.204204 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000400100000 Oct 8 19:34:30.204221 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd083] Oct 8 19:34:30.204239 kernel: smp: Brought up 1 node, 2 CPUs Oct 8 19:34:30.204256 kernel: SMP: Total of 2 processors activated. Oct 8 19:34:30.204273 kernel: CPU features: detected: 32-bit EL0 Support Oct 8 19:34:30.204297 kernel: CPU features: detected: 32-bit EL1 Support Oct 8 19:34:30.204315 kernel: CPU features: detected: CRC32 instructions Oct 8 19:34:30.204346 kernel: CPU: All CPU(s) started at EL1 Oct 8 19:34:30.204371 kernel: alternatives: applying system-wide alternatives Oct 8 19:34:30.204389 kernel: devtmpfs: initialized Oct 8 19:34:30.204409 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 8 19:34:30.204427 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 8 19:34:30.204446 kernel: pinctrl core: initialized pinctrl subsystem Oct 8 19:34:30.204464 kernel: SMBIOS 3.0.0 present. Oct 8 19:34:30.204487 kernel: DMI: Amazon EC2 a1.large/, BIOS 1.0 11/1/2018 Oct 8 19:34:30.204506 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 8 19:34:30.204524 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 8 19:34:30.204542 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 8 19:34:30.204560 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 8 19:34:30.204579 kernel: audit: initializing netlink subsys (disabled) Oct 8 19:34:30.204597 kernel: audit: type=2000 audit(0.288:1): state=initialized audit_enabled=0 res=1 Oct 8 19:34:30.204619 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 8 19:34:30.204637 kernel: cpuidle: using governor menu Oct 8 19:34:30.204655 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 8 19:34:30.204673 kernel: ASID allocator initialised with 65536 entries Oct 8 19:34:30.204692 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 8 19:34:30.204710 kernel: Serial: AMBA PL011 UART driver Oct 8 19:34:30.204728 kernel: Modules: 17504 pages in range for non-PLT usage Oct 8 19:34:30.204746 kernel: Modules: 509024 pages in range for PLT usage Oct 8 19:34:30.204765 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 8 19:34:30.204787 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 8 19:34:30.204806 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 8 19:34:30.204824 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 8 19:34:30.204842 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 8 19:34:30.204861 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 8 19:34:30.204879 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 8 19:34:30.204897 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 8 19:34:30.204915 kernel: ACPI: Added _OSI(Module Device) Oct 8 19:34:30.204933 kernel: ACPI: Added _OSI(Processor Device) Oct 8 19:34:30.204956 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 8 19:34:30.204975 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 8 19:34:30.204993 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 8 19:34:30.205011 kernel: ACPI: Interpreter enabled Oct 8 19:34:30.205029 kernel: ACPI: Using GIC for interrupt routing Oct 8 19:34:30.205047 kernel: ACPI: MCFG table detected, 1 entries Oct 8 19:34:30.205065 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-0f]) Oct 8 19:34:30.205438 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 8 19:34:30.205671 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 8 19:34:30.205906 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 8 19:34:30.206176 kernel: acpi PNP0A08:00: ECAM area [mem 0x20000000-0x20ffffff] reserved by PNP0C02:00 Oct 8 19:34:30.206405 kernel: acpi PNP0A08:00: ECAM at [mem 0x20000000-0x20ffffff] for [bus 00-0f] Oct 8 19:34:30.206433 kernel: ACPI: Remapped I/O 0x000000001fff0000 to [io 0x0000-0xffff window] Oct 8 19:34:30.206452 kernel: acpiphp: Slot [1] registered Oct 8 19:34:30.206471 kernel: acpiphp: Slot [2] registered Oct 8 19:34:30.206490 kernel: acpiphp: Slot [3] registered Oct 8 19:34:30.206509 kernel: acpiphp: Slot [4] registered Oct 8 19:34:30.206536 kernel: acpiphp: Slot [5] registered Oct 8 19:34:30.206554 kernel: acpiphp: Slot [6] registered Oct 8 19:34:30.206573 kernel: acpiphp: Slot [7] registered Oct 8 19:34:30.206591 kernel: acpiphp: Slot [8] registered Oct 8 19:34:30.206610 kernel: acpiphp: Slot [9] registered Oct 8 19:34:30.206628 kernel: acpiphp: Slot [10] registered Oct 8 19:34:30.206647 kernel: acpiphp: Slot [11] registered Oct 8 19:34:30.206665 kernel: acpiphp: Slot [12] registered Oct 8 19:34:30.206683 kernel: acpiphp: Slot [13] registered Oct 8 19:34:30.206706 kernel: acpiphp: Slot [14] registered Oct 8 19:34:30.206725 kernel: acpiphp: Slot [15] registered Oct 8 19:34:30.206743 kernel: acpiphp: Slot [16] registered Oct 8 19:34:30.206761 kernel: acpiphp: Slot [17] registered Oct 8 19:34:30.206780 kernel: acpiphp: Slot [18] registered Oct 8 19:34:30.206798 kernel: acpiphp: Slot [19] registered Oct 8 19:34:30.206817 kernel: acpiphp: Slot [20] registered Oct 8 19:34:30.206835 kernel: acpiphp: Slot [21] registered Oct 8 19:34:30.206853 kernel: acpiphp: Slot [22] registered Oct 8 19:34:30.206873 kernel: acpiphp: Slot [23] registered Oct 8 19:34:30.206898 kernel: acpiphp: Slot [24] registered Oct 8 19:34:30.206918 kernel: acpiphp: Slot [25] registered Oct 8 19:34:30.206937 kernel: acpiphp: Slot [26] registered Oct 8 19:34:30.206956 kernel: acpiphp: Slot [27] registered Oct 8 19:34:30.206974 kernel: acpiphp: Slot [28] registered Oct 8 19:34:30.206993 kernel: acpiphp: Slot [29] registered Oct 8 19:34:30.207011 kernel: acpiphp: Slot [30] registered Oct 8 19:34:30.207030 kernel: acpiphp: Slot [31] registered Oct 8 19:34:30.207048 kernel: PCI host bridge to bus 0000:00 Oct 8 19:34:30.207379 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xffffffff window] Oct 8 19:34:30.207593 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 8 19:34:30.207793 kernel: pci_bus 0000:00: root bus resource [mem 0x400000000000-0x407fffffffff window] Oct 8 19:34:30.207988 kernel: pci_bus 0000:00: root bus resource [bus 00-0f] Oct 8 19:34:30.208316 kernel: pci 0000:00:00.0: [1d0f:0200] type 00 class 0x060000 Oct 8 19:34:30.210339 kernel: pci 0000:00:01.0: [1d0f:8250] type 00 class 0x070003 Oct 8 19:34:30.210607 kernel: pci 0000:00:01.0: reg 0x10: [mem 0x80118000-0x80118fff] Oct 8 19:34:30.210842 kernel: pci 0000:00:04.0: [1d0f:8061] type 00 class 0x010802 Oct 8 19:34:30.211057 kernel: pci 0000:00:04.0: reg 0x10: [mem 0x80114000-0x80117fff] Oct 8 19:34:30.212497 kernel: pci 0000:00:04.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 8 19:34:30.212763 kernel: pci 0000:00:05.0: [1d0f:ec20] type 00 class 0x020000 Oct 8 19:34:30.212979 kernel: pci 0000:00:05.0: reg 0x10: [mem 0x80110000-0x80113fff] Oct 8 19:34:30.213260 kernel: pci 0000:00:05.0: reg 0x18: [mem 0x80000000-0x800fffff pref] Oct 8 19:34:30.213483 kernel: pci 0000:00:05.0: reg 0x20: [mem 0x80100000-0x8010ffff] Oct 8 19:34:30.213694 kernel: pci 0000:00:05.0: PME# supported from D0 D1 D2 D3hot D3cold Oct 8 19:34:30.213929 kernel: pci 0000:00:05.0: BAR 2: assigned [mem 0x80000000-0x800fffff pref] Oct 8 19:34:30.216295 kernel: pci 0000:00:05.0: BAR 4: assigned [mem 0x80100000-0x8010ffff] Oct 8 19:34:30.216567 kernel: pci 0000:00:04.0: BAR 0: assigned [mem 0x80110000-0x80113fff] Oct 8 19:34:30.216785 kernel: pci 0000:00:05.0: BAR 0: assigned [mem 0x80114000-0x80117fff] Oct 8 19:34:30.217010 kernel: pci 0000:00:01.0: BAR 0: assigned [mem 0x80118000-0x80118fff] Oct 8 19:34:30.217320 kernel: pci_bus 0000:00: resource 4 [mem 0x80000000-0xffffffff window] Oct 8 19:34:30.217547 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 8 19:34:30.217747 kernel: pci_bus 0000:00: resource 6 [mem 0x400000000000-0x407fffffffff window] Oct 8 19:34:30.217775 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 8 19:34:30.217795 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 8 19:34:30.217815 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 8 19:34:30.217859 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 8 19:34:30.217882 kernel: iommu: Default domain type: Translated Oct 8 19:34:30.217914 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 8 19:34:30.217934 kernel: efivars: Registered efivars operations Oct 8 19:34:30.217953 kernel: vgaarb: loaded Oct 8 19:34:30.217971 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 8 19:34:30.217990 kernel: VFS: Disk quotas dquot_6.6.0 Oct 8 19:34:30.218010 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 8 19:34:30.218028 kernel: pnp: PnP ACPI init Oct 8 19:34:30.219386 kernel: system 00:00: [mem 0x20000000-0x2fffffff] could not be reserved Oct 8 19:34:30.219438 kernel: pnp: PnP ACPI: found 1 devices Oct 8 19:34:30.219458 kernel: NET: Registered PF_INET protocol family Oct 8 19:34:30.219478 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 8 19:34:30.219497 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 8 19:34:30.219516 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 8 19:34:30.219535 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 8 19:34:30.219553 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 8 19:34:30.219572 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 8 19:34:30.219592 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 8 19:34:30.219618 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 8 19:34:30.219638 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 8 19:34:30.219657 kernel: PCI: CLS 0 bytes, default 64 Oct 8 19:34:30.219676 kernel: kvm [1]: HYP mode not available Oct 8 19:34:30.219695 kernel: Initialise system trusted keyrings Oct 8 19:34:30.219714 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 8 19:34:30.219732 kernel: Key type asymmetric registered Oct 8 19:34:30.219750 kernel: Asymmetric key parser 'x509' registered Oct 8 19:34:30.219768 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 8 19:34:30.219791 kernel: io scheduler mq-deadline registered Oct 8 19:34:30.219809 kernel: io scheduler kyber registered Oct 8 19:34:30.219827 kernel: io scheduler bfq registered Oct 8 19:34:30.220063 kernel: pl061_gpio ARMH0061:00: PL061 GPIO chip registered Oct 8 19:34:30.221195 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 8 19:34:30.221237 kernel: ACPI: button: Power Button [PWRB] Oct 8 19:34:30.221257 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 Oct 8 19:34:30.221276 kernel: ACPI: button: Sleep Button [SLPB] Oct 8 19:34:30.221295 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 8 19:34:30.223507 kernel: ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 Oct 8 19:34:30.223800 kernel: serial 0000:00:01.0: enabling device (0010 -> 0012) Oct 8 19:34:30.223832 kernel: printk: console [ttyS0] disabled Oct 8 19:34:30.223852 kernel: 0000:00:01.0: ttyS0 at MMIO 0x80118000 (irq = 14, base_baud = 115200) is a 16550A Oct 8 19:34:30.223871 kernel: printk: console [ttyS0] enabled Oct 8 19:34:30.223890 kernel: printk: bootconsole [uart0] disabled Oct 8 19:34:30.223908 kernel: thunder_xcv, ver 1.0 Oct 8 19:34:30.223927 kernel: thunder_bgx, ver 1.0 Oct 8 19:34:30.223945 kernel: nicpf, ver 1.0 Oct 8 19:34:30.223975 kernel: nicvf, ver 1.0 Oct 8 19:34:30.224292 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 8 19:34:30.224518 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-10-08T19:34:29 UTC (1728416069) Oct 8 19:34:30.224548 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 8 19:34:30.224568 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 3 counters available Oct 8 19:34:30.224586 kernel: watchdog: Delayed init of the lockup detector failed: -19 Oct 8 19:34:30.224605 kernel: watchdog: Hard watchdog permanently disabled Oct 8 19:34:30.224623 kernel: NET: Registered PF_INET6 protocol family Oct 8 19:34:30.224653 kernel: Segment Routing with IPv6 Oct 8 19:34:30.224671 kernel: In-situ OAM (IOAM) with IPv6 Oct 8 19:34:30.224689 kernel: NET: Registered PF_PACKET protocol family Oct 8 19:34:30.224707 kernel: Key type dns_resolver registered Oct 8 19:34:30.224726 kernel: registered taskstats version 1 Oct 8 19:34:30.224744 kernel: Loading compiled-in X.509 certificates Oct 8 19:34:30.224762 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.54-flatcar: e9e638352c282bfddf5aec6da700ad8191939d05' Oct 8 19:34:30.224780 kernel: Key type .fscrypt registered Oct 8 19:34:30.224798 kernel: Key type fscrypt-provisioning registered Oct 8 19:34:30.224821 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 8 19:34:30.224840 kernel: ima: Allocated hash algorithm: sha1 Oct 8 19:34:30.224858 kernel: ima: No architecture policies found Oct 8 19:34:30.224876 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 8 19:34:30.224894 kernel: clk: Disabling unused clocks Oct 8 19:34:30.224912 kernel: Freeing unused kernel memory: 39360K Oct 8 19:34:30.224930 kernel: Run /init as init process Oct 8 19:34:30.224949 kernel: with arguments: Oct 8 19:34:30.224968 kernel: /init Oct 8 19:34:30.224991 kernel: with environment: Oct 8 19:34:30.225009 kernel: HOME=/ Oct 8 19:34:30.225028 kernel: TERM=linux Oct 8 19:34:30.225047 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 8 19:34:30.225072 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) Oct 8 19:34:30.225123 systemd[1]: Detected virtualization amazon. Oct 8 19:34:30.225148 systemd[1]: Detected architecture arm64. Oct 8 19:34:30.225169 systemd[1]: Running in initrd. Oct 8 19:34:30.225196 systemd[1]: No hostname configured, using default hostname. Oct 8 19:34:30.225216 systemd[1]: Hostname set to . Oct 8 19:34:30.225237 systemd[1]: Initializing machine ID from VM UUID. Oct 8 19:34:30.225258 systemd[1]: Queued start job for default target initrd.target. Oct 8 19:34:30.225278 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 8 19:34:30.225298 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 8 19:34:30.225320 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 8 19:34:30.225341 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 8 19:34:30.225367 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 8 19:34:30.225389 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 8 19:34:30.225413 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 8 19:34:30.225434 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 8 19:34:30.225455 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 8 19:34:30.225477 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 8 19:34:30.225503 systemd[1]: Reached target paths.target - Path Units. Oct 8 19:34:30.225525 systemd[1]: Reached target slices.target - Slice Units. Oct 8 19:34:30.225546 systemd[1]: Reached target swap.target - Swaps. Oct 8 19:34:30.225575 systemd[1]: Reached target timers.target - Timer Units. Oct 8 19:34:30.225626 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 8 19:34:30.225691 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 8 19:34:30.225725 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 8 19:34:30.225753 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 8 19:34:30.225776 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 8 19:34:30.225804 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 8 19:34:30.225844 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 8 19:34:30.225871 systemd[1]: Reached target sockets.target - Socket Units. Oct 8 19:34:30.225892 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 8 19:34:30.225912 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 8 19:34:30.225932 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 8 19:34:30.225953 systemd[1]: Starting systemd-fsck-usr.service... Oct 8 19:34:30.225973 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 8 19:34:30.225994 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 8 19:34:30.226021 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 8 19:34:30.226041 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 8 19:34:30.226061 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 8 19:34:30.226158 systemd-journald[250]: Collecting audit messages is disabled. Oct 8 19:34:30.226213 systemd[1]: Finished systemd-fsck-usr.service. Oct 8 19:34:30.226236 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 8 19:34:30.226256 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 8 19:34:30.226276 systemd-journald[250]: Journal started Oct 8 19:34:30.226319 systemd-journald[250]: Runtime Journal (/run/log/journal/ec25796b706f75f60b05e6187bcaa1a8) is 8.0M, max 75.3M, 67.3M free. Oct 8 19:34:30.192701 systemd-modules-load[251]: Inserted module 'overlay' Oct 8 19:34:30.235766 systemd[1]: Started systemd-journald.service - Journal Service. Oct 8 19:34:30.239132 kernel: Bridge firewalling registered Oct 8 19:34:30.236650 systemd-modules-load[251]: Inserted module 'br_netfilter' Oct 8 19:34:30.243773 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 8 19:34:30.269229 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 8 19:34:30.274303 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 8 19:34:30.280687 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 8 19:34:30.286371 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 8 19:34:30.300792 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 8 19:34:30.306865 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 8 19:34:30.315227 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 8 19:34:30.339665 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 8 19:34:30.353531 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 8 19:34:30.366505 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 8 19:34:30.383477 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 8 19:34:30.391742 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 8 19:34:30.437865 dracut-cmdline[287]: dracut-dracut-053 Oct 8 19:34:30.445873 systemd-resolved[280]: Positive Trust Anchors: Oct 8 19:34:30.445914 systemd-resolved[280]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 8 19:34:30.445977 systemd-resolved[280]: 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 Oct 8 19:34:30.473690 dracut-cmdline[287]: 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=f7968382bc5b46f9b6104a9f012cfba991c8ea306771e716a099618547de81d3 Oct 8 19:34:30.624164 kernel: SCSI subsystem initialized Oct 8 19:34:30.634119 kernel: Loading iSCSI transport class v2.0-870. Oct 8 19:34:30.646134 kernel: iscsi: registered transport (tcp) Oct 8 19:34:30.669137 kernel: iscsi: registered transport (qla4xxx) Oct 8 19:34:30.669211 kernel: QLogic iSCSI HBA Driver Oct 8 19:34:30.716163 kernel: random: crng init done Oct 8 19:34:30.716366 systemd-resolved[280]: Defaulting to hostname 'linux'. Oct 8 19:34:30.719908 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 8 19:34:30.722235 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 8 19:34:30.756260 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 8 19:34:30.770357 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 8 19:34:30.804126 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 8 19:34:30.806862 kernel: device-mapper: uevent: version 1.0.3 Oct 8 19:34:30.806920 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 8 19:34:30.878155 kernel: raid6: neonx8 gen() 6641 MB/s Oct 8 19:34:30.895143 kernel: raid6: neonx4 gen() 6414 MB/s Oct 8 19:34:30.912147 kernel: raid6: neonx2 gen() 5364 MB/s Oct 8 19:34:30.929140 kernel: raid6: neonx1 gen() 3930 MB/s Oct 8 19:34:30.946149 kernel: raid6: int64x8 gen() 3801 MB/s Oct 8 19:34:30.963139 kernel: raid6: int64x4 gen() 3685 MB/s Oct 8 19:34:30.980152 kernel: raid6: int64x2 gen() 3555 MB/s Oct 8 19:34:30.997926 kernel: raid6: int64x1 gen() 2764 MB/s Oct 8 19:34:30.997998 kernel: raid6: using algorithm neonx8 gen() 6641 MB/s Oct 8 19:34:31.015903 kernel: raid6: .... xor() 4927 MB/s, rmw enabled Oct 8 19:34:31.015969 kernel: raid6: using neon recovery algorithm Oct 8 19:34:31.024632 kernel: xor: measuring software checksum speed Oct 8 19:34:31.024724 kernel: 8regs : 11042 MB/sec Oct 8 19:34:31.025719 kernel: 32regs : 11963 MB/sec Oct 8 19:34:31.026905 kernel: arm64_neon : 9267 MB/sec Oct 8 19:34:31.026957 kernel: xor: using function: 32regs (11963 MB/sec) Oct 8 19:34:31.112142 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 8 19:34:31.134470 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 8 19:34:31.145435 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 8 19:34:31.189189 systemd-udevd[468]: Using default interface naming scheme 'v255'. Oct 8 19:34:31.199300 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 8 19:34:31.214863 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 8 19:34:31.255257 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Oct 8 19:34:31.321709 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 8 19:34:31.330484 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 8 19:34:31.463267 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 8 19:34:31.476631 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 8 19:34:31.526606 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 8 19:34:31.532613 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 8 19:34:31.536660 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 8 19:34:31.539787 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 8 19:34:31.562744 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 8 19:34:31.616863 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 8 19:34:31.676171 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 8 19:34:31.683221 kernel: ena 0000:00:05.0: enabling device (0010 -> 0012) Oct 8 19:34:31.689286 kernel: ena 0000:00:05.0: ENA device version: 0.10 Oct 8 19:34:31.689690 kernel: ena 0000:00:05.0: ENA controller version: 0.0.1 implementation version 1 Oct 8 19:34:31.709131 kernel: ena 0000:00:05.0: Elastic Network Adapter (ENA) found at mem 80114000, mac addr 06:70:27:eb:e6:4b Oct 8 19:34:31.709542 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 8 19:34:31.711722 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 8 19:34:31.730695 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 8 19:34:31.733596 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 8 19:34:31.734729 (udev-worker)[517]: Network interface NamePolicy= disabled on kernel command line. Oct 8 19:34:31.736360 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 8 19:34:31.743145 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 8 19:34:31.763545 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 8 19:34:31.773832 kernel: ACPI: \_SB_.PCI0.GSI0: Enabled at IRQ 35 Oct 8 19:34:31.773936 kernel: nvme nvme0: pci function 0000:00:04.0 Oct 8 19:34:31.783177 kernel: nvme nvme0: 2/0/0 default/read/poll queues Oct 8 19:34:31.789490 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 8 19:34:31.789559 kernel: GPT:9289727 != 16777215 Oct 8 19:34:31.790745 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 8 19:34:31.790795 kernel: GPT:9289727 != 16777215 Oct 8 19:34:31.790821 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 8 19:34:31.790847 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 8 19:34:31.805197 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 8 19:34:31.819659 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 8 19:34:31.850950 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 8 19:34:31.936183 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 scanned by (udev-worker) (531) Oct 8 19:34:31.946482 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Amazon Elastic Block Store EFI-SYSTEM. Oct 8 19:34:31.960133 kernel: BTRFS: device fsid ad786f33-c7c5-429e-95f9-4ea457bd3916 devid 1 transid 40 /dev/nvme0n1p3 scanned by (udev-worker) (537) Oct 8 19:34:32.058325 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 8 19:34:32.075903 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Amazon Elastic Block Store ROOT. Oct 8 19:34:32.091776 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Amazon Elastic Block Store USR-A. Oct 8 19:34:32.097225 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Amazon Elastic Block Store USR-A. Oct 8 19:34:32.116666 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 8 19:34:32.131845 disk-uuid[661]: Primary Header is updated. Oct 8 19:34:32.131845 disk-uuid[661]: Secondary Entries is updated. Oct 8 19:34:32.131845 disk-uuid[661]: Secondary Header is updated. Oct 8 19:34:32.146129 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 8 19:34:32.153130 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 8 19:34:32.161131 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 8 19:34:33.161158 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Oct 8 19:34:33.164520 disk-uuid[662]: The operation has completed successfully. Oct 8 19:34:33.331703 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 8 19:34:33.331925 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 8 19:34:33.398358 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 8 19:34:33.407300 sh[1004]: Success Oct 8 19:34:33.438137 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 8 19:34:33.606532 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 8 19:34:33.612294 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 8 19:34:33.618407 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 8 19:34:33.654596 kernel: BTRFS info (device dm-0): first mount of filesystem ad786f33-c7c5-429e-95f9-4ea457bd3916 Oct 8 19:34:33.654659 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 8 19:34:33.654686 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 8 19:34:33.657439 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 8 19:34:33.657475 kernel: BTRFS info (device dm-0): using free space tree Oct 8 19:34:33.802137 kernel: BTRFS info (device dm-0): enabling ssd optimizations Oct 8 19:34:33.823331 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 8 19:34:33.824564 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 8 19:34:33.841449 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 8 19:34:33.846017 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 8 19:34:33.874283 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem cbd8a2bc-d0a3-4040-91fa-086f2a330687 Oct 8 19:34:33.874359 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 8 19:34:33.874388 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 8 19:34:33.880155 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 8 19:34:33.903918 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem cbd8a2bc-d0a3-4040-91fa-086f2a330687 Oct 8 19:34:33.903072 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 8 19:34:33.957386 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 8 19:34:33.970548 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 8 19:34:34.042191 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 8 19:34:34.069414 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 8 19:34:34.110552 systemd-networkd[1196]: lo: Link UP Oct 8 19:34:34.110574 systemd-networkd[1196]: lo: Gained carrier Oct 8 19:34:34.114738 systemd-networkd[1196]: Enumeration completed Oct 8 19:34:34.116266 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 8 19:34:34.116684 systemd-networkd[1196]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 8 19:34:34.116690 systemd-networkd[1196]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 8 19:34:34.125554 systemd-networkd[1196]: eth0: Link UP Oct 8 19:34:34.125562 systemd-networkd[1196]: eth0: Gained carrier Oct 8 19:34:34.125581 systemd-networkd[1196]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 8 19:34:34.129615 systemd[1]: Reached target network.target - Network. Oct 8 19:34:34.147213 systemd-networkd[1196]: eth0: DHCPv4 address 172.31.16.220/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 8 19:34:34.519631 ignition[1138]: Ignition 2.19.0 Oct 8 19:34:34.520193 ignition[1138]: Stage: fetch-offline Oct 8 19:34:34.520747 ignition[1138]: no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:34.520772 ignition[1138]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:34.521360 ignition[1138]: Ignition finished successfully Oct 8 19:34:34.530532 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 8 19:34:34.548399 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 8 19:34:34.572604 ignition[1208]: Ignition 2.19.0 Oct 8 19:34:34.572988 ignition[1208]: Stage: fetch Oct 8 19:34:34.573828 ignition[1208]: no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:34.573857 ignition[1208]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:34.574039 ignition[1208]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:34.590944 ignition[1208]: PUT result: OK Oct 8 19:34:34.593976 ignition[1208]: parsed url from cmdline: "" Oct 8 19:34:34.594151 ignition[1208]: no config URL provided Oct 8 19:34:34.595463 ignition[1208]: reading system config file "/usr/lib/ignition/user.ign" Oct 8 19:34:34.595504 ignition[1208]: no config at "/usr/lib/ignition/user.ign" Oct 8 19:34:34.595555 ignition[1208]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:34.598168 ignition[1208]: PUT result: OK Oct 8 19:34:34.598243 ignition[1208]: GET http://169.254.169.254/2019-10-01/user-data: attempt #1 Oct 8 19:34:34.602059 ignition[1208]: GET result: OK Oct 8 19:34:34.602275 ignition[1208]: parsing config with SHA512: 16357a07b560af183de085421ea9de617762a95cb85ed132d5b8e319c549f15df16d92a5443465ecbf659edcba95b4e4c6572c4bf1052c5f5c10e959f2c9631a Oct 8 19:34:34.620248 unknown[1208]: fetched base config from "system" Oct 8 19:34:34.620842 ignition[1208]: fetch: fetch complete Oct 8 19:34:34.620299 unknown[1208]: fetched base config from "system" Oct 8 19:34:34.620862 ignition[1208]: fetch: fetch passed Oct 8 19:34:34.620316 unknown[1208]: fetched user config from "aws" Oct 8 19:34:34.620946 ignition[1208]: Ignition finished successfully Oct 8 19:34:34.627167 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 8 19:34:34.644581 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 8 19:34:34.679765 ignition[1214]: Ignition 2.19.0 Oct 8 19:34:34.679797 ignition[1214]: Stage: kargs Oct 8 19:34:34.683011 ignition[1214]: no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:34.683054 ignition[1214]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:34.683876 ignition[1214]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:34.689227 ignition[1214]: PUT result: OK Oct 8 19:34:34.693151 ignition[1214]: kargs: kargs passed Oct 8 19:34:34.693247 ignition[1214]: Ignition finished successfully Oct 8 19:34:34.699178 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 8 19:34:34.706388 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 8 19:34:34.739681 ignition[1220]: Ignition 2.19.0 Oct 8 19:34:34.740648 ignition[1220]: Stage: disks Oct 8 19:34:34.742701 ignition[1220]: no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:34.742728 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:34.742885 ignition[1220]: PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:34.747526 ignition[1220]: PUT result: OK Oct 8 19:34:34.753860 ignition[1220]: disks: disks passed Oct 8 19:34:34.753964 ignition[1220]: Ignition finished successfully Oct 8 19:34:34.758078 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 8 19:34:34.762612 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 8 19:34:34.764860 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 8 19:34:34.768961 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 8 19:34:34.772911 systemd[1]: Reached target sysinit.target - System Initialization. Oct 8 19:34:34.776496 systemd[1]: Reached target basic.target - Basic System. Oct 8 19:34:34.787395 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 8 19:34:34.838165 systemd-fsck[1228]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 8 19:34:34.848557 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 8 19:34:34.860147 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 8 19:34:34.951138 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 833c86f3-93dd-4526-bb43-c7809dac8e51 r/w with ordered data mode. Quota mode: none. Oct 8 19:34:34.951874 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 8 19:34:34.955631 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 8 19:34:34.975320 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 8 19:34:34.988537 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 8 19:34:34.992787 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 8 19:34:34.996059 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 8 19:34:34.998557 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 8 19:34:35.006822 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 8 19:34:35.019440 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 8 19:34:35.031392 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/nvme0n1p6 scanned by mount (1247) Oct 8 19:34:35.031446 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem cbd8a2bc-d0a3-4040-91fa-086f2a330687 Oct 8 19:34:35.031473 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 8 19:34:35.031522 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 8 19:34:35.036202 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 8 19:34:35.038476 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 8 19:34:35.473708 initrd-setup-root[1271]: cut: /sysroot/etc/passwd: No such file or directory Oct 8 19:34:35.483652 initrd-setup-root[1278]: cut: /sysroot/etc/group: No such file or directory Oct 8 19:34:35.493706 initrd-setup-root[1285]: cut: /sysroot/etc/shadow: No such file or directory Oct 8 19:34:35.501991 initrd-setup-root[1292]: cut: /sysroot/etc/gshadow: No such file or directory Oct 8 19:34:35.720350 systemd-networkd[1196]: eth0: Gained IPv6LL Oct 8 19:34:35.860248 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 8 19:34:35.869331 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 8 19:34:35.879549 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 8 19:34:35.899743 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 8 19:34:35.902016 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem cbd8a2bc-d0a3-4040-91fa-086f2a330687 Oct 8 19:34:35.933181 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 8 19:34:35.951579 ignition[1360]: INFO : Ignition 2.19.0 Oct 8 19:34:35.951579 ignition[1360]: INFO : Stage: mount Oct 8 19:34:35.955609 ignition[1360]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:35.955609 ignition[1360]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:35.955609 ignition[1360]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:35.962684 ignition[1360]: INFO : PUT result: OK Oct 8 19:34:35.966679 ignition[1360]: INFO : mount: mount passed Oct 8 19:34:35.968241 ignition[1360]: INFO : Ignition finished successfully Oct 8 19:34:35.971238 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 8 19:34:35.979252 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 8 19:34:36.018378 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 8 19:34:36.052208 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/nvme0n1p6 scanned by mount (1372) Oct 8 19:34:36.055748 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem cbd8a2bc-d0a3-4040-91fa-086f2a330687 Oct 8 19:34:36.055795 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-generic) checksum algorithm Oct 8 19:34:36.055822 kernel: BTRFS info (device nvme0n1p6): using free space tree Oct 8 19:34:36.061129 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Oct 8 19:34:36.064517 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 8 19:34:36.107504 ignition[1389]: INFO : Ignition 2.19.0 Oct 8 19:34:36.107504 ignition[1389]: INFO : Stage: files Oct 8 19:34:36.110787 ignition[1389]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:36.110787 ignition[1389]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:36.114891 ignition[1389]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:36.118152 ignition[1389]: INFO : PUT result: OK Oct 8 19:34:36.122014 ignition[1389]: DEBUG : files: compiled without relabeling support, skipping Oct 8 19:34:36.124450 ignition[1389]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 8 19:34:36.124450 ignition[1389]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 8 19:34:36.178417 ignition[1389]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 8 19:34:36.181038 ignition[1389]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 8 19:34:36.184059 unknown[1389]: wrote ssh authorized keys file for user: core Oct 8 19:34:36.188118 ignition[1389]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 8 19:34:36.196812 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 8 19:34:36.200573 ignition[1389]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 8 19:34:36.200573 ignition[1389]: INFO : files: op(4): [started] processing unit "etcd-member.service" Oct 8 19:34:36.205975 ignition[1389]: 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" Oct 8 19:34:36.205975 ignition[1389]: 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" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: op(6): [started] setting preset to enabled for "etcd-member.service" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: op(6): [finished] setting preset to enabled for "etcd-member.service" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 8 19:34:36.205975 ignition[1389]: INFO : files: files passed Oct 8 19:34:36.205975 ignition[1389]: INFO : Ignition finished successfully Oct 8 19:34:36.220851 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 8 19:34:36.243538 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 8 19:34:36.250065 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 8 19:34:36.264659 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 8 19:34:36.264902 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 8 19:34:36.283702 initrd-setup-root-after-ignition[1417]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 8 19:34:36.283702 initrd-setup-root-after-ignition[1417]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 8 19:34:36.291260 initrd-setup-root-after-ignition[1421]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 8 19:34:36.298418 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 8 19:34:36.303470 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 8 19:34:36.324333 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 8 19:34:36.374862 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 8 19:34:36.375059 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 8 19:34:36.380939 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 8 19:34:36.384806 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 8 19:34:36.386818 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 8 19:34:36.404236 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 8 19:34:36.432436 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 8 19:34:36.446525 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 8 19:34:36.482610 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 8 19:34:36.487078 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 8 19:34:36.492077 systemd[1]: Stopped target timers.target - Timer Units. Oct 8 19:34:36.494145 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 8 19:34:36.494406 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 8 19:34:36.502232 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 8 19:34:36.504525 systemd[1]: Stopped target basic.target - Basic System. Oct 8 19:34:36.509951 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 8 19:34:36.512075 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 8 19:34:36.514498 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 8 19:34:36.525673 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 8 19:34:36.527951 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 8 19:34:36.530628 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 8 19:34:36.537881 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 8 19:34:36.542581 systemd[1]: Stopped target swap.target - Swaps. Oct 8 19:34:36.544259 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 8 19:34:36.544488 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 8 19:34:36.551823 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 8 19:34:36.555823 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 8 19:34:36.559328 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 8 19:34:36.561905 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 8 19:34:36.565231 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 8 19:34:36.565508 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 8 19:34:36.573199 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 8 19:34:36.573622 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 8 19:34:36.580457 systemd[1]: ignition-files.service: Deactivated successfully. Oct 8 19:34:36.580859 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 8 19:34:36.595511 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 8 19:34:36.603200 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 8 19:34:36.604946 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 8 19:34:36.605364 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 8 19:34:36.609039 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 8 19:34:36.613201 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 8 19:34:36.631594 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 8 19:34:36.633911 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 8 19:34:36.648520 ignition[1441]: INFO : Ignition 2.19.0 Oct 8 19:34:36.650405 ignition[1441]: INFO : Stage: umount Oct 8 19:34:36.652616 ignition[1441]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 8 19:34:36.655219 ignition[1441]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/aws" Oct 8 19:34:36.655219 ignition[1441]: INFO : PUT http://169.254.169.254/latest/api/token: attempt #1 Oct 8 19:34:36.659932 ignition[1441]: INFO : PUT result: OK Oct 8 19:34:36.666457 ignition[1441]: INFO : umount: umount passed Oct 8 19:34:36.668539 ignition[1441]: INFO : Ignition finished successfully Oct 8 19:34:36.674895 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 8 19:34:36.676921 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 8 19:34:36.679128 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 8 19:34:36.679236 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 8 19:34:36.681270 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 8 19:34:36.681356 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 8 19:34:36.683340 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 8 19:34:36.683425 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 8 19:34:36.685464 systemd[1]: Stopped target network.target - Network. Oct 8 19:34:36.687195 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 8 19:34:36.687294 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 8 19:34:36.689449 systemd[1]: Stopped target paths.target - Path Units. Oct 8 19:34:36.692760 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 8 19:34:36.713771 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 8 19:34:36.716192 systemd[1]: Stopped target slices.target - Slice Units. Oct 8 19:34:36.723502 systemd[1]: Stopped target sockets.target - Socket Units. Oct 8 19:34:36.726962 systemd[1]: iscsid.socket: Deactivated successfully. Oct 8 19:34:36.727060 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 8 19:34:36.730470 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 8 19:34:36.730547 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 8 19:34:36.737325 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 8 19:34:36.737437 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 8 19:34:36.741021 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 8 19:34:36.741159 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 8 19:34:36.748558 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 8 19:34:36.751786 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 8 19:34:36.757164 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 8 19:34:36.758372 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 8 19:34:36.758598 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 8 19:34:36.761528 systemd-networkd[1196]: eth0: DHCPv6 lease lost Oct 8 19:34:36.768569 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 8 19:34:36.771648 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 8 19:34:36.777267 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 8 19:34:36.777554 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 8 19:34:36.787390 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 8 19:34:36.787499 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 8 19:34:36.794485 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 8 19:34:36.794592 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 8 19:34:36.812993 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 8 19:34:36.818126 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 8 19:34:36.818247 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 8 19:34:36.820781 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 8 19:34:36.820876 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 8 19:34:36.822971 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 8 19:34:36.823059 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 8 19:34:36.835373 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 8 19:34:36.835473 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 8 19:34:36.838362 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 8 19:34:36.863929 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 8 19:34:36.865168 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 8 19:34:36.875228 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 8 19:34:36.876416 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 8 19:34:36.883784 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 8 19:34:36.884079 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 8 19:34:36.890248 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 8 19:34:36.890344 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 8 19:34:36.907832 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 8 19:34:36.909687 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 8 19:34:36.915357 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 8 19:34:36.915468 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 8 19:34:36.917988 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 8 19:34:36.918071 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 8 19:34:36.935703 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 8 19:34:36.939481 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 8 19:34:36.939639 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 8 19:34:36.943138 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 8 19:34:36.943226 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 8 19:34:36.945926 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 8 19:34:36.946011 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 8 19:34:36.948651 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 8 19:34:36.948731 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 8 19:34:36.995511 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 8 19:34:36.995907 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 8 19:34:37.002368 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 8 19:34:37.021407 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 8 19:34:37.037810 systemd[1]: Switching root. Oct 8 19:34:37.108127 systemd-journald[250]: Received SIGTERM from PID 1 (systemd). Oct 8 19:34:37.108216 systemd-journald[250]: Journal stopped Oct 8 19:34:39.686274 kernel: SELinux: policy capability network_peer_controls=1 Oct 8 19:34:39.686415 kernel: SELinux: policy capability open_perms=1 Oct 8 19:34:39.686458 kernel: SELinux: policy capability extended_socket_class=1 Oct 8 19:34:39.686489 kernel: SELinux: policy capability always_check_network=0 Oct 8 19:34:39.686520 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 8 19:34:39.686550 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 8 19:34:39.686580 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 8 19:34:39.686617 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 8 19:34:39.686648 kernel: audit: type=1403 audit(1728416077.673:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 8 19:34:39.686694 systemd[1]: Successfully loaded SELinux policy in 60.879ms. Oct 8 19:34:39.686742 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.203ms. Oct 8 19:34:39.686782 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) Oct 8 19:34:39.686815 systemd[1]: Detected virtualization amazon. Oct 8 19:34:39.686847 systemd[1]: Detected architecture arm64. Oct 8 19:34:39.686878 systemd[1]: Detected first boot. Oct 8 19:34:39.686912 systemd[1]: Initializing machine ID from VM UUID. Oct 8 19:34:39.686948 zram_generator::config[1483]: No configuration found. Oct 8 19:34:39.686986 systemd[1]: Populated /etc with preset unit settings. Oct 8 19:34:39.687017 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 8 19:34:39.687052 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 8 19:34:39.687084 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 8 19:34:39.687157 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 8 19:34:39.687209 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 8 19:34:39.687248 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 8 19:34:39.687278 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 8 19:34:39.687311 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 8 19:34:39.687346 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 8 19:34:39.687380 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 8 19:34:39.687413 systemd[1]: Created slice user.slice - User and Session Slice. Oct 8 19:34:39.687448 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 8 19:34:39.687479 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 8 19:34:39.687513 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 8 19:34:39.687547 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 8 19:34:39.687578 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 8 19:34:39.687611 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 8 19:34:39.687643 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 8 19:34:39.687674 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 8 19:34:39.687703 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 8 19:34:39.687737 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 8 19:34:39.687771 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 8 19:34:39.687804 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 8 19:34:39.687836 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 8 19:34:39.687867 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 8 19:34:39.687898 systemd[1]: Reached target slices.target - Slice Units. Oct 8 19:34:39.687930 systemd[1]: Reached target swap.target - Swaps. Oct 8 19:34:39.687959 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 8 19:34:39.687994 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 8 19:34:39.688023 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 8 19:34:39.688053 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 8 19:34:39.688085 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 8 19:34:39.691150 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 8 19:34:39.691194 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 8 19:34:39.691230 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 8 19:34:39.691262 systemd[1]: Mounting media.mount - External Media Directory... Oct 8 19:34:39.691293 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 8 19:34:39.691333 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 8 19:34:39.691363 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 8 19:34:39.691396 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). Oct 8 19:34:39.691428 systemd[1]: Reached target machines.target - Containers. Oct 8 19:34:39.691458 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 8 19:34:39.691490 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 8 19:34:39.691520 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 8 19:34:39.691550 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 8 19:34:39.691579 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 8 19:34:39.691613 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 8 19:34:39.691643 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 8 19:34:39.691675 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 8 19:34:39.691706 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 8 19:34:39.691749 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 8 19:34:39.691786 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 8 19:34:39.691817 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 8 19:34:39.691849 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 8 19:34:39.691885 systemd[1]: Stopped systemd-fsck-usr.service. Oct 8 19:34:39.691915 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 8 19:34:39.691946 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 8 19:34:39.691978 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 8 19:34:39.692011 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 8 19:34:39.692044 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 8 19:34:39.692088 systemd[1]: verity-setup.service: Deactivated successfully. Oct 8 19:34:39.692155 systemd[1]: Stopped verity-setup.service. Oct 8 19:34:39.692186 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 8 19:34:39.692224 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 8 19:34:39.692254 systemd[1]: Mounted media.mount - External Media Directory. Oct 8 19:34:39.692299 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 8 19:34:39.692334 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 8 19:34:39.692366 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 8 19:34:39.692397 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 8 19:34:39.692434 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 8 19:34:39.692464 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 8 19:34:39.692496 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 8 19:34:39.692526 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 8 19:34:39.692556 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 8 19:34:39.692586 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 8 19:34:39.692616 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 8 19:34:39.692647 kernel: fuse: init (API version 7.39) Oct 8 19:34:39.692682 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 8 19:34:39.692714 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 8 19:34:39.692749 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 8 19:34:39.692779 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 8 19:34:39.692812 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 8 19:34:39.692845 kernel: loop: module loaded Oct 8 19:34:39.692887 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 8 19:34:39.692917 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 8 19:34:39.692948 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 8 19:34:39.692980 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 8 19:34:39.693010 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 8 19:34:39.693052 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 8 19:34:39.698482 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 8 19:34:39.698563 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 8 19:34:39.698608 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 8 19:34:39.698686 systemd-journald[1566]: Collecting audit messages is disabled. Oct 8 19:34:39.698748 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 8 19:34:39.698779 kernel: ACPI: bus type drm_connector registered Oct 8 19:34:39.698808 systemd-journald[1566]: Journal started Oct 8 19:34:39.698854 systemd-journald[1566]: Runtime Journal (/run/log/journal/ec25796b706f75f60b05e6187bcaa1a8) is 8.0M, max 75.3M, 67.3M free. Oct 8 19:34:38.988739 systemd[1]: Queued start job for default target multi-user.target. Oct 8 19:34:39.064914 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Oct 8 19:34:39.065908 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 8 19:34:39.718186 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 8 19:34:39.718266 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 8 19:34:39.734671 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 8 19:34:39.734758 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 8 19:34:39.754962 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 8 19:34:39.755156 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 8 19:34:39.755215 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 8 19:34:39.763196 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 8 19:34:39.776697 systemd[1]: Started systemd-journald.service - Journal Service. Oct 8 19:34:39.779230 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 8 19:34:39.780726 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 8 19:34:39.783474 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 8 19:34:39.786567 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 8 19:34:39.811117 systemd-tmpfiles[1578]: ACLs are not supported, ignoring. Oct 8 19:34:39.811159 systemd-tmpfiles[1578]: ACLs are not supported, ignoring. Oct 8 19:34:39.835246 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 8 19:34:39.863247 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 8 19:34:39.897823 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 8 19:34:39.910451 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 8 19:34:39.942810 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 8 19:34:39.945374 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 8 19:34:39.952491 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 8 19:34:39.982322 kernel: loop0: detected capacity change from 0 to 114328 Oct 8 19:34:39.999335 systemd-journald[1566]: Time spent on flushing to /var/log/journal/ec25796b706f75f60b05e6187bcaa1a8 is 56.852ms for 899 entries. Oct 8 19:34:39.999335 systemd-journald[1566]: System Journal (/var/log/journal/ec25796b706f75f60b05e6187bcaa1a8) is 8.0M, max 195.6M, 187.6M free. Oct 8 19:34:40.064712 systemd-journald[1566]: Received client request to flush runtime journal. Oct 8 19:34:40.042671 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 8 19:34:40.071805 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 8 19:34:40.108211 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 8 19:34:40.119451 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 8 19:34:40.123671 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 8 19:34:40.137716 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 8 19:34:40.157481 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 8 19:34:40.166748 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 8 19:34:40.185354 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 8 19:34:40.213626 systemd-tmpfiles[1629]: ACLs are not supported, ignoring. Oct 8 19:34:40.213668 systemd-tmpfiles[1629]: ACLs are not supported, ignoring. Oct 8 19:34:40.218147 kernel: loop1: detected capacity change from 0 to 114432 Oct 8 19:34:40.243544 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 8 19:34:40.247522 udevadm[1631]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 8 19:34:40.356144 kernel: loop2: detected capacity change from 0 to 52536 Oct 8 19:34:40.494208 kernel: loop3: detected capacity change from 0 to 114328 Oct 8 19:34:40.521426 kernel: loop4: detected capacity change from 0 to 114432 Oct 8 19:34:40.537181 kernel: loop5: detected capacity change from 0 to 52536 Oct 8 19:34:40.546719 (sd-merge)[1640]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-ami'. Oct 8 19:34:40.548438 (sd-merge)[1640]: Merged extensions into '/usr'. Oct 8 19:34:40.554924 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 8 19:34:40.571439 systemd[1]: Starting ensure-sysext.service... Oct 8 19:34:40.583488 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 8 19:34:40.609294 systemd[1]: Reloading requested from client PID 1642 ('systemctl') (unit ensure-sysext.service)... Oct 8 19:34:40.609352 systemd[1]: Reloading... Oct 8 19:34:40.686957 systemd-tmpfiles[1643]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 8 19:34:40.687731 systemd-tmpfiles[1643]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 8 19:34:40.695396 systemd-tmpfiles[1643]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 8 19:34:40.696040 systemd-tmpfiles[1643]: ACLs are not supported, ignoring. Oct 8 19:34:40.702270 systemd-tmpfiles[1643]: ACLs are not supported, ignoring. Oct 8 19:34:40.726802 systemd-tmpfiles[1643]: Detected autofs mount point /boot during canonicalization of boot. Oct 8 19:34:40.726843 systemd-tmpfiles[1643]: Skipping /boot Oct 8 19:34:40.777646 zram_generator::config[1670]: No configuration found. Oct 8 19:34:40.806086 systemd-tmpfiles[1643]: Detected autofs mount point /boot during canonicalization of boot. Oct 8 19:34:40.808459 systemd-tmpfiles[1643]: Skipping /boot Oct 8 19:34:41.199915 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. Oct 8 19:34:41.317053 systemd[1]: Reloading finished in 706 ms. Oct 8 19:34:41.360388 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 8 19:34:41.370193 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 8 19:34:41.397449 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 8 19:34:41.414456 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 8 19:34:41.421597 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 8 19:34:41.437987 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 8 19:34:41.449450 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 8 19:34:41.457653 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 8 19:34:41.472255 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 8 19:34:41.485523 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 8 19:34:41.495159 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 8 19:34:41.502068 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 8 19:34:41.504483 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 8 19:34:41.511619 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 8 19:34:41.512027 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 8 19:34:41.523570 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 8 19:34:41.529634 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 8 19:34:41.532495 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 8 19:34:41.532894 systemd[1]: Reached target time-set.target - System Time Set. Oct 8 19:34:41.554516 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 8 19:34:41.559197 systemd[1]: Finished ensure-sysext.service. Oct 8 19:34:41.607017 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 8 19:34:41.632759 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 8 19:34:41.636593 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 8 19:34:41.674385 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 8 19:34:41.678026 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 8 19:34:41.678478 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 8 19:34:41.681936 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 8 19:34:41.683867 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 8 19:34:41.692728 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 8 19:34:41.692927 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 8 19:34:41.702039 systemd-udevd[1729]: Using default interface naming scheme 'v255'. Oct 8 19:34:41.704817 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 8 19:34:41.705562 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 8 19:34:41.745857 ldconfig[1587]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 8 19:34:41.759238 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 8 19:34:41.773580 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 8 19:34:41.779841 augenrules[1759]: No rules Oct 8 19:34:41.781289 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 8 19:34:41.790085 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 8 19:34:41.793042 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). Oct 8 19:34:41.793839 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 8 19:34:41.805326 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 8 19:34:41.822837 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 8 19:34:41.845235 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 8 19:34:42.055839 systemd-resolved[1728]: Positive Trust Anchors: Oct 8 19:34:42.056475 systemd-resolved[1728]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 8 19:34:42.056547 systemd-resolved[1728]: 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 Oct 8 19:34:42.062667 systemd-networkd[1771]: lo: Link UP Oct 8 19:34:42.062689 systemd-networkd[1771]: lo: Gained carrier Oct 8 19:34:42.066300 systemd-networkd[1771]: Enumeration completed Oct 8 19:34:42.066490 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 8 19:34:42.074928 systemd-resolved[1728]: Defaulting to hostname 'linux'. Oct 8 19:34:42.093814 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 8 19:34:42.093869 (udev-worker)[1789]: Network interface NamePolicy= disabled on kernel command line. Oct 8 19:34:42.096255 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 8 19:34:42.098611 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 8 19:34:42.098676 systemd[1]: Reached target network.target - Network. Oct 8 19:34:42.100467 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 8 19:34:42.119158 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1778) Oct 8 19:34:42.140195 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1778) Oct 8 19:34:42.201375 systemd-networkd[1771]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 8 19:34:42.201398 systemd-networkd[1771]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 8 19:34:42.204521 systemd-networkd[1771]: eth0: Link UP Oct 8 19:34:42.204891 systemd-networkd[1771]: eth0: Gained carrier Oct 8 19:34:42.204924 systemd-networkd[1771]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 8 19:34:42.218237 systemd-networkd[1771]: eth0: DHCPv4 address 172.31.16.220/20, gateway 172.31.16.1 acquired from 172.31.16.1 Oct 8 19:34:42.272157 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1781) Oct 8 19:34:42.436793 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 8 19:34:42.537412 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Amazon Elastic Block Store OEM. Oct 8 19:34:42.540870 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 8 19:34:42.554438 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 8 19:34:42.568488 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 8 19:34:42.599438 lvm[1892]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 8 19:34:42.608290 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 8 19:34:42.624075 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 8 19:34:42.640084 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 8 19:34:42.643917 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 8 19:34:42.646238 systemd[1]: Reached target sysinit.target - System Initialization. Oct 8 19:34:42.648569 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 8 19:34:42.651183 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 8 19:34:42.653973 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 8 19:34:42.657140 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 8 19:34:42.659928 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 8 19:34:42.662302 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 8 19:34:42.662523 systemd[1]: Reached target paths.target - Path Units. Oct 8 19:34:42.664437 systemd[1]: Reached target timers.target - Timer Units. Oct 8 19:34:42.667620 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 8 19:34:42.672474 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 8 19:34:42.681548 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 8 19:34:42.689454 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 8 19:34:42.693558 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 8 19:34:42.695913 systemd[1]: Reached target sockets.target - Socket Units. Oct 8 19:34:42.697979 systemd[1]: Reached target basic.target - Basic System. Oct 8 19:34:42.700562 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 8 19:34:42.700627 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 8 19:34:42.714507 systemd[1]: Starting containerd.service - containerd container runtime... Oct 8 19:34:42.724604 lvm[1902]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 8 19:34:42.728415 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 8 19:34:42.737508 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 8 19:34:42.743472 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 8 19:34:42.757611 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 8 19:34:42.759693 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). Oct 8 19:34:42.765334 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 8 19:34:42.771230 systemd[1]: Started ntpd.service - Network Time Service. Oct 8 19:34:42.778341 systemd[1]: Starting setup-oem.service - Setup OEM... Oct 8 19:34:42.788994 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 8 19:34:42.794475 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 8 19:34:42.806855 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 8 19:34:42.809974 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 8 19:34:42.811134 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 8 19:34:42.814185 systemd[1]: Starting update-engine.service - Update Engine... Oct 8 19:34:42.822385 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 8 19:34:42.876994 jq[1906]: false Oct 8 19:34:42.880232 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 8 19:34:42.882837 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 8 19:34:42.909042 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 8 19:34:42.946121 extend-filesystems[1907]: Found loop3 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found loop4 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found loop5 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found nvme0n1 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found nvme0n1p1 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found nvme0n1p2 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found nvme0n1p3 Oct 8 19:34:42.946121 extend-filesystems[1907]: Found usr Oct 8 19:34:42.946121 extend-filesystems[1907]: Found nvme0n1p4 Oct 8 19:34:42.963366 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 8 19:34:42.969279 extend-filesystems[1907]: Found nvme0n1p6 Oct 8 19:34:42.969279 extend-filesystems[1907]: Found nvme0n1p7 Oct 8 19:34:42.969279 extend-filesystems[1907]: Found nvme0n1p9 Oct 8 19:34:42.969279 extend-filesystems[1907]: Checking size of /dev/nvme0n1p9 Oct 8 19:34:42.970898 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 8 19:34:42.971469 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 8 19:34:43.014061 jq[1915]: true Oct 8 19:34:43.030339 ntpd[1909]: ntpd 4.2.8p17@1.4004-o Tue Oct 8 17:50:55 UTC 2024 (1): Starting Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: ntpd 4.2.8p17@1.4004-o Tue Oct 8 17:50:55 UTC 2024 (1): Starting Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: ---------------------------------------------------- Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: ntp-4 is maintained by Network Time Foundation, Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: corporation. Support and training for ntp-4 are Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: available at https://www.nwtime.org/support Oct 8 19:34:43.040449 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: ---------------------------------------------------- Oct 8 19:34:43.030396 ntpd[1909]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Oct 8 19:34:43.040720 (ntainerd)[1936]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 8 19:34:43.030418 ntpd[1909]: ---------------------------------------------------- Oct 8 19:34:43.030438 ntpd[1909]: ntp-4 is maintained by Network Time Foundation, Oct 8 19:34:43.030456 ntpd[1909]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Oct 8 19:34:43.030478 ntpd[1909]: corporation. Support and training for ntp-4 are Oct 8 19:34:43.030497 ntpd[1909]: available at https://www.nwtime.org/support Oct 8 19:34:43.030517 ntpd[1909]: ---------------------------------------------------- Oct 8 19:34:43.050058 ntpd[1909]: proto: precision = 0.096 usec (-23) Oct 8 19:34:43.050252 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: proto: precision = 0.096 usec (-23) Oct 8 19:34:43.057418 extend-filesystems[1907]: Resized partition /dev/nvme0n1p9 Oct 8 19:34:43.061342 systemd[1]: motdgen.service: Deactivated successfully. Oct 8 19:34:43.063258 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 8 19:34:43.068543 ntpd[1909]: basedate set to 2024-09-26 Oct 8 19:34:43.069995 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: basedate set to 2024-09-26 Oct 8 19:34:43.069995 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: gps base set to 2024-09-29 (week 2334) Oct 8 19:34:43.068587 ntpd[1909]: gps base set to 2024-09-29 (week 2334) Oct 8 19:34:43.081918 extend-filesystems[1947]: resize2fs 1.47.1 (20-May-2024) Oct 8 19:34:43.084993 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listen and drop on 0 v6wildcard [::]:123 Oct 8 19:34:43.084993 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 8 19:34:43.081656 ntpd[1909]: Listen and drop on 0 v6wildcard [::]:123 Oct 8 19:34:43.081772 ntpd[1909]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 8 19:34:43.091400 jq[1938]: true Oct 8 19:34:43.102401 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 553472 to 1489915 blocks Oct 8 19:34:43.092715 ntpd[1909]: Listen normally on 2 lo 127.0.0.1:123 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listen normally on 2 lo 127.0.0.1:123 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listen normally on 3 eth0 172.31.16.220:123 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listen normally on 4 lo [::1]:123 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: bind(21) AF_INET6 fe80::470:27ff:feeb:e64b%2#123 flags 0x11 failed: Cannot assign requested address Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: unable to create socket on eth0 (5) for fe80::470:27ff:feeb:e64b%2#123 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: failed to init interface for address fe80::470:27ff:feeb:e64b%2 Oct 8 19:34:43.102654 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: Listening on routing socket on fd #21 for interface updates Oct 8 19:34:43.092791 ntpd[1909]: Listen normally on 3 eth0 172.31.16.220:123 Oct 8 19:34:43.092858 ntpd[1909]: Listen normally on 4 lo [::1]:123 Oct 8 19:34:43.092938 ntpd[1909]: bind(21) AF_INET6 fe80::470:27ff:feeb:e64b%2#123 flags 0x11 failed: Cannot assign requested address Oct 8 19:34:43.092980 ntpd[1909]: unable to create socket on eth0 (5) for fe80::470:27ff:feeb:e64b%2#123 Oct 8 19:34:43.093008 ntpd[1909]: failed to init interface for address fe80::470:27ff:feeb:e64b%2 Oct 8 19:34:43.093070 ntpd[1909]: Listening on routing socket on fd #21 for interface updates Oct 8 19:34:43.104748 dbus-daemon[1905]: [system] SELinux support is enabled Oct 8 19:34:43.105175 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 8 19:34:43.115976 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). Oct 8 19:34:43.124637 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 8 19:34:43.124637 ntpd[1909]: 8 Oct 19:34:43 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 8 19:34:43.122206 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 8 19:34:43.116066 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 8 19:34:43.122289 ntpd[1909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 8 19:34:43.118605 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). Oct 8 19:34:43.118645 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 8 19:34:43.134196 update_engine[1914]: I20241008 19:34:43.130941 1914 main.cc:92] Flatcar Update Engine starting Oct 8 19:34:43.155839 dbus-daemon[1905]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=244 pid=1771 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Oct 8 19:34:43.188612 update_engine[1914]: I20241008 19:34:43.180574 1914 update_check_scheduler.cc:74] Next update check in 9m33s Oct 8 19:34:43.184619 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Oct 8 19:34:43.186912 systemd[1]: Started update-engine.service - Update Engine. Oct 8 19:34:43.194142 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 8 19:34:43.234695 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 1489915 Oct 8 19:34:43.246125 coreos-metadata[1904]: Oct 08 19:34:43.243 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.246 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-id: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.247 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.247 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/instance-type: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.248 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.248 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/local-ipv4: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.248 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.248 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-ipv4: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.249 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.249 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/ipv6: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.250 INFO Fetch failed with 404: resource not found Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.250 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.250 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.250 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/placement/availability-zone-id: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.251 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.251 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/hostname: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.252 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.252 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-hostname: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.253 INFO Fetch successful Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.253 INFO Fetching http://169.254.169.254/2021-01-03/dynamic/instance-identity/document: Attempt #1 Oct 8 19:34:43.267850 coreos-metadata[1904]: Oct 08 19:34:43.254 INFO Fetch successful Oct 8 19:34:43.277644 extend-filesystems[1947]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Oct 8 19:34:43.277644 extend-filesystems[1947]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 8 19:34:43.277644 extend-filesystems[1947]: The filesystem on /dev/nvme0n1p9 is now 1489915 (4k) blocks long. Oct 8 19:34:43.310858 extend-filesystems[1907]: Resized filesystem in /dev/nvme0n1p9 Oct 8 19:34:43.278758 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 8 19:34:43.281286 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 8 19:34:43.361815 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (1789) Oct 8 19:34:43.386168 systemd[1]: Finished setup-oem.service - Setup OEM. Oct 8 19:34:43.388874 systemd-logind[1913]: Watching system buttons on /dev/input/event0 (Power Button) Oct 8 19:34:43.388926 systemd-logind[1913]: Watching system buttons on /dev/input/event1 (Sleep Button) Oct 8 19:34:43.390455 systemd-logind[1913]: New seat seat0. Oct 8 19:34:43.396390 systemd[1]: Started systemd-logind.service - User Login Management. Oct 8 19:34:43.401374 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 8 19:34:43.404051 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 8 19:34:43.519435 bash[2005]: Updated "/home/core/.ssh/authorized_keys" Oct 8 19:34:43.520824 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 8 19:34:43.533868 systemd[1]: Starting sshkeys.service... Oct 8 19:34:43.676257 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 8 19:34:43.693814 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 8 19:34:43.721967 dbus-daemon[1905]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 8 19:34:43.726267 dbus-daemon[1905]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1955 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Oct 8 19:34:43.752387 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 8 19:34:43.756355 containerd[1936]: time="2024-10-08T19:34:43.755475958Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Oct 8 19:34:43.774910 locksmithd[1956]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 8 19:34:43.788066 systemd[1]: Starting polkit.service - Authorization Manager... Oct 8 19:34:43.854262 polkitd[2072]: Started polkitd version 121 Oct 8 19:34:43.876434 polkitd[2072]: Loading rules from directory /etc/polkit-1/rules.d Oct 8 19:34:43.876580 polkitd[2072]: Loading rules from directory /usr/share/polkit-1/rules.d Oct 8 19:34:43.881770 polkitd[2072]: Finished loading, compiling and executing 2 rules Oct 8 19:34:43.882782 dbus-daemon[1905]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 8 19:34:43.883044 systemd[1]: Started polkit.service - Authorization Manager. Oct 8 19:34:43.890255 polkitd[2072]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Oct 8 19:34:43.939429 containerd[1936]: time="2024-10-08T19:34:43.939350483Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.965525 containerd[1936]: time="2024-10-08T19:34:43.965430131Z" 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.54-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 8 19:34:43.965525 containerd[1936]: time="2024-10-08T19:34:43.965508011Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 8 19:34:43.965708 containerd[1936]: time="2024-10-08T19:34:43.965545763Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 8 19:34:43.965933 containerd[1936]: time="2024-10-08T19:34:43.965883695Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 8 19:34:43.966008 containerd[1936]: time="2024-10-08T19:34:43.965931935Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.966147 containerd[1936]: time="2024-10-08T19:34:43.966060371Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 8 19:34:43.970254 containerd[1936]: time="2024-10-08T19:34:43.970198847Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.970780679Z" 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 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.970827515Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.970863179Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.970889483Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.971084279Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.971702 containerd[1936]: time="2024-10-08T19:34:43.971628023Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 8 19:34:43.972990 containerd[1936]: time="2024-10-08T19:34:43.972936395Z" 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 Oct 8 19:34:43.974985 containerd[1936]: time="2024-10-08T19:34:43.974191271Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 8 19:34:43.974985 containerd[1936]: time="2024-10-08T19:34:43.974522987Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 8 19:34:43.974985 containerd[1936]: time="2024-10-08T19:34:43.974640155Z" level=info msg="metadata content store policy set" policy=shared Oct 8 19:34:43.976409 systemd-networkd[1771]: eth0: Gained IPv6LL Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.983662307Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.983770355Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.983910623Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.983948699Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.983983775Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 8 19:34:43.985195 containerd[1936]: time="2024-10-08T19:34:43.984387047Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 8 19:34:43.987255 coreos-metadata[2053]: Oct 08 19:34:43.987 INFO Putting http://169.254.169.254/latest/api/token: Attempt #1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989439251Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989793647Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989829647Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989859779Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989902967Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989933651Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989962883Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.989996315Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990029075Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990060887Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990108899Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990142991Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990183815Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.991585 containerd[1936]: time="2024-10-08T19:34:43.990216659Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990247115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990277691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990306503Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990337463Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990367631Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990397511Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990430463Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990468539Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990495959Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990523379Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990552059Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990585407Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990626999Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990655439Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.992594 containerd[1936]: time="2024-10-08T19:34:43.990681527Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990829091Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990865403Z" 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 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990891227Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990921407Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990947411Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.990975707Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.991000595Z" level=info msg="NRI interface is disabled by configuration." Oct 8 19:34:43.995226 containerd[1936]: time="2024-10-08T19:34:43.991026719Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 8 19:34:43.995576 coreos-metadata[2053]: Oct 08 19:34:43.993 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys: Attempt #1 Oct 8 19:34:43.996571 coreos-metadata[2053]: Oct 08 19:34:43.995 INFO Fetch successful Oct 8 19:34:43.996571 coreos-metadata[2053]: Oct 08 19:34:43.996 INFO Fetching http://169.254.169.254/2021-01-03/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 8 19:34:43.997892 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 8 19:34:44.008157 containerd[1936]: time="2024-10-08T19:34:44.007008812Z" 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}" Oct 8 19:34:44.008157 containerd[1936]: time="2024-10-08T19:34:44.007260476Z" level=info msg="Connect containerd service" Oct 8 19:34:44.008157 containerd[1936]: time="2024-10-08T19:34:44.007348688Z" level=info msg="using legacy CRI server" Oct 8 19:34:44.008157 containerd[1936]: time="2024-10-08T19:34:44.007368596Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 8 19:34:44.008157 containerd[1936]: time="2024-10-08T19:34:44.007547480Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 8 19:34:44.015297 containerd[1936]: time="2024-10-08T19:34:44.014760788Z" 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" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.019926572Z" level=info msg="Start subscribing containerd event" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.020074868Z" level=info msg="Start recovering state" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.020238236Z" level=info msg="Start event monitor" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.020271836Z" level=info msg="Start snapshots syncer" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.020294600Z" level=info msg="Start cni network conf syncer for default" Oct 8 19:34:44.021079 containerd[1936]: time="2024-10-08T19:34:44.020313920Z" level=info msg="Start streaming server" Oct 8 19:34:44.021493 coreos-metadata[2053]: Oct 08 19:34:44.020 INFO Fetch successful Oct 8 19:34:44.024058 containerd[1936]: time="2024-10-08T19:34:44.024008972Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 8 19:34:44.024322 containerd[1936]: time="2024-10-08T19:34:44.024289712Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 8 19:34:44.025785 containerd[1936]: time="2024-10-08T19:34:44.025722308Z" level=info msg="containerd successfully booted in 0.277844s" Oct 8 19:34:44.026631 unknown[2053]: wrote ssh authorized keys file for user: core Oct 8 19:34:44.035246 systemd[1]: Started containerd.service - containerd container runtime. Oct 8 19:34:44.041589 systemd[1]: Reached target network-online.target - Network is Online. Oct 8 19:34:44.053718 systemd[1]: Started amazon-ssm-agent.service - amazon-ssm-agent. Oct 8 19:34:44.057558 systemd-hostnamed[1955]: Hostname set to (transient) Oct 8 19:34:44.059251 systemd-resolved[1728]: System hostname changed to 'ip-172-31-16-220'. Oct 8 19:34:44.065722 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Oct 8 19:34:44.086696 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 8 19:34:44.118365 (-wrapper)[2105]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Oct 8 19:34:44.188967 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 8 19:34:44.195348 amazon-ssm-agent[2104]: Initializing new seelog logger Oct 8 19:34:44.196008 amazon-ssm-agent[2104]: New Seelog Logger Creation Complete Oct 8 19:34:44.196243 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.197490 amazon-ssm-agent[2104]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.197490 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 processing appconfig overrides Oct 8 19:34:44.198329 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.198718 amazon-ssm-agent[2104]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.199023 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 processing appconfig overrides Oct 8 19:34:44.199384 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.200025 amazon-ssm-agent[2104]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.200301 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 processing appconfig overrides Oct 8 19:34:44.202705 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO Proxy environment variables: Oct 8 19:34:44.205993 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 Found config file at /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.206155 amazon-ssm-agent[2104]: Applying config override from /etc/amazon/ssm/amazon-ssm-agent.json. Oct 8 19:34:44.206399 amazon-ssm-agent[2104]: 2024/10/08 19:34:44 processing appconfig overrides Oct 8 19:34:44.211656 update-ssh-keys[2107]: Updated "/home/core/.ssh/authorized_keys" Oct 8 19:34:44.215640 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 8 19:34:44.224862 systemd[1]: Finished sshkeys.service. Oct 8 19:34:44.304384 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO https_proxy: Oct 8 19:34:44.407120 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO http_proxy: Oct 8 19:34:44.504443 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO no_proxy: Oct 8 19:34:44.565593 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 8 19:34:44.579606 (dockerd)[2132]: 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 Oct 8 19:34:44.602504 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO Checking if agent identity type OnPrem can be assumed Oct 8 19:34:44.701029 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO Checking if agent identity type EC2 can be assumed Oct 8 19:34:44.801438 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO Agent will take identity from EC2 Oct 8 19:34:44.900972 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 8 19:34:45.000468 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 8 19:34:45.103120 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] using named pipe channel for IPC Oct 8 19:34:45.202259 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] amazon-ssm-agent - v3.2.0.0 Oct 8 19:34:45.238886 dockerd[2132]: time="2024-10-08T19:34:45.238779010Z" level=info msg="Starting up" Oct 8 19:34:45.305263 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] OS: linux, Arch: arm64 Oct 8 19:34:45.405551 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] Starting Core Agent Oct 8 19:34:45.506307 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [amazon-ssm-agent] registrar detected. Attempting registration Oct 8 19:34:45.552825 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport2809510454-merged.mount: Deactivated successfully. Oct 8 19:34:45.608134 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [Registrar] Starting registrar module Oct 8 19:34:45.652589 dockerd[2132]: time="2024-10-08T19:34:45.651059052Z" level=info msg="Loading containers: start." Oct 8 19:34:45.712811 amazon-ssm-agent[2104]: 2024-10-08 19:34:44 INFO [EC2Identity] no registration info found for ec2 instance, attempting registration Oct 8 19:34:45.830871 amazon-ssm-agent[2104]: 2024-10-08 19:34:45 INFO [EC2Identity] EC2 registration was successful. Oct 8 19:34:45.867704 amazon-ssm-agent[2104]: 2024-10-08 19:34:45 INFO [CredentialRefresher] credentialRefresher has started Oct 8 19:34:45.867704 amazon-ssm-agent[2104]: 2024-10-08 19:34:45 INFO [CredentialRefresher] Starting credentials refresher loop Oct 8 19:34:45.867704 amazon-ssm-agent[2104]: 2024-10-08 19:34:45 INFO EC2RoleProvider Successfully connected with instance profile role credentials Oct 8 19:34:45.909342 kernel: Initializing XFRM netlink socket Oct 8 19:34:45.931327 amazon-ssm-agent[2104]: 2024-10-08 19:34:45 INFO [CredentialRefresher] Next credential rotation will be in 30.0249929254 minutes Oct 8 19:34:45.962421 (udev-worker)[1776]: Network interface NamePolicy= disabled on kernel command line. Oct 8 19:34:46.062617 systemd-networkd[1771]: docker0: Link UP Oct 8 19:34:46.087716 dockerd[2132]: time="2024-10-08T19:34:46.087597994Z" level=info msg="Loading containers: done." Oct 8 19:34:46.117799 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3700032456-merged.mount: Deactivated successfully. Oct 8 19:34:46.125164 dockerd[2132]: time="2024-10-08T19:34:46.125000578Z" 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 Oct 8 19:34:46.125983 dockerd[2132]: time="2024-10-08T19:34:46.125344270Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Oct 8 19:34:46.125983 dockerd[2132]: time="2024-10-08T19:34:46.125567482Z" level=info msg="Daemon has completed initialization" Oct 8 19:34:46.168351 sshd_keygen[1939]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 8 19:34:46.203310 dockerd[2132]: time="2024-10-08T19:34:46.202254250Z" level=info msg="API listen on /run/docker.sock" Oct 8 19:34:46.207948 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 8 19:34:46.212116 etcd-wrapper[2121]: Error response from daemon: No such container: etcd-member Oct 8 19:34:46.229007 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 8 19:34:46.242630 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 8 19:34:46.250415 etcd-wrapper[2266]: Error response from daemon: No such container: etcd-member Oct 8 19:34:46.256052 systemd[1]: Started sshd@0-172.31.16.220:22-139.178.68.195:33876.service - OpenSSH per-connection server daemon (139.178.68.195:33876). Oct 8 19:34:46.274908 systemd[1]: issuegen.service: Deactivated successfully. Oct 8 19:34:46.275619 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 8 19:34:46.290163 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 8 19:34:46.340002 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 8 19:34:46.344157 etcd-wrapper[2290]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Oct 8 19:34:46.354755 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 8 19:34:46.366683 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 8 19:34:46.369246 systemd[1]: Reached target getty.target - Login Prompts. Oct 8 19:34:46.467212 sshd[2274]: Accepted publickey for core from 139.178.68.195 port 33876 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:46.470498 sshd[2274]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:46.491220 systemd-logind[1913]: New session 1 of user core. Oct 8 19:34:46.493637 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 8 19:34:46.506614 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 8 19:34:46.534178 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 8 19:34:46.559940 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 8 19:34:46.579261 (systemd)[2301]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 8 19:34:46.809448 systemd[2301]: Queued start job for default target default.target. Oct 8 19:34:46.819934 systemd[2301]: Created slice app.slice - User Application Slice. Oct 8 19:34:46.820175 systemd[2301]: Reached target paths.target - Paths. Oct 8 19:34:46.820210 systemd[2301]: Reached target timers.target - Timers. Oct 8 19:34:46.822909 systemd[2301]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 8 19:34:46.854901 systemd[2301]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 8 19:34:46.855026 systemd[2301]: Reached target sockets.target - Sockets. Oct 8 19:34:46.855058 systemd[2301]: Reached target basic.target - Basic System. Oct 8 19:34:46.855176 systemd[2301]: Reached target default.target - Main User Target. Oct 8 19:34:46.855243 systemd[2301]: Startup finished in 262ms. Oct 8 19:34:46.855391 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 8 19:34:46.869658 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 8 19:34:46.900669 amazon-ssm-agent[2104]: 2024-10-08 19:34:46 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker is not running, starting worker process Oct 8 19:34:47.001156 amazon-ssm-agent[2104]: 2024-10-08 19:34:46 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] [WorkerProvider] Worker ssm-agent-worker (pid:2311) started Oct 8 19:34:47.042613 systemd[1]: Started sshd@1-172.31.16.220:22-139.178.68.195:33884.service - OpenSSH per-connection server daemon (139.178.68.195:33884). Oct 8 19:34:47.102299 amazon-ssm-agent[2104]: 2024-10-08 19:34:46 INFO [amazon-ssm-agent] [LongRunningWorkerContainer] Monitor long running worker health every 60 seconds Oct 8 19:34:47.216127 sshd[2320]: Accepted publickey for core from 139.178.68.195 port 33884 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:47.219395 sshd[2320]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:47.230232 systemd-logind[1913]: New session 2 of user core. Oct 8 19:34:47.241365 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 8 19:34:47.368760 sshd[2320]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:47.373412 systemd[1]: sshd@1-172.31.16.220:22-139.178.68.195:33884.service: Deactivated successfully. Oct 8 19:34:47.376152 systemd[1]: session-2.scope: Deactivated successfully. Oct 8 19:34:47.380250 systemd-logind[1913]: Session 2 logged out. Waiting for processes to exit. Oct 8 19:34:47.382001 systemd-logind[1913]: Removed session 2. Oct 8 19:34:47.408726 systemd[1]: Started sshd@2-172.31.16.220:22-139.178.68.195:33896.service - OpenSSH per-connection server daemon (139.178.68.195:33896). Oct 8 19:34:47.530228 etcd-wrapper[2290]: v3.5.16: Pulling from coreos/etcd Oct 8 19:34:47.591787 sshd[2329]: Accepted publickey for core from 139.178.68.195 port 33896 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:47.594788 sshd[2329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:47.603978 systemd-logind[1913]: New session 3 of user core. Oct 8 19:34:47.612514 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 8 19:34:47.744723 sshd[2329]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:47.751010 systemd[1]: sshd@2-172.31.16.220:22-139.178.68.195:33896.service: Deactivated successfully. Oct 8 19:34:47.754942 systemd[1]: session-3.scope: Deactivated successfully. Oct 8 19:34:47.758768 systemd-logind[1913]: Session 3 logged out. Waiting for processes to exit. Oct 8 19:34:47.761540 systemd-logind[1913]: Removed session 3. Oct 8 19:34:47.783431 etcd-wrapper[2290]: d10f96ed9607: Pulling fs layer Oct 8 19:34:47.783564 etcd-wrapper[2290]: 2ae710cd8bfe: Pulling fs layer Oct 8 19:34:47.784006 etcd-wrapper[2290]: d462aa345367: Pulling fs layer Oct 8 19:34:47.784769 etcd-wrapper[2290]: 0f8b424aa0b9: Pulling fs layer Oct 8 19:34:47.785284 etcd-wrapper[2290]: d557676654e5: Pulling fs layer Oct 8 19:34:47.785364 etcd-wrapper[2290]: c8022d07192e: Pulling fs layer Oct 8 19:34:47.785552 etcd-wrapper[2290]: d858cbc252ad: Pulling fs layer Oct 8 19:34:47.785613 etcd-wrapper[2290]: 1069fc2daed1: Pulling fs layer Oct 8 19:34:47.785613 etcd-wrapper[2290]: b40161cd83fc: Pulling fs layer Oct 8 19:34:47.786569 etcd-wrapper[2290]: 5318d93a3a65: Pulling fs layer Oct 8 19:34:47.786632 etcd-wrapper[2290]: 307c1adadb60: Pulling fs layer Oct 8 19:34:47.786632 etcd-wrapper[2290]: f9b3c65df792: Pulling fs layer Oct 8 19:34:47.786632 etcd-wrapper[2290]: 5709b4a82c35: Pulling fs layer Oct 8 19:34:47.786632 etcd-wrapper[2290]: d5372e382b07: Pulling fs layer Oct 8 19:34:47.786821 etcd-wrapper[2290]: 9c3b5253dddc: Pulling fs layer Oct 8 19:34:47.786821 etcd-wrapper[2290]: 7a57323f7551: Pulling fs layer Oct 8 19:34:47.786821 etcd-wrapper[2290]: 5318d93a3a65: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: 307c1adadb60: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: f9b3c65df792: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: 5709b4a82c35: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: d5372e382b07: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: 9c3b5253dddc: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: 7a57323f7551: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: c8022d07192e: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: d858cbc252ad: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: 1069fc2daed1: Waiting Oct 8 19:34:47.786821 etcd-wrapper[2290]: b40161cd83fc: Waiting Oct 8 19:34:47.787388 etcd-wrapper[2290]: 0f8b424aa0b9: Waiting Oct 8 19:34:47.787388 etcd-wrapper[2290]: d557676654e5: Waiting Oct 8 19:34:48.046875 etcd-wrapper[2290]: 2ae710cd8bfe: Download complete Oct 8 19:34:48.063280 etcd-wrapper[2290]: d10f96ed9607: Verifying Checksum Oct 8 19:34:48.063280 etcd-wrapper[2290]: d10f96ed9607: Download complete Oct 8 19:34:48.107891 etcd-wrapper[2290]: d462aa345367: Verifying Checksum Oct 8 19:34:48.108662 etcd-wrapper[2290]: d462aa345367: Download complete Oct 8 19:34:48.123784 etcd-wrapper[2290]: d10f96ed9607: Pull complete Oct 8 19:34:48.155749 systemd[1]: var-lib-docker-overlay2-a87b3e605ea4537e99a2906e5f852c6ae13305af544e8c85ed4dd7bc51e0fbc9-merged.mount: Deactivated successfully. Oct 8 19:34:48.215800 etcd-wrapper[2290]: 2ae710cd8bfe: Pull complete Oct 8 19:34:48.337354 etcd-wrapper[2290]: 0f8b424aa0b9: Verifying Checksum Oct 8 19:34:48.337354 etcd-wrapper[2290]: 0f8b424aa0b9: Download complete Oct 8 19:34:48.351252 etcd-wrapper[2290]: d557676654e5: Verifying Checksum Oct 8 19:34:48.351625 etcd-wrapper[2290]: d557676654e5: Download complete Oct 8 19:34:48.391953 etcd-wrapper[2290]: c8022d07192e: Verifying Checksum Oct 8 19:34:48.391953 etcd-wrapper[2290]: c8022d07192e: Download complete Oct 8 19:34:48.613165 etcd-wrapper[2290]: 1069fc2daed1: Verifying Checksum Oct 8 19:34:48.614300 etcd-wrapper[2290]: 1069fc2daed1: Download complete Oct 8 19:34:48.652837 etcd-wrapper[2290]: d858cbc252ad: Verifying Checksum Oct 8 19:34:48.656525 etcd-wrapper[2290]: d858cbc252ad: Download complete Oct 8 19:34:48.661278 etcd-wrapper[2290]: b40161cd83fc: Verifying Checksum Oct 8 19:34:48.661278 etcd-wrapper[2290]: b40161cd83fc: Download complete Oct 8 19:34:48.928409 etcd-wrapper[2290]: 307c1adadb60: Verifying Checksum Oct 8 19:34:48.928409 etcd-wrapper[2290]: 307c1adadb60: Download complete Oct 8 19:34:48.930253 etcd-wrapper[2290]: 5318d93a3a65: Verifying Checksum Oct 8 19:34:48.930541 etcd-wrapper[2290]: 5318d93a3a65: Download complete Oct 8 19:34:48.972672 systemd[1]: var-lib-docker-overlay2-9c612b66097afaeb8187a08b7f78c137814c3a082e7597c914f5e5c35b2450b2-merged.mount: Deactivated successfully. Oct 8 19:34:49.031249 ntpd[1909]: Listen normally on 6 eth0 [fe80::470:27ff:feeb:e64b%2]:123 Oct 8 19:34:49.032358 ntpd[1909]: 8 Oct 19:34:49 ntpd[1909]: Listen normally on 6 eth0 [fe80::470:27ff:feeb:e64b%2]:123 Oct 8 19:34:49.092391 etcd-wrapper[2290]: f9b3c65df792: Verifying Checksum Oct 8 19:34:49.092391 etcd-wrapper[2290]: f9b3c65df792: Download complete Oct 8 19:34:49.291189 etcd-wrapper[2290]: d462aa345367: Pull complete Oct 8 19:34:49.341160 etcd-wrapper[2290]: d5372e382b07: Verifying Checksum Oct 8 19:34:49.341160 etcd-wrapper[2290]: d5372e382b07: Download complete Oct 8 19:34:49.348534 etcd-wrapper[2290]: 5709b4a82c35: Download complete Oct 8 19:34:49.356484 systemd[1]: var-lib-docker-overlay2-218f251c4b4c8e3c625a5b4f3bd53130eee3ed4f7d10dd29d3afec0618d66efd-merged.mount: Deactivated successfully. Oct 8 19:34:49.371916 etcd-wrapper[2290]: 9c3b5253dddc: Verifying Checksum Oct 8 19:34:49.371916 etcd-wrapper[2290]: 9c3b5253dddc: Download complete Oct 8 19:34:49.529739 etcd-wrapper[2290]: 0f8b424aa0b9: Pull complete Oct 8 19:34:49.555464 systemd[1]: var-lib-docker-overlay2-a6e33752696c88ed5dd63e7058d9e5c3941d6410dc6177f07913773748d6d797-merged.mount: Deactivated successfully. Oct 8 19:34:49.611641 etcd-wrapper[2290]: d557676654e5: Pull complete Oct 8 19:34:49.638328 etcd-wrapper[2290]: 7a57323f7551: Verifying Checksum Oct 8 19:34:49.638963 etcd-wrapper[2290]: 7a57323f7551: Download complete Oct 8 19:34:49.649245 etcd-wrapper[2290]: c8022d07192e: Pull complete Oct 8 19:34:49.687035 etcd-wrapper[2290]: d858cbc252ad: Pull complete Oct 8 19:34:49.735955 etcd-wrapper[2290]: 1069fc2daed1: Pull complete Oct 8 19:34:49.805396 etcd-wrapper[2290]: b40161cd83fc: Pull complete Oct 8 19:34:49.842418 etcd-wrapper[2290]: 5318d93a3a65: Pull complete Oct 8 19:34:49.920610 etcd-wrapper[2290]: 307c1adadb60: Pull complete Oct 8 19:34:50.211566 systemd-resolved[1728]: Clock change detected. Flushing caches. Oct 8 19:34:50.527961 systemd[1]: var-lib-docker-overlay2-7cd697d4c7965f25fd28fd5820402517bf687c9353261d15609973d0ac205d30-merged.mount: Deactivated successfully. Oct 8 19:34:50.879789 etcd-wrapper[2290]: f9b3c65df792: Pull complete Oct 8 19:34:51.104250 systemd[1]: var-lib-docker-overlay2-dc669e45b9d0668cb518e51ec2998c02a8fa3d400e1ad1eb0993bf1fad04e997-merged.mount: Deactivated successfully. Oct 8 19:34:51.262829 etcd-wrapper[2290]: 5709b4a82c35: Pull complete Oct 8 19:34:51.461259 systemd[1]: var-lib-docker-overlay2-45f88b67fa20b02af3789ff7759a6fda306e2b9d2a2be8c96961881671654d81-merged.mount: Deactivated successfully. Oct 8 19:34:51.622588 etcd-wrapper[2290]: d5372e382b07: Pull complete Oct 8 19:34:51.650332 systemd[1]: var-lib-docker-overlay2-955678ccec6050a567ddbac36dc9deadc993f2034b50170c5e22cf9ea2982eed-merged.mount: Deactivated successfully. Oct 8 19:34:51.697741 etcd-wrapper[2290]: 9c3b5253dddc: Pull complete Oct 8 19:34:51.724674 systemd[1]: var-lib-docker-overlay2-76d6834ee1808b028b140c73e54e9cac54df60d121b2e25d81a1cbc438f062d3-merged.mount: Deactivated successfully. Oct 8 19:34:51.777870 etcd-wrapper[2290]: 7a57323f7551: Pull complete Oct 8 19:34:51.792655 etcd-wrapper[2290]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Oct 8 19:34:51.797457 etcd-wrapper[2290]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Oct 8 19:34:52.189376 containerd[1936]: time="2024-10-08T19:34:52.189099625Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 8 19:34:52.189376 containerd[1936]: time="2024-10-08T19:34:52.189255121Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 8 19:34:52.190877 containerd[1936]: time="2024-10-08T19:34:52.189337345Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 8 19:34:52.190877 containerd[1936]: time="2024-10-08T19:34:52.189994453Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 8 19:34:52.224622 systemd[1]: Started docker-80d9c5a6fa19f076c5afedb03da26af7559c45f449ad89d61fa1f03047f1e626.scope - libcontainer container 80d9c5a6fa19f076c5afedb03da26af7559c45f449ad89d61fa1f03047f1e626. Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.316208Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.316482Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec25796b706f75f60b05e6187bcaa1a8"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316537Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316557Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316570Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316583Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316689Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.316719Z","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.16.220:2379"]} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.316862Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.316886Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Oct 8 19:34:52.319066 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.317680Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Oct 8 19:34:52.320237 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.317908Z","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":"ec25796b706f75f60b05e6187bcaa1a8","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.16.220:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec25796b706f75f60b05e6187bcaa1a8=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"} Oct 8 19:34:52.380042 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.379641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"60.300672ms"} Oct 8 19:34:52.387981 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.387627Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Oct 8 19:34:52.388356 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.388082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Oct 8 19:34:52.388775 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.388338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Oct 8 19:34:52.388775 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.388435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Oct 8 19:34:52.388775 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.388490Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Oct 8 19:34:52.389231 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.388827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 8 19:34:52.467163 etcd-wrapper[2290]: {"level":"warn","ts":"2024-10-08T19:34:52.466642Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Oct 8 19:34:52.469813 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.469492Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Oct 8 19:34:52.472103 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.471810Z","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"} Oct 8 19:34:52.475791 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.474131Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Oct 8 19:34:52.475791 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.474511Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Oct 8 19:34:52.477469 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.476726Z","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"} Oct 8 19:34:52.477469 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.477211Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Oct 8 19:34:52.477959 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.477694Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Oct 8 19:34:52.480357 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.478930Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Oct 8 19:34:52.481407 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.478873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Oct 8 19:34:52.481407 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.479611Z","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"]} Oct 8 19:34:52.481673 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.481110Z","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.16.220:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Oct 8 19:34:52.482199 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.481863Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Oct 8 19:34:52.482199 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.481958Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Oct 8 19:34:52.489849 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.489535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Oct 8 19:34:52.489849 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.489596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Oct 8 19:34:52.490606 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.489786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Oct 8 19:34:52.490606 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.489877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Oct 8 19:34:52.490606 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.490065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Oct 8 19:34:52.490606 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.490121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Oct 8 19:34:52.490606 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.490141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Oct 8 19:34:52.491960 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.491652Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Oct 8 19:34:52.493343 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.492883Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec25796b706f75f60b05e6187bcaa1a8 ClientURLs:[http://172.31.16.220:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Oct 8 19:34:52.493343 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.492941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Oct 8 19:34:52.494945 systemd[1]: Started etcd-member.service - etcd (System Application Container). Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.493763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.493864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.493923Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.494054Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.494264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Oct 8 19:34:52.496076 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.494554Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Oct 8 19:34:52.495582 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 8 19:34:52.496715 systemd[1]: Startup finished in 1.232s (kernel) + 7.862s (initrd) + 14.702s (userspace) = 23.796s. Oct 8 19:34:52.497414 etcd-wrapper[2290]: {"level":"info","ts":"2024-10-08T19:34:52.496877Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Oct 8 19:34:52.640688 systemd[1]: var-lib-docker-overlay2-479aa2a5dd5412517d545cfd679a98322d7fbb41c6540e33c77559cae9554ab9\x2dinit-merged.mount: Deactivated successfully. Oct 8 19:34:57.969831 systemd[1]: Started sshd@3-172.31.16.220:22-139.178.68.195:60892.service - OpenSSH per-connection server daemon (139.178.68.195:60892). Oct 8 19:34:58.146444 sshd[2472]: Accepted publickey for core from 139.178.68.195 port 60892 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:58.149553 sshd[2472]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:58.158592 systemd-logind[1913]: New session 4 of user core. Oct 8 19:34:58.165563 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 8 19:34:58.292888 sshd[2472]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:58.298961 systemd[1]: sshd@3-172.31.16.220:22-139.178.68.195:60892.service: Deactivated successfully. Oct 8 19:34:58.302239 systemd[1]: session-4.scope: Deactivated successfully. Oct 8 19:34:58.305695 systemd-logind[1913]: Session 4 logged out. Waiting for processes to exit. Oct 8 19:34:58.308601 systemd-logind[1913]: Removed session 4. Oct 8 19:34:58.330832 systemd[1]: Started sshd@4-172.31.16.220:22-139.178.68.195:60906.service - OpenSSH per-connection server daemon (139.178.68.195:60906). Oct 8 19:34:58.496694 sshd[2479]: Accepted publickey for core from 139.178.68.195 port 60906 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:58.499448 sshd[2479]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:58.508049 systemd-logind[1913]: New session 5 of user core. Oct 8 19:34:58.515578 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 8 19:34:58.631777 sshd[2479]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:58.637209 systemd-logind[1913]: Session 5 logged out. Waiting for processes to exit. Oct 8 19:34:58.637985 systemd[1]: sshd@4-172.31.16.220:22-139.178.68.195:60906.service: Deactivated successfully. Oct 8 19:34:58.641539 systemd[1]: session-5.scope: Deactivated successfully. Oct 8 19:34:58.645280 systemd-logind[1913]: Removed session 5. Oct 8 19:34:58.673804 systemd[1]: Started sshd@5-172.31.16.220:22-139.178.68.195:60922.service - OpenSSH per-connection server daemon (139.178.68.195:60922). Oct 8 19:34:58.847557 sshd[2486]: Accepted publickey for core from 139.178.68.195 port 60922 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:58.850118 sshd[2486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:58.857680 systemd-logind[1913]: New session 6 of user core. Oct 8 19:34:58.866541 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 8 19:34:58.990663 sshd[2486]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:58.997364 systemd-logind[1913]: Session 6 logged out. Waiting for processes to exit. Oct 8 19:34:58.997691 systemd[1]: sshd@5-172.31.16.220:22-139.178.68.195:60922.service: Deactivated successfully. Oct 8 19:34:59.002324 systemd[1]: session-6.scope: Deactivated successfully. Oct 8 19:34:59.004087 systemd-logind[1913]: Removed session 6. Oct 8 19:34:59.035763 systemd[1]: Started sshd@6-172.31.16.220:22-139.178.68.195:60930.service - OpenSSH per-connection server daemon (139.178.68.195:60930). Oct 8 19:34:59.204073 sshd[2493]: Accepted publickey for core from 139.178.68.195 port 60930 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:59.206724 sshd[2493]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:59.214115 systemd-logind[1913]: New session 7 of user core. Oct 8 19:34:59.224574 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 8 19:34:59.345189 sudo[2496]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 8 19:34:59.346873 sudo[2496]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 8 19:34:59.365911 sudo[2496]: pam_unix(sudo:session): session closed for user root Oct 8 19:34:59.390265 sshd[2493]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:59.397020 systemd[1]: sshd@6-172.31.16.220:22-139.178.68.195:60930.service: Deactivated successfully. Oct 8 19:34:59.401837 systemd[1]: session-7.scope: Deactivated successfully. Oct 8 19:34:59.405896 systemd-logind[1913]: Session 7 logged out. Waiting for processes to exit. Oct 8 19:34:59.408269 systemd-logind[1913]: Removed session 7. Oct 8 19:34:59.431818 systemd[1]: Started sshd@7-172.31.16.220:22-139.178.68.195:60944.service - OpenSSH per-connection server daemon (139.178.68.195:60944). Oct 8 19:34:59.604315 sshd[2501]: Accepted publickey for core from 139.178.68.195 port 60944 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:34:59.607701 sshd[2501]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:34:59.616745 systemd-logind[1913]: New session 8 of user core. Oct 8 19:34:59.627594 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 8 19:34:59.734909 sudo[2505]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 8 19:34:59.736452 sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 8 19:34:59.744214 sudo[2505]: pam_unix(sudo:session): session closed for user root Oct 8 19:34:59.755839 sudo[2504]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Oct 8 19:34:59.757226 sudo[2504]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 8 19:34:59.781206 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Oct 8 19:34:59.798185 auditctl[2508]: No rules Oct 8 19:34:59.799116 systemd[1]: audit-rules.service: Deactivated successfully. Oct 8 19:34:59.799595 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Oct 8 19:34:59.808143 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 8 19:34:59.859196 augenrules[2526]: No rules Oct 8 19:34:59.861738 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 8 19:34:59.865568 sudo[2504]: pam_unix(sudo:session): session closed for user root Oct 8 19:34:59.889063 sshd[2501]: pam_unix(sshd:session): session closed for user core Oct 8 19:34:59.895029 systemd-logind[1913]: Session 8 logged out. Waiting for processes to exit. Oct 8 19:34:59.895769 systemd[1]: sshd@7-172.31.16.220:22-139.178.68.195:60944.service: Deactivated successfully. Oct 8 19:34:59.899240 systemd[1]: session-8.scope: Deactivated successfully. Oct 8 19:34:59.903005 systemd-logind[1913]: Removed session 8. Oct 8 19:34:59.929881 systemd[1]: Started sshd@8-172.31.16.220:22-139.178.68.195:60950.service - OpenSSH per-connection server daemon (139.178.68.195:60950). Oct 8 19:35:00.095351 sshd[2534]: Accepted publickey for core from 139.178.68.195 port 60950 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:35:00.098660 sshd[2534]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:35:00.107185 systemd-logind[1913]: New session 9 of user core. Oct 8 19:35:00.118588 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 8 19:35:00.239506 sshd[2534]: pam_unix(sshd:session): session closed for user core Oct 8 19:35:00.248185 systemd[1]: sshd@8-172.31.16.220:22-139.178.68.195:60950.service: Deactivated successfully. Oct 8 19:35:00.253979 systemd[1]: session-9.scope: Deactivated successfully. Oct 8 19:35:00.256914 systemd-logind[1913]: Session 9 logged out. Waiting for processes to exit. Oct 8 19:35:00.259802 systemd-logind[1913]: Removed session 9. Oct 8 19:35:00.281811 systemd[1]: Started sshd@9-172.31.16.220:22-139.178.68.195:60956.service - OpenSSH per-connection server daemon (139.178.68.195:60956). Oct 8 19:35:00.466265 sshd[2543]: Accepted publickey for core from 139.178.68.195 port 60956 ssh2: RSA SHA256:Mk9S5TnwRn/Nvp9hJQCsIZR4kjDFrRbnnuGA/cRmM/Q Oct 8 19:35:00.469099 sshd[2543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 8 19:35:00.479588 systemd-logind[1913]: New session 10 of user core. Oct 8 19:35:00.489699 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 8 19:35:00.760399 sshd[2543]: pam_unix(sshd:session): session closed for user core Oct 8 19:35:00.767431 systemd[1]: sshd@9-172.31.16.220:22-139.178.68.195:60956.service: Deactivated successfully. Oct 8 19:35:00.770957 systemd[1]: session-10.scope: Deactivated successfully. Oct 8 19:35:00.773220 systemd-logind[1913]: Session 10 logged out. Waiting for processes to exit. Oct 8 19:35:00.775143 systemd-logind[1913]: Removed session 10. Oct 8 19:35:14.273061 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 8 19:35:28.711353 update_engine[1914]: I20241008 19:35:28.710671 1914 update_attempter.cc:509] Updating boot flags... Oct 8 19:35:28.786530 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (2572) Oct 8 19:35:29.049365 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (2571) Oct 8 19:35:29.272344 kernel: BTRFS warning: duplicate device /dev/nvme0n1p3 devid 1 generation 40 scanned by (udev-worker) (2571)